Results for igt@kms_flip@plain-flip-ts-check@a-edp1

Machine description: shard-mtlp-7

Result: Fail

git-log-oneline i915_display_info23 igt_runner23 runtimes23 results23.json results23-i915-load.json i915_display_info_post_exec23 boot23 dmesg23

DetailValue
Duration 12.18 seconds
Hostname 
shard-mtlp-7
Igt-Version 
IGT-Version: 2.0-g0498a2e21 (x86_64) (Linux: 6.14.0-rc6-CI_DRM_16293-ga958e31a81b3+ x86_64)
Out 
Using IGT_SRANDOM=1742296600 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-eDP1
  2880x1800: 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa 
Expected frametime: 11111us; measured 11198.8us +- 2.380us accuracy 0.06%
vblank interval differs from modeline! expected 11111.1us, measured 11199us +- 2.380us, difference 87.6us (36.8 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:777 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 A-eDP1: FAIL (12.176s)
Err 
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-eDP1
(kms_flip:2469) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660:
(kms_flip:2469) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.273224
last_received_ts = 229.272934
last_seq = 10228
current_ts = 229.284378
current_received_ts = 229.284058
current_seq = 10229
count = 551
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.273224/10228, current 229.284378/10229: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.284378
last_received_ts = 229.284058
last_seq = 10229
current_ts = 229.295532
current_received_ts = 229.295227
current_seq = 10230
count = 552
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.284378/10229, current 229.295532/10230: elapsed=11154.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.295532
last_received_ts = 229.295227
last_seq = 10230
current_ts = 229.306686
current_received_ts = 229.306396
current_seq = 10231
count = 553
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.295532/10230, current 229.306686/10231: elapsed=11156.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.306686
last_received_ts = 229.306396
last_seq = 10231
current_ts = 229.317841
current_received_ts = 229.317520
current_seq = 10232
count = 554
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.306686/10231, current 229.317841/10232: elapsed=11155.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.317841
last_received_ts = 229.317520
last_seq = 10232
current_ts = 229.328995
current_received_ts = 229.328705
current_seq = 10233
count = 555
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.317841/10232, current 229.328995/10233: elapsed=11154.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.328995
last_received_ts = 229.328705
last_seq = 10233
current_ts = 229.340149
current_received_ts = 229.339844
current_seq = 10234
count = 556
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.328995/10233, current 229.340149/10234: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.340149
last_received_ts = 229.339844
last_seq = 10234
current_ts = 229.351303
current_received_ts = 229.351028
current_seq = 10235
count = 557
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.340149/10234, current 229.351303/10235: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.351303
last_received_ts = 229.351028
last_seq = 10235
current_ts = 229.362457
current_received_ts = 229.362137
current_seq = 10236
count = 558
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.351303/10235, current 229.362457/10236: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.362457
last_received_ts = 229.362137
last_seq = 10236
current_ts = 229.373611
current_received_ts = 229.373306
current_seq = 10237
count = 559
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.362457/10236, current 229.373611/10237: elapsed=11154.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.373611
last_received_ts = 229.373306
last_seq = 10237
current_ts = 229.384766
current_received_ts = 229.384445
current_seq = 10238
count = 560
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.373611/10237, current 229.384766/10238: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.384766
last_received_ts = 229.384445
last_seq = 10238
current_ts = 229.395920
current_received_ts = 229.395645
current_seq = 10239
count = 561
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.384766/10238, current 229.395920/10239: elapsed=11154.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.395920
last_received_ts = 229.395645
last_seq = 10239
current_ts = 229.407074
current_received_ts = 229.406784
current_seq = 10240
count = 562
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.395920/10239, current 229.407074/10240: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.407074
last_received_ts = 229.406784
last_seq = 10240
current_ts = 229.418228
current_received_ts = 229.417938
current_seq = 10241
count = 563
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.407074/10240, current 229.418228/10241: elapsed=11157.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.418228
last_received_ts = 229.417938
last_seq = 10241
current_ts = 229.429382
current_received_ts = 229.429092
current_seq = 10242
count = 564
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.418228/10241, current 229.429382/10242: elapsed=11155.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.429382
last_received_ts = 229.429092
last_seq = 10242
current_ts = 229.440536
current_received_ts = 229.440231
current_seq = 10243
count = 565
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.429382/10242, current 229.440536/10243: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.440536
last_received_ts = 229.440231
last_seq = 10243
current_ts = 229.451675
current_received_ts = 229.451370
current_seq = 10244
count = 566
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.440536/10243, current 229.451675/10244: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.451675
last_received_ts = 229.451370
last_seq = 10244
current_ts = 229.462845
current_received_ts = 229.462616
current_seq = 10245
count = 567
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.451675/10244, current 229.462845/10245: elapsed=11159.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.462845
last_received_ts = 229.462616
last_seq = 10245
current_ts = 229.473984
current_received_ts = 229.473663
current_seq = 10246
count = 568
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.462845/10245, current 229.473984/10246: elapsed=11148.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.473984
last_received_ts = 229.473663
last_seq = 10246
current_ts = 229.485138
current_received_ts = 229.484833
current_seq = 10247
count = 569
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.473984/10246, current 229.485138/10247: elapsed=11156.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.485138
last_received_ts = 229.484833
last_seq = 10247
current_ts = 229.496292
current_received_ts = 229.496063
current_seq = 10248
count = 570
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.485138/10247, current 229.496292/10248: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.496292
last_received_ts = 229.496063
last_seq = 10248
current_ts = 229.507446
current_received_ts = 229.507126
current_seq = 10249
count = 571
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.496292/10248, current 229.507446/10249: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.507446
last_received_ts = 229.507126
last_seq = 10249
current_ts = 229.518600
current_received_ts = 229.518295
current_seq = 10250
count = 572
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.507446/10249, current 229.518600/10250: elapsed=11158.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.518600
last_received_ts = 229.518295
last_seq = 10250
current_ts = 229.529755
current_received_ts = 229.529495
current_seq = 10251
count = 573
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.518600/10250, current 229.529755/10251: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.529755
last_received_ts = 229.529495
last_seq = 10251
current_ts = 229.540909
current_received_ts = 229.540649
current_seq = 10252
count = 574
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.529755/10251, current 229.540909/10252: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.540909
last_received_ts = 229.540649
last_seq = 10252
current_ts = 229.552063
current_received_ts = 229.551788
current_seq = 10253
count = 575
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.540909/10252, current 229.552063/10253: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.552063
last_received_ts = 229.551788
last_seq = 10253
current_ts = 229.563217
current_received_ts = 229.562912
current_seq = 10254
count = 576
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.552063/10253, current 229.563217/10254: elapsed=11156.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.563217
last_received_ts = 229.562912
last_seq = 10254
current_ts = 229.574371
current_received_ts = 229.574036
current_seq = 10255
count = 577
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.563217/10254, current 229.574371/10255: elapsed=11154.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.574371
last_received_ts = 229.574036
last_seq = 10255
current_ts = 229.585526
current_received_ts = 229.585236
current_seq = 10256
count = 578
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.574371/10255, current 229.585526/10256: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.585526
last_received_ts = 229.585236
last_seq = 10256
current_ts = 229.596680
current_received_ts = 229.596359
current_seq = 10257
count = 579
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.585526/10256, current 229.596680/10257: elapsed=11154.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.596680
last_received_ts = 229.596359
last_seq = 10257
current_ts = 229.607834
current_received_ts = 229.607529
current_seq = 10258
count = 580
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.596680/10257, current 229.607834/10258: elapsed=11154.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.607834
last_received_ts = 229.607529
last_seq = 10258
current_ts = 229.618988
current_received_ts = 229.618713
current_seq = 10259
count = 581
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.607834/10258, current 229.618988/10259: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.618988
last_received_ts = 229.618713
last_seq = 10259
current_ts = 229.630142
current_received_ts = 229.629852
current_seq = 10260
count = 582
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.618988/10259, current 229.630142/10260: elapsed=11155.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.630142
last_received_ts = 229.629852
last_seq = 10260
current_ts = 229.641296
current_received_ts = 229.640976
current_seq = 10261
count = 583
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.630142/10260, current 229.641296/10261: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.641296
last_received_ts = 229.640976
last_seq = 10261
current_ts = 229.652451
current_received_ts = 229.652161
current_seq = 10262
count = 584
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.641296/10261, current 229.652451/10262: elapsed=11157.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.652451
last_received_ts = 229.652161
last_seq = 10262
current_ts = 229.663605
current_received_ts = 229.663269
current_seq = 10263
count = 585
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.652451/10262, current 229.663605/10263: elapsed=11155.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.663605
last_received_ts = 229.663269
last_seq = 10263
current_ts = 229.674744
current_received_ts = 229.674438
current_seq = 10264
count = 586
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.663605/10263, current 229.674744/10264: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.674744
last_received_ts = 229.674438
last_seq = 10264
current_ts = 229.685898
current_received_ts = 229.685638
current_seq = 10265
count = 587
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.674744/10264, current 229.685898/10265: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.685898
last_received_ts = 229.685638
last_seq = 10265
current_ts = 229.697067
current_received_ts = 229.696777
current_seq = 10266
count = 588
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.685898/10265, current 229.697067/10266: elapsed=11157.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.697067
last_received_ts = 229.696777
last_seq = 10266
current_ts = 229.708206
current_received_ts = 229.707916
current_seq = 10267
count = 589
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.697067/10266, current 229.708206/10267: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.708206
last_received_ts = 229.707916
last_seq = 10267
current_ts = 229.719360
current_received_ts = 229.719070
current_seq = 10268
count = 590
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.708206/10267, current 229.719360/10268: elapsed=11156.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.719360
last_received_ts = 229.719070
last_seq = 10268
current_ts = 229.730515
current_received_ts = 229.730225
current_seq = 10269
count = 591
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.719360/10268, current 229.730515/10269: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.730515
last_received_ts = 229.730225
last_seq = 10269
current_ts = 229.741669
current_received_ts = 229.741348
current_seq = 10270
count = 592
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.730515/10269, current 229.741669/10270: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.741669
last_received_ts = 229.741348
last_seq = 10270
current_ts = 229.752823
current_received_ts = 229.752563
current_seq = 10271
count = 593
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.741669/10270, current 229.752823/10271: elapsed=11157.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.752823
last_received_ts = 229.752563
last_seq = 10271
current_ts = 229.763977
current_received_ts = 229.763657
current_seq = 10272
count = 594
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.752823/10271, current 229.763977/10272: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.763977
last_received_ts = 229.763657
last_seq = 10272
current_ts = 229.775131
current_received_ts = 229.774841
current_seq = 10273
count = 595
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.763977/10272, current 229.775131/10273: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.775131
last_received_ts = 229.774841
last_seq = 10273
current_ts = 229.786285
current_received_ts = 229.785995
current_seq = 10274
count = 596
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.775131/10273, current 229.786285/10274: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.786285
last_received_ts = 229.785995
last_seq = 10274
current_ts = 229.797440
current_received_ts = 229.797119
current_seq = 10275
count = 597
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.786285/10274, current 229.797440/10275: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.797440
last_received_ts = 229.797119
last_seq = 10275
current_ts = 229.808594
current_received_ts = 229.808289
current_seq = 10276
count = 598
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.797440/10275, current 229.808594/10276: elapsed=11156.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.808594
last_received_ts = 229.808289
last_seq = 10276
current_ts = 229.819748
current_received_ts = 229.819443
current_seq = 10277
count = 599
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.808594/10276, current 229.819748/10277: elapsed=11155.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.819748
last_received_ts = 229.819443
last_seq = 10277
current_ts = 229.830902
current_received_ts = 229.830597
current_seq = 10278
count = 600
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.819748/10277, current 229.830902/10278: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.830902
last_received_ts = 229.830597
last_seq = 10278
current_ts = 229.842056
current_received_ts = 229.841766
current_seq = 10279
count = 601
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.830902/10278, current 229.842056/10279: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.842056
last_received_ts = 229.841766
last_seq = 10279
current_ts = 229.853210
current_received_ts = 229.852936
current_seq = 10280
count = 602
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.842056/10279, current 229.853210/10280: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.853210
last_received_ts = 229.852936
last_seq = 10280
current_ts = 229.864365
current_received_ts = 229.864059
current_seq = 10281
count = 603
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.853210/10280, current 229.864365/10281: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.864365
last_received_ts = 229.864059
last_seq = 10281
current_ts = 229.875504
current_received_ts = 229.875214
current_seq = 10282
count = 604
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.864365/10281, current 229.875504/10282: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.875504
last_received_ts = 229.875214
last_seq = 10282
current_ts = 229.886658
current_received_ts = 229.886353
current_seq = 10283
count = 605
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.875504/10282, current 229.886658/10283: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.886658
last_received_ts = 229.886353
last_seq = 10283
current_ts = 229.897812
current_received_ts = 229.897491
current_seq = 10284
count = 606
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.886658/10283, current 229.897812/10284: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.897812
last_received_ts = 229.897491
last_seq = 10284
current_ts = 229.908951
current_received_ts = 229.908676
current_seq = 10285
count = 607
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.897812/10284, current 229.908951/10285: elapsed=11146.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.908951
last_received_ts = 229.908676
last_seq = 10285
current_ts = 229.920105
current_received_ts = 229.919800
current_seq = 10286
count = 608
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.908951/10285, current 229.920105/10286: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.920105
last_received_ts = 229.919800
last_seq = 10286
current_ts = 229.931244
current_received_ts = 229.930954
current_seq = 10287
count = 609
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.920105/10286, current 229.931244/10287: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.931244
last_received_ts = 229.930954
last_seq = 10287
current_ts = 229.942398
current_received_ts = 229.942093
current_seq = 10288
count = 610
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.931244/10287, current 229.942398/10288: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.942398
last_received_ts = 229.942093
last_seq = 10288
current_ts = 229.953552
current_received_ts = 229.953232
current_seq = 10289
count = 611
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.942398/10288, current 229.953552/10289: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.953552
last_received_ts = 229.953232
last_seq = 10289
current_ts = 229.964691
current_received_ts = 229.964417
current_seq = 10290
count = 612
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.953552/10289, current 229.964691/10290: elapsed=11145.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.964691
last_received_ts = 229.964417
last_seq = 10290
current_ts = 229.975845
current_received_ts = 229.975540
current_seq = 10291
count = 613
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.964691/10290, current 229.975845/10291: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.975845
last_received_ts = 229.975540
last_seq = 10291
current_ts = 229.987000
current_received_ts = 229.986694
current_seq = 10292
count = 614
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.975845/10291, current 229.987000/10292: elapsed=11145.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.987000
last_received_ts = 229.986694
last_seq = 10292
current_ts = 229.998138
current_received_ts = 229.997833
current_seq = 10293
count = 615
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.987000/10292, current 229.998138/10293: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 229.998138
last_received_ts = 229.997833
last_seq = 10293
current_ts = 230.009293
current_received_ts = 230.009033
current_seq = 10294
count = 616
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 229.998138/10293, current 230.009293/10294: elapsed=11148.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.009293
last_received_ts = 230.009033
last_seq = 10294
current_ts = 230.020447
current_received_ts = 230.020111
current_seq = 10295
count = 617
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.009293/10294, current 230.020447/10295: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.020447
last_received_ts = 230.020111
last_seq = 10295
current_ts = 230.031586
current_received_ts = 230.031296
current_seq = 10296
count = 618
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.020447/10295, current 230.031586/10296: elapsed=11145.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.031586
last_received_ts = 230.031296
last_seq = 10296
current_ts = 230.042740
current_received_ts = 230.042404
current_seq = 10297
count = 619
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.031586/10296, current 230.042740/10297: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.042740
last_received_ts = 230.042404
last_seq = 10297
current_ts = 230.053879
current_received_ts = 230.053574
current_seq = 10298
count = 620
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.042740/10297, current 230.053879/10298: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.053879
last_received_ts = 230.053574
last_seq = 10298
current_ts = 230.065033
current_received_ts = 230.064743
current_seq = 10299
count = 621
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.053879/10298, current 230.065033/10299: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.065033
last_received_ts = 230.064743
last_seq = 10299
current_ts = 230.076187
current_received_ts = 230.075897
current_seq = 10300
count = 622
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.065033/10299, current 230.076187/10300: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.076187
last_received_ts = 230.075897
last_seq = 10300
current_ts = 230.087341
current_received_ts = 230.087051
current_seq = 10301
count = 623
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.076187/10300, current 230.087341/10301: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.087341
last_received_ts = 230.087051
last_seq = 10301
current_ts = 230.098480
current_received_ts = 230.098175
current_seq = 10302
count = 624
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.087341/10301, current 230.098480/10302: elapsed=11144.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.098480
last_received_ts = 230.098175
last_seq = 10302
current_ts = 230.109634
current_received_ts = 230.109329
current_seq = 10303
count = 625
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.098480/10302, current 230.109634/10303: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.109634
last_received_ts = 230.109329
last_seq = 10303
current_ts = 230.120789
current_received_ts = 230.120514
current_seq = 10304
count = 626
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.109634/10303, current 230.120789/10304: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.120789
last_received_ts = 230.120514
last_seq = 10304
current_ts = 230.131927
current_received_ts = 230.131683
current_seq = 10305
count = 627
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.120789/10304, current 230.131927/10305: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.131927
last_received_ts = 230.131683
last_seq = 10305
current_ts = 230.143082
current_received_ts = 230.142776
current_seq = 10306
count = 628
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.131927/10305, current 230.143082/10306: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.143082
last_received_ts = 230.142776
last_seq = 10306
current_ts = 230.154236
current_received_ts = 230.153915
current_seq = 10307
count = 629
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.143082/10306, current 230.154236/10307: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.154236
last_received_ts = 230.153915
last_seq = 10307
current_ts = 230.165375
current_received_ts = 230.165100
current_seq = 10308
count = 630
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.154236/10307, current 230.165375/10308: elapsed=11146.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.165375
last_received_ts = 230.165100
last_seq = 10308
current_ts = 230.176529
current_received_ts = 230.176224
current_seq = 10309
count = 631
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.165375/10308, current 230.176529/10309: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.176529
last_received_ts = 230.176224
last_seq = 10309
current_ts = 230.187683
current_received_ts = 230.187363
current_seq = 10310
count = 632
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.176529/10309, current 230.187683/10310: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.187683
last_received_ts = 230.187363
last_seq = 10310
current_ts = 230.198822
current_received_ts = 230.198547
current_seq = 10311
count = 633
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.187683/10310, current 230.198822/10311: elapsed=11146.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.198822
last_received_ts = 230.198547
last_seq = 10311
current_ts = 230.209976
current_received_ts = 230.209671
current_seq = 10312
count = 634
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.198822/10311, current 230.209976/10312: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.209976
last_received_ts = 230.209671
last_seq = 10312
current_ts = 230.221115
current_received_ts = 230.220840
current_seq = 10313
count = 635
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.209976/10312, current 230.221115/10313: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.221115
last_received_ts = 230.220840
last_seq = 10313
current_ts = 230.232269
current_received_ts = 230.231979
current_seq = 10314
count = 636
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.221115/10313, current 230.232269/10314: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.232269
last_received_ts = 230.231979
last_seq = 10314
current_ts = 230.243423
current_received_ts = 230.243088
current_seq = 10315
count = 637
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.232269/10314, current 230.243423/10315: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.243423
last_received_ts = 230.243088
last_seq = 10315
current_ts = 230.254578
current_received_ts = 230.254242
current_seq = 10316
count = 638
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.243423/10315, current 230.254578/10316: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.254578
last_received_ts = 230.254242
last_seq = 10316
current_ts = 230.265717
current_received_ts = 230.265411
current_seq = 10317
count = 639
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.254578/10316, current 230.265717/10317: elapsed=11145.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.265717
last_received_ts = 230.265411
last_seq = 10317
current_ts = 230.276871
current_received_ts = 230.276550
current_seq = 10318
count = 640
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.265717/10317, current 230.276871/10318: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.276871
last_received_ts = 230.276550
last_seq = 10318
current_ts = 230.288010
current_received_ts = 230.287720
current_seq = 10319
count = 641
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.276871/10318, current 230.288010/10319: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.288010
last_received_ts = 230.287720
last_seq = 10319
current_ts = 230.299164
current_received_ts = 230.298889
current_seq = 10320
count = 642
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.288010/10319, current 230.299164/10320: elapsed=11146.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.299164
last_received_ts = 230.298889
last_seq = 10320
current_ts = 230.310318
current_received_ts = 230.310028
current_seq = 10321
count = 643
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.299164/10320, current 230.310318/10321: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.310318
last_received_ts = 230.310028
last_seq = 10321
current_ts = 230.321457
current_received_ts = 230.321167
current_seq = 10322
count = 644
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.310318/10321, current 230.321457/10322: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.321457
last_received_ts = 230.321167
last_seq = 10322
current_ts = 230.332611
current_received_ts = 230.332321
current_seq = 10323
count = 645
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.321457/10322, current 230.332611/10323: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.332611
last_received_ts = 230.332321
last_seq = 10323
current_ts = 230.343750
current_received_ts = 230.343445
current_seq = 10324
count = 646
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.332611/10323, current 230.343750/10324: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.343750
last_received_ts = 230.343445
last_seq = 10324
current_ts = 230.354904
current_received_ts = 230.354599
current_seq = 10325
count = 647
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.343750/10324, current 230.354904/10325: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.354904
last_received_ts = 230.354599
last_seq = 10325
current_ts = 230.366058
current_received_ts = 230.365738
current_seq = 10326
count = 648
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.354904/10325, current 230.366058/10326: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.366058
last_received_ts = 230.365738
last_seq = 10326
current_ts = 230.377197
current_received_ts = 230.376877
current_seq = 10327
count = 649
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.366058/10326, current 230.377197/10327: elapsed=11144.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.377197
last_received_ts = 230.376877
last_seq = 10327
current_ts = 230.388351
current_received_ts = 230.388077
current_seq = 10328
count = 650
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.377197/10327, current 230.388351/10328: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.388351
last_received_ts = 230.388077
last_seq = 10328
current_ts = 230.399506
current_received_ts = 230.399200
current_seq = 10329
count = 651
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.388351/10328, current 230.399506/10329: elapsed=11148.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.399506
last_received_ts = 230.399200
last_seq = 10329
current_ts = 230.410645
current_received_ts = 230.410324
current_seq = 10330
count = 652
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.399506/10329, current 230.410645/10330: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.410645
last_received_ts = 230.410324
last_seq = 10330
current_ts = 230.421799
current_received_ts = 230.421555
current_seq = 10331
count = 653
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.410645/10330, current 230.421799/10331: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.421799
last_received_ts = 230.421555
last_seq = 10331
current_ts = 230.432953
current_received_ts = 230.432648
current_seq = 10332
count = 654
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.421799/10331, current 230.432953/10332: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.432953
last_received_ts = 230.432648
last_seq = 10332
current_ts = 230.444092
current_received_ts = 230.443787
current_seq = 10333
count = 655
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.432953/10332, current 230.444092/10333: elapsed=11144.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.444092
last_received_ts = 230.443787
last_seq = 10333
current_ts = 230.455246
current_received_ts = 230.454956
current_seq = 10334
count = 656
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.444092/10333, current 230.455246/10334: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.455246
last_received_ts = 230.454956
last_seq = 10334
current_ts = 230.466400
current_received_ts = 230.466080
current_seq = 10335
count = 657
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.455246/10334, current 230.466400/10335: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.466400
last_received_ts = 230.466080
last_seq = 10335
current_ts = 230.477539
current_received_ts = 230.477219
current_seq = 10336
count = 658
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.466400/10335, current 230.477539/10336: elapsed=11148.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.477539
last_received_ts = 230.477219
last_seq = 10336
current_ts = 230.488693
current_received_ts = 230.488419
current_seq = 10337
count = 659
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.477539/10336, current 230.488693/10337: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.488693
last_received_ts = 230.488419
last_seq = 10337
current_ts = 230.499832
current_received_ts = 230.499542
current_seq = 10338
count = 660
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.488693/10337, current 230.499832/10338: elapsed=11145.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.499832
last_received_ts = 230.499542
last_seq = 10338
current_ts = 230.510986
current_received_ts = 230.510712
current_seq = 10339
count = 661
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.499832/10338, current 230.510986/10339: elapsed=11150.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.510986
last_received_ts = 230.510712
last_seq = 10339
current_ts = 230.522141
current_received_ts = 230.521866
current_seq = 10340
count = 662
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.510986/10339, current 230.522141/10340: elapsed=11153.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.522141
last_received_ts = 230.521866
last_seq = 10340
current_ts = 230.533279
current_received_ts = 230.532990
current_seq = 10341
count = 663
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.522141/10340, current 230.533279/10341: elapsed=11146.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.533279
last_received_ts = 230.532990
last_seq = 10341
current_ts = 230.544434
current_received_ts = 230.544128
current_seq = 10342
count = 664
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.533279/10341, current 230.544434/10342: elapsed=11151.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.544434
last_received_ts = 230.544128
last_seq = 10342
current_ts = 230.555588
current_received_ts = 230.555298
current_seq = 10343
count = 665
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.544434/10342, current 230.555588/10343: elapsed=11148.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.555588
last_received_ts = 230.555298
last_seq = 10343
current_ts = 230.566727
current_received_ts = 230.566437
current_seq = 10344
count = 666
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.555588/10343, current 230.566727/10344: elapsed=11147.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.566727
last_received_ts = 230.566437
last_seq = 10344
current_ts = 230.577881
current_received_ts = 230.577576
current_seq = 10345
count = 667
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.566727/10344, current 230.577881/10345: elapsed=11148.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.577881
last_received_ts = 230.577576
last_seq = 10345
current_ts = 230.589035
current_received_ts = 230.588745
current_seq = 10346
count = 668
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.577881/10345, current 230.589035/10346: elapsed=11152.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.589035
last_received_ts = 230.588745
last_seq = 10346
current_ts = 230.600174
current_received_ts = 230.599884
current_seq = 10347
count = 669
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.589035/10346, current 230.600174/10347: elapsed=11146.0us expected=11198.8us +- 56.0us, error 0.5%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.600174
last_received_ts = 230.599884
last_seq = 10347
current_ts = 230.611328
current_received_ts = 230.611069
current_seq = 10348
count = 670
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.600174/10347, current 230.611328/10348: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: name = flip
last_ts = 230.611328
last_received_ts = 230.611069
last_seq = 10348
current_ts = 230.622482
current_received_ts = 230.622208
current_seq = 10349
count = 671
seq_step = 1
(kms_flip:2469) DEBUG: flip ts/seq: last 230.611328/10348, current 230.622482/10349: elapsed=11149.0us expected=11198.8us +- 56.0us, error 0.4%
(kms_flip:2469) DEBUG: expected 670, counted 672, encoder type 2
(kms_flip:2469) DEBUG: dropped frames, expected 670, counted 672, encoder type 2
(kms_flip:2469) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660:
(kms_flip:2469) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2469) igt_core-INFO: Stack trace:
(kms_flip:2469) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2469) igt_core-INFO:   #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0()
(kms_flip:2469) igt_core-INFO:   #2 ../tests/kms_flip.c:1831 run_test()
(kms_flip:2469) igt_core-INFO:   #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987()
(kms_flip:2469) igt_core-INFO:   #4 ../tests/kms_flip.c:1987 main()
(kms_flip:2469) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2469) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2469) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (12.176s)
Dmesg
<6> [250.652719] Console: switching to colour dummy device 80x25
<6> [250.653027] [IGT] kms_flip: executing
<7> [250.660539] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [250.663139] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [250.682928] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [250.688427] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1]
<7> [250.688445] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:250:DP-1]
<7> [250.692963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1] disconnected
<7> [250.693228] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1]
<7> [250.693236] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:262:HDMI-A-1]
<7> [250.698084] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1] disconnected
<7> [250.698346] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2]
<7> [250.698354] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:268:DP-2]
<7> [250.698723] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt)
<7> [250.699258] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2] disconnected
<7> [250.699511] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2]
<7> [250.699519] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:277:HDMI-A-2]
<7> [250.699948] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> legacy)
<7> [250.704944] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2] disconnected
<7> [250.705207] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3]
<7> [250.705216] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:281:DP-3]
<7> [250.705536] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt)
<7> [250.706096] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3] disconnected
<7> [250.706375] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4]
<7> [250.706389] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:290:DP-4]
<7> [250.706758] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt)
<7> [250.707308] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4] disconnected
<7> [250.707669] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:eDP-1]
<7> [250.707678] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:241:eDP-1]
<7> [250.707905] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [250.708039] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [250.708547] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turning VDD on
<7> [250.708782] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [250.709716] i915 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [i915]] DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [250.710443] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [250.710598] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 216000, 243000, 270000, 324000, 378000, 432000, 540000
<7> [250.710713] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000
<7> [250.710857] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:241:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<7> [250.710866] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:241:eDP-1] ELD monitor
<7> [250.710868] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:241:eDP-1] ELD size 20, SAD count 0
<7> [250.710886] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:241:eDP-1] VRR capable: no
<7> [250.711004] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:241:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [250.711949] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] PCON ENCODER DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [250.712068] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:241:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<7> [250.713416] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:eDP-1] probed modes:
<7> [250.713425] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [250.713442] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1]
<7> [250.713460] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:250:DP-1]
<7> [250.718468] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1] disconnected
<7> [250.718485] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1]
<7> [250.718492] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:262:HDMI-A-1]
<7> [250.723407] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1] disconnected
<7> [250.723423] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2]
<7> [250.723430] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:268:DP-2]
<7> [250.723644] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2] disconnected
<7> [250.723657] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2]
<7> [250.723664] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:277:HDMI-A-2]
<7> [250.728389] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2] disconnected
<7> [250.728417] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3]
<7> [250.728428] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:281:DP-3]
<7> [250.728702] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3] disconnected
<7> [250.728721] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4]
<7> [250.728731] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:290:DP-4]
<7> [250.728999] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4] disconnected
<6> [250.729532] [IGT] kms_flip: starting subtest plain-flip-ts-check
<6> [250.731833] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [250.732107] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:298]
<7> [250.732147] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:301]
<7> [250.741817] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.742363] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.775299] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.775532] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.796133] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.796361] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.822898] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.823114] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [250.848935] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [250.849021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [250.849179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [250.849392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [250.849609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [250.849808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 498869/524288, found tu 0, data 0/0 link 0/0)
<7> [250.849934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [250.850061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [250.850202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [250.850326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [250.850449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [250.850570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [250.850692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [250.850814] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [250.850935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [250.851061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [250.851192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [250.851316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [250.851437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)
<7> [250.851557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)
<7> [250.851676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [250.851797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [250.851916] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [250.852073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [250.852282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [250.852449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [250.852611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [250.852741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [250.852862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [250.852981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [250.853107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)
<7> [250.853227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)
<7> [250.853348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [250.853467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [250.853587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [250.853707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [250.853828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [250.853948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)
<7> [250.854068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)
<7> [250.854215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 540000, found 0)
<7> [250.854338] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [250.854500] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [250.854622] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [250.854772] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 4001) -> ( 0 - 0), size 4001 -> 0
<7> [250.854889] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (4001 - 4096) -> ( 0 - 0), size 95 -> 0
<7> [250.855014] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [250.855169] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [250.855285] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [250.855403] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [250.855523] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [250.855650] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200
<7> [250.855778] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [250.855902] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [250.856024] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [250.856157] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0
<7> [250.856283] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Can change cdclk via squashing
<7> [250.856406] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [250.856538] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [250.856682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [250.856805] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [250.856928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [250.857047] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [250.857186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [250.857307] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [250.857429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [250.857594] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2
<7> [250.858502] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]
<7> [251.063753] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:241:eDP-1] set backlight PWM = 0
<7> [251.064659] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [251.070720] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turn panel power off
<7> [251.071204] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x00000089, pins 0x00000010, long 0x00000000
<7> [251.071222] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [251.071676] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:240:DDI A/PHY A] - short
<7> [251.071833] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [251.122256] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [251.123442] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [251.123566] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [251.124090] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:240:DDI A/PHY A] - long
<7> [251.124188] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [251.124542] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [251.124760] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:249:DDI B/PHY B]
<7> [251.125093] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring short hpd on eDP [ENCODER:240:DDI A/PHY A]
<7> [251.125279] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:251:DP-MST A]
<7> [251.125537] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:240:DDI A/PHY A]
<7> [251.125750] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:252:DP-MST B]
<7> [251.126169] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DP-MST C]
<7> [251.126580] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:254:DP-MST D]
<7> [251.127055] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:267:DDI TC1/PHY TC1]
<7> [251.127519] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:269:DP-MST A]
<7> [251.127973] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST B]
<7> [251.128274] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST C]
<7> [251.128512] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST D]
<7> [251.128785] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:276:DDI TC2/PHY TC2]
<7> [251.129018] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI TC3/PHY TC3]
<7> [251.129518] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [251.129801] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [251.130082] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [251.130202] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [251.130316] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:289:DDI TC4/PHY TC4]
<7> [251.130437] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:291:DP-MST A]
<7> [251.130551] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:292:DP-MST B]
<7> [251.130688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:293:DP-MST C]
<7> [251.130838] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:294:DP-MST D]
<7> [251.131031] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:241:eDP-1]
<7> [251.131272] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [251.131427] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: yes -> no (pipe: *)
<7> [251.131565] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [251.131798] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [251.132021] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [251.132181] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [251.132341] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432
<7> [251.132513] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [251.132661] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [251.132750] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:238:pipe D]
<7> [251.132841] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [251.132864] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:241:eDP-1]
<7> [251.132924] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:241:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [251.133064] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:240:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 max link_bpp 30.0000
<7> [251.133181] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 link rate required 1926825 available 2160000
<7> [251.133301] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [251.133424] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:240:DDI A/PHY A] [CRTC:82:pipe A]
<7> [251.133543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [251.133681] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [251.133809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [251.133931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [251.134049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 498869/524288)
<7> [251.134166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [251.134282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [251.134397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [251.134515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [251.134643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [251.134763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [251.134880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [251.134995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [251.135109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [251.135222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [251.135337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [251.135453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [251.135577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)
<7> [251.135700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)
<7> [251.135816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [251.135933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [251.136055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [251.136175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [251.136294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [251.136414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [251.136535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [251.136668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [251.136787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [251.136903] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [251.137018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)
<7> [251.137138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)
<7> [251.137263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [251.137388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [251.137516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [251.137650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [251.137772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [251.137892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)
<7> [251.138012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)
<7> [251.138128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 540000)
<7> [251.138244] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [251.138447] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [251.138681] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [251.138932] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001
<7> [251.139139] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95
<7> [251.139341] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [251.139545] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0
<7> [251.139720] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87
<7> [251.139877] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88
<7> [251.140039] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 2055280 num active planes 1
<7> [251.140274] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200
<7> [251.140408] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [251.140545] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [251.140667] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [251.140781] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159
<7> [251.140911] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (256910 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [251.141032] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (40143 kHz) > old min cdclk (0 kHz)
<7> [251.141156] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Can change cdclk via squashing
<7> [251.141278] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz
<7> [251.141398] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [251.141544] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [251.141669] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [251.141793] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [251.141916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [251.142037] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [251.142159] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [251.142280] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [251.142401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64
<7> [251.142527] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [251.142648] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled
<7> [251.142769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled
<7> [251.142889] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [251.143009] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0
<7> [251.143130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [251.143250] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14
<7> [251.143369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB
<7> [251.143489] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB
<7> [251.143624] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0
<7> [251.143743] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range
<7> [251.143861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined
<7> [251.143979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [251.144098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [251.144220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [251.144339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [251.144459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [251.144594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [251.144717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [251.144838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa
<7> [251.144958] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [251.145077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pipe src: 2880x1800+0+0, pixel rate 513820
<7> [251.145197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0
<7> [251.145318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [251.145438] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [251.145567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [251.145687] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x83d60, dpll_md: 0xf42110, fp0: 0xf8, fp1: 0x1000000
<7> [251.145808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [251.145927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [251.146046] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [251.146166] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [251.146287] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [251.146407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [251.146535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [251.146656] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [251.146775] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [251.146895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [251.147013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [251.147134] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [251.147255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:298] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [251.147376] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [251.147504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [251.147626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [251.147745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [251.147863] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [251.147983] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [251.148104] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [251.150500] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [251.150741] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [251.150957] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [251.151235] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [251.151373] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [251.151800] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [251.152009] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [251.152174] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768
<7> [251.152300] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [251.152429] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:249:DDI B/PHY B]
<7> [251.152550] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:251:DP-MST A]
<7> [251.152670] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:252:DP-MST B]
<7> [251.152791] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DP-MST C]
<7> [251.152910] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:254:DP-MST D]
<7> [251.153027] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:267:DDI TC1/PHY TC1]
<7> [251.153146] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:269:DP-MST A]
<7> [251.153263] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST B]
<7> [251.153381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST C]
<7> [251.153509] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST D]
<7> [251.153636] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:276:DDI TC2/PHY TC2]
<7> [251.153752] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI TC3/PHY TC3]
<7> [251.153868] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [251.153984] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [251.154100] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [251.154218] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [251.154335] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:289:DDI TC4/PHY TC4]
<7> [251.154460] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:291:DP-MST A]
<7> [251.154577] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:292:DP-MST B]
<7> [251.154695] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:293:DP-MST C]
<7> [251.154811] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:294:DP-MST D]
<7> [251.154924] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf
<7> [251.155145] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: *)
<7> [251.155276] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [251.155440] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [251.156505] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turn panel power on
<7> [251.156693] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 wait for panel power cycle (467 ms remaining)
<7> [251.626819] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [251.630311] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [251.630955] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 wait for panel power on
<7> [251.631524] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [251.663125] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [251.663599] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:240:DDI A/PHY A] - long
<7> [251.664031] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [251.664487] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:240:DDI A/PHY A]
<7> [251.729479] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected)
<7> [251.729602] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected)
<7> [251.729737] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected)
<7> [251.729784] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (legacy -> disconnected)
<7> [251.832185] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [251.834660] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turning VDD on
<7> [251.835153] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [251.839063] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80
<7> [251.839911] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [251.840843] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07
<7> [251.841831] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [251.842509] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [251.843634] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Clock recovery OK
<7> [251.843750] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Using DP training pattern TPS3
<7> [251.845298] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [251.845413] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4
<7> [251.845988] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [251.846211] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]
<7> [251.846345] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A
<7> [251.846518] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:241:eDP-1] set backlight PWM = 13892
<7> [251.857880] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2
<7> [251.859450] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:241:eDP-1]
<7> [251.859626] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [254.398263] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turning VDD off
<7> [254.398748] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [259.542104] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [259.542566] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [259.543029] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<6> [262.908417] [IGT] kms_flip: finished subtest A-eDP1, FAIL
Created at 2025-03-18 12:02:14