Results for igt@kms_flip@plain-flip-ts-check@a-hdmi-a1

Result: Fail

git-log-oneline i915_display_info21 igt_runner21 runtimes21 results21.json results21-i915-load.json guc_logs21.tar i915_display_info_post_exec21 boot21 dmesg21

DetailValue
Duration 19.16 seconds
Hostname 
shard-dg1-14
Igt-Version 
IGT-Version: 2.3-g66eadc8d5 (x86_64) (Linux: 7.0.0-rc5-CI_DRM_18213-gcfe3d5f3eb5c+ x86_64)
Out 
Using IGT_SRANDOM=1774492093 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16670.9us +- 4.113us accuracy 0.07%
Event flip: expected 449, counted 442, passrate = 12.22%, encoder type 2
Expected frametime: 16667us; measured 16817.8us +- 3.890us accuracy 0.07%
vblank interval differs from modeline! expected 16666.7us, measured 16818us +- 3.890us, difference 151.1us (38.8 sigma)
Event flip: expected 446, counted 448, passrate = 82.14%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2126 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (19.160s)
Err 
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-HDMI-A1
(kms_flip:7652) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:7652) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.275330
last_received_ts = 538.274841
last_seq = 21005
current_ts = 538.292053
current_received_ts = 538.291565
current_seq = 21006
count = 364
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.275330/21005, current 538.292053/21006: elapsed=16730.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.275330/21005, current 538.292053/21006: elapsed=16730.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.292053
last_received_ts = 538.291565
last_seq = 21006
current_ts = 538.308777
current_received_ts = 538.308350
current_seq = 21007
count = 365
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.292053/21006, current 538.308777/21007: elapsed=16720.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.292053/21006, current 538.308777/21007: elapsed=16720.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.308777
last_received_ts = 538.308350
last_seq = 21007
current_ts = 538.325500
current_received_ts = 538.325073
current_seq = 21008
count = 366
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.308777/21007, current 538.325500/21008: elapsed=16743.0us expected=16817.8us +- 84.1us, error 0.4%
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.325500
last_received_ts = 538.325073
last_seq = 21008
current_ts = 538.342224
current_received_ts = 538.341797
current_seq = 21009
count = 367
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.325500/21008, current 538.342224/21009: elapsed=16730.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.325500/21008, current 538.342224/21009: elapsed=16730.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.342224
last_received_ts = 538.341797
last_seq = 21009
current_ts = 538.358948
current_received_ts = 538.358521
current_seq = 21010
count = 368
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.342224/21009, current 538.358948/21010: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.342224/21009, current 538.358948/21010: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.358948
last_received_ts = 538.358521
last_seq = 21010
current_ts = 538.375671
current_received_ts = 538.375244
current_seq = 21011
count = 369
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.358948/21010, current 538.375671/21011: elapsed=16726.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.358948/21010, current 538.375671/21011: elapsed=16726.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.375671
last_received_ts = 538.375244
last_seq = 21011
current_ts = 538.392395
current_received_ts = 538.391968
current_seq = 21012
count = 370
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.375671/21011, current 538.392395/21012: elapsed=16736.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.392395
last_received_ts = 538.391968
last_seq = 21012
current_ts = 538.409180
current_received_ts = 538.408691
current_seq = 21013
count = 371
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.392395/21012, current 538.409180/21013: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.392395/21012, current 538.409180/21013: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.409180
last_received_ts = 538.408691
last_seq = 21013
current_ts = 538.425903
current_received_ts = 538.425415
current_seq = 21014
count = 372
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.409180/21013, current 538.425903/21014: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.409180/21013, current 538.425903/21014: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.425903
last_received_ts = 538.425415
last_seq = 21014
current_ts = 538.442627
current_received_ts = 538.442139
current_seq = 21015
count = 373
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.425903/21014, current 538.442627/21015: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.425903/21014, current 538.442627/21015: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.442627
last_received_ts = 538.442139
last_seq = 21015
current_ts = 538.459351
current_received_ts = 538.458923
current_seq = 21016
count = 374
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.442627/21015, current 538.459351/21016: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.442627/21015, current 538.459351/21016: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.459351
last_received_ts = 538.458923
last_seq = 21016
current_ts = 538.476074
current_received_ts = 538.475647
current_seq = 21017
count = 375
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.459351/21016, current 538.476074/21017: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.459351/21016, current 538.476074/21017: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.476074
last_received_ts = 538.475647
last_seq = 21017
current_ts = 538.492798
current_received_ts = 538.492371
current_seq = 21018
count = 376
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.476074/21017, current 538.492798/21018: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.476074/21017, current 538.492798/21018: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.492798
last_received_ts = 538.492371
last_seq = 21018
current_ts = 538.509521
current_received_ts = 538.509094
current_seq = 21019
count = 377
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.492798/21018, current 538.509521/21019: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.492798/21018, current 538.509521/21019: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.509521
last_received_ts = 538.509094
last_seq = 21019
current_ts = 538.526245
current_received_ts = 538.525818
current_seq = 21020
count = 378
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.509521/21019, current 538.526245/21020: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.509521/21019, current 538.526245/21020: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.526245
last_received_ts = 538.525818
last_seq = 21020
current_ts = 538.543030
current_received_ts = 538.542542
current_seq = 21021
count = 379
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.526245/21020, current 538.543030/21021: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.526245/21020, current 538.543030/21021: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.543030
last_received_ts = 538.542542
last_seq = 21021
current_ts = 538.559753
current_received_ts = 538.559265
current_seq = 21022
count = 380
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.543030/21021, current 538.559753/21022: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.543030/21021, current 538.559753/21022: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.559753
last_received_ts = 538.559265
last_seq = 21022
current_ts = 538.576477
current_received_ts = 538.576050
current_seq = 21023
count = 381
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.559753/21022, current 538.576477/21023: elapsed=16738.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.576477
last_received_ts = 538.576050
last_seq = 21023
current_ts = 538.593201
current_received_ts = 538.592773
current_seq = 21024
count = 382
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.576477/21023, current 538.593201/21024: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.576477/21023, current 538.593201/21024: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.593201
last_received_ts = 538.592773
last_seq = 21024
current_ts = 538.609924
current_received_ts = 538.609497
current_seq = 21025
count = 383
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.593201/21024, current 538.609924/21025: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.593201/21024, current 538.609924/21025: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.609924
last_received_ts = 538.609497
last_seq = 21025
current_ts = 538.626648
current_received_ts = 538.626221
current_seq = 21026
count = 384
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.609924/21025, current 538.626648/21026: elapsed=16726.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.609924/21025, current 538.626648/21026: elapsed=16726.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.626648
last_received_ts = 538.626221
last_seq = 21026
current_ts = 538.643372
current_received_ts = 538.642944
current_seq = 21027
count = 385
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.626648/21026, current 538.643372/21027: elapsed=16735.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.643372
last_received_ts = 538.642944
last_seq = 21027
current_ts = 538.660095
current_received_ts = 538.659668
current_seq = 21028
count = 386
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.643372/21027, current 538.660095/21028: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.643372/21027, current 538.660095/21028: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.660095
last_received_ts = 538.659668
last_seq = 21028
current_ts = 538.676880
current_received_ts = 538.676392
current_seq = 21029
count = 387
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.660095/21028, current 538.676880/21029: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.660095/21028, current 538.676880/21029: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.676880
last_received_ts = 538.676392
last_seq = 21029
current_ts = 538.693604
current_received_ts = 538.693115
current_seq = 21030
count = 388
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.676880/21029, current 538.693604/21030: elapsed=16730.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.676880/21029, current 538.693604/21030: elapsed=16730.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.693604
last_received_ts = 538.693115
last_seq = 21030
current_ts = 538.710327
current_received_ts = 538.709839
current_seq = 21031
count = 389
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.693604/21030, current 538.710327/21031: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.693604/21030, current 538.710327/21031: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.710327
last_received_ts = 538.709839
last_seq = 21031
current_ts = 538.727051
current_received_ts = 538.726562
current_seq = 21032
count = 390
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.710327/21031, current 538.727051/21032: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.710327/21031, current 538.727051/21032: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.727051
last_received_ts = 538.726562
last_seq = 21032
current_ts = 538.743774
current_received_ts = 538.743347
current_seq = 21033
count = 391
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.727051/21032, current 538.743774/21033: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.727051/21032, current 538.743774/21033: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.743774
last_received_ts = 538.743347
last_seq = 21033
current_ts = 538.760498
current_received_ts = 538.760071
current_seq = 21034
count = 392
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.743774/21033, current 538.760498/21034: elapsed=16738.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.760498
last_received_ts = 538.760071
last_seq = 21034
current_ts = 538.777222
current_received_ts = 538.776794
current_seq = 21035
count = 393
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.760498/21034, current 538.777222/21035: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.760498/21034, current 538.777222/21035: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.777222
last_received_ts = 538.776794
last_seq = 21035
current_ts = 538.793945
current_received_ts = 538.793518
current_seq = 21036
count = 394
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.777222/21035, current 538.793945/21036: elapsed=16730.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.777222/21035, current 538.793945/21036: elapsed=16730.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.793945
last_received_ts = 538.793518
last_seq = 21036
current_ts = 538.810730
current_received_ts = 538.810242
current_seq = 21037
count = 395
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.793945/21036, current 538.810730/21037: elapsed=16727.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.793945/21036, current 538.810730/21037: elapsed=16727.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.810730
last_received_ts = 538.810242
last_seq = 21037
current_ts = 538.827454
current_received_ts = 538.826965
current_seq = 21038
count = 396
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.810730/21037, current 538.827454/21038: elapsed=16736.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.827454
last_received_ts = 538.826965
last_seq = 21038
current_ts = 538.844177
current_received_ts = 538.843689
current_seq = 21039
count = 397
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.827454/21038, current 538.844177/21039: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.827454/21038, current 538.844177/21039: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.844177
last_received_ts = 538.843689
last_seq = 21039
current_ts = 538.860901
current_received_ts = 538.860413
current_seq = 21040
count = 398
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.844177/21039, current 538.860901/21040: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.844177/21039, current 538.860901/21040: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.860901
last_received_ts = 538.860413
last_seq = 21040
current_ts = 538.877625
current_received_ts = 538.877197
current_seq = 21041
count = 399
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.860901/21040, current 538.877625/21041: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.860901/21040, current 538.877625/21041: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.877625
last_received_ts = 538.877197
last_seq = 21041
current_ts = 538.894348
current_received_ts = 538.893921
current_seq = 21042
count = 400
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.877625/21041, current 538.894348/21042: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.877625/21041, current 538.894348/21042: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.894348
last_received_ts = 538.893921
last_seq = 21042
current_ts = 538.911072
current_received_ts = 538.910645
current_seq = 21043
count = 401
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.894348/21042, current 538.911072/21043: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.894348/21042, current 538.911072/21043: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.911072
last_received_ts = 538.910645
last_seq = 21043
current_ts = 538.927795
current_received_ts = 538.927368
current_seq = 21044
count = 402
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.911072/21043, current 538.927795/21044: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.911072/21043, current 538.927795/21044: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.927795
last_received_ts = 538.927368
last_seq = 21044
current_ts = 538.944580
current_received_ts = 538.944092
current_seq = 21045
count = 403
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.927795/21044, current 538.944580/21045: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.927795/21044, current 538.944580/21045: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.944580
last_received_ts = 538.944092
last_seq = 21045
current_ts = 538.961304
current_received_ts = 538.960815
current_seq = 21046
count = 404
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.944580/21045, current 538.961304/21046: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.944580/21045, current 538.961304/21046: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.961304
last_received_ts = 538.960815
last_seq = 21046
current_ts = 538.978027
current_received_ts = 538.977539
current_seq = 21047
count = 405
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.961304/21046, current 538.978027/21047: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.961304/21046, current 538.978027/21047: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.978027
last_received_ts = 538.977539
last_seq = 21047
current_ts = 538.994751
current_received_ts = 538.994263
current_seq = 21048
count = 406
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.978027/21047, current 538.994751/21048: elapsed=16728.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 538.978027/21047, current 538.994751/21048: elapsed=16728.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 538.994751
last_received_ts = 538.994263
last_seq = 21048
current_ts = 539.011475
current_received_ts = 539.011047
current_seq = 21049
count = 407
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 538.994751/21048, current 539.011475/21049: elapsed=16735.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.011475
last_received_ts = 539.011047
last_seq = 21049
current_ts = 539.028198
current_received_ts = 539.027771
current_seq = 21050
count = 408
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.011475/21049, current 539.028198/21050: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.011475/21049, current 539.028198/21050: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.028198
last_received_ts = 539.027771
last_seq = 21050
current_ts = 539.044922
current_received_ts = 539.044495
current_seq = 21051
count = 409
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.028198/21050, current 539.044922/21051: elapsed=16731.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.028198/21050, current 539.044922/21051: elapsed=16731.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.044922
last_received_ts = 539.044495
last_seq = 21051
current_ts = 539.061646
current_received_ts = 539.061279
current_seq = 21052
count = 410
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.044922/21051, current 539.061646/21052: elapsed=16722.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.044922/21051, current 539.061646/21052: elapsed=16722.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.061646
last_received_ts = 539.061279
last_seq = 21052
current_ts = 539.078430
current_received_ts = 539.077942
current_seq = 21053
count = 411
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.061646/21052, current 539.078430/21053: elapsed=16741.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.078430
last_received_ts = 539.077942
last_seq = 21053
current_ts = 539.095154
current_received_ts = 539.094666
current_seq = 21054
count = 412
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.078430/21053, current 539.095154/21054: elapsed=16730.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.078430/21053, current 539.095154/21054: elapsed=16730.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.095154
last_received_ts = 539.094666
last_seq = 21054
current_ts = 539.111877
current_received_ts = 539.111450
current_seq = 21055
count = 413
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.095154/21054, current 539.111877/21055: elapsed=16721.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.095154/21054, current 539.111877/21055: elapsed=16721.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.111877
last_received_ts = 539.111450
last_seq = 21055
current_ts = 539.128601
current_received_ts = 539.128113
current_seq = 21056
count = 414
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.111877/21055, current 539.128601/21056: elapsed=16742.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.128601
last_received_ts = 539.128113
last_seq = 21056
current_ts = 539.145325
current_received_ts = 539.144897
current_seq = 21057
count = 415
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.128601/21056, current 539.145325/21057: elapsed=16730.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.128601/21056, current 539.145325/21057: elapsed=16730.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.145325
last_received_ts = 539.144897
last_seq = 21057
current_ts = 539.162048
current_received_ts = 539.161621
current_seq = 21058
count = 416
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.145325/21057, current 539.162048/21058: elapsed=16732.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.145325/21057, current 539.162048/21058: elapsed=16732.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.162048
last_received_ts = 539.161621
last_seq = 21058
current_ts = 539.178772
current_received_ts = 539.178345
current_seq = 21059
count = 417
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.162048/21058, current 539.178772/21059: elapsed=16730.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.162048/21058, current 539.178772/21059: elapsed=16730.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.178772
last_received_ts = 539.178345
last_seq = 21059
current_ts = 539.195496
current_received_ts = 539.195068
current_seq = 21060
count = 418
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.178772/21059, current 539.195496/21060: elapsed=16722.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.178772/21059, current 539.195496/21060: elapsed=16722.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.195496
last_received_ts = 539.195068
last_seq = 21060
current_ts = 539.212219
current_received_ts = 539.211792
current_seq = 21061
count = 419
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.195496/21060, current 539.212219/21061: elapsed=16725.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.195496/21060, current 539.212219/21061: elapsed=16725.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.212219
last_received_ts = 539.211792
last_seq = 21061
current_ts = 539.228943
current_received_ts = 539.228516
current_seq = 21062
count = 420
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.212219/21061, current 539.228943/21062: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.212219/21061, current 539.228943/21062: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.228943
last_received_ts = 539.228516
last_seq = 21062
current_ts = 539.245667
current_received_ts = 539.245239
current_seq = 21063
count = 421
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.228943/21062, current 539.245667/21063: elapsed=16725.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.228943/21062, current 539.245667/21063: elapsed=16725.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.245667
last_received_ts = 539.245239
last_seq = 21063
current_ts = 539.262390
current_received_ts = 539.261963
current_seq = 21064
count = 422
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.245667/21063, current 539.262390/21064: elapsed=16723.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.245667/21063, current 539.262390/21064: elapsed=16723.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.262390
last_received_ts = 539.261963
last_seq = 21064
current_ts = 539.279114
current_received_ts = 539.278687
current_seq = 21065
count = 423
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.262390/21064, current 539.279114/21065: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.262390/21064, current 539.279114/21065: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.279114
last_received_ts = 539.278687
last_seq = 21065
current_ts = 539.295837
current_received_ts = 539.295410
current_seq = 21066
count = 424
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.279114/21065, current 539.295837/21066: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.279114/21065, current 539.295837/21066: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.295837
last_received_ts = 539.295410
last_seq = 21066
current_ts = 539.312561
current_received_ts = 539.312134
current_seq = 21067
count = 425
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.295837/21066, current 539.312561/21067: elapsed=16726.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.295837/21066, current 539.312561/21067: elapsed=16726.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.312561
last_received_ts = 539.312134
last_seq = 21067
current_ts = 539.329285
current_received_ts = 539.328857
current_seq = 21068
count = 426
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.312561/21067, current 539.329285/21068: elapsed=16723.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.312561/21067, current 539.329285/21068: elapsed=16723.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.329285
last_received_ts = 539.328857
last_seq = 21068
current_ts = 539.346008
current_received_ts = 539.345581
current_seq = 21069
count = 427
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.329285/21068, current 539.346008/21069: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.329285/21068, current 539.346008/21069: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.346008
last_received_ts = 539.345581
last_seq = 21069
current_ts = 539.362732
current_received_ts = 539.362244
current_seq = 21070
count = 428
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.346008/21069, current 539.362732/21070: elapsed=16715.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.346008/21069, current 539.362732/21070: elapsed=16715.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.362732
last_received_ts = 539.362244
last_seq = 21070
current_ts = 539.379456
current_received_ts = 539.379089
current_seq = 21071
count = 429
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.362732/21070, current 539.379456/21071: elapsed=16734.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.379456
last_received_ts = 539.379089
last_seq = 21071
current_ts = 539.396179
current_received_ts = 539.395752
current_seq = 21072
count = 430
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.379456/21071, current 539.396179/21072: elapsed=16723.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.379456/21071, current 539.396179/21072: elapsed=16723.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.396179
last_received_ts = 539.395752
last_seq = 21072
current_ts = 539.412903
current_received_ts = 539.412476
current_seq = 21073
count = 431
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.396179/21072, current 539.412903/21073: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.396179/21072, current 539.412903/21073: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.412903
last_received_ts = 539.412476
last_seq = 21073
current_ts = 539.429626
current_received_ts = 539.429199
current_seq = 21074
count = 432
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.412903/21073, current 539.429626/21074: elapsed=16725.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.412903/21073, current 539.429626/21074: elapsed=16725.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.429626
last_received_ts = 539.429199
last_seq = 21074
current_ts = 539.446350
current_received_ts = 539.445923
current_seq = 21075
count = 433
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.429626/21074, current 539.446350/21075: elapsed=16723.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.429626/21074, current 539.446350/21075: elapsed=16723.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.446350
last_received_ts = 539.445923
last_seq = 21075
current_ts = 539.463074
current_received_ts = 539.462646
current_seq = 21076
count = 434
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.446350/21075, current 539.463074/21076: elapsed=16725.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.446350/21075, current 539.463074/21076: elapsed=16725.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.463074
last_received_ts = 539.462646
last_seq = 21076
current_ts = 539.479797
current_received_ts = 539.479370
current_seq = 21077
count = 435
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.463074/21076, current 539.479797/21077: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.463074/21076, current 539.479797/21077: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.479797
last_received_ts = 539.479370
last_seq = 21077
current_ts = 539.496521
current_received_ts = 539.496155
current_seq = 21078
count = 436
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.479797/21077, current 539.496521/21078: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.479797/21077, current 539.496521/21078: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.496521
last_received_ts = 539.496155
last_seq = 21078
current_ts = 539.513245
current_received_ts = 539.512817
current_seq = 21079
count = 437
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.496521/21078, current 539.513245/21079: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.496521/21078, current 539.513245/21079: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.513245
last_received_ts = 539.512817
last_seq = 21079
current_ts = 539.529968
current_received_ts = 539.529541
current_seq = 21080
count = 438
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.513245/21079, current 539.529968/21080: elapsed=16726.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.513245/21079, current 539.529968/21080: elapsed=16726.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.529968
last_received_ts = 539.529541
last_seq = 21080
current_ts = 539.546692
current_received_ts = 539.546265
current_seq = 21081
count = 439
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.529968/21080, current 539.546692/21081: elapsed=16719.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.529968/21080, current 539.546692/21081: elapsed=16719.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.546692
last_received_ts = 539.546265
last_seq = 21081
current_ts = 539.563416
current_received_ts = 539.562988
current_seq = 21082
count = 440
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.546692/21081, current 539.563416/21082: elapsed=16729.0us expected=16817.8us +- 84.1us, error 0.5%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.546692/21081, current 539.563416/21082: elapsed=16729.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.563416
last_received_ts = 539.562988
last_seq = 21082
current_ts = 539.580139
current_received_ts = 539.579712
current_seq = 21083
count = 441
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.563416/21082, current 539.580139/21083: elapsed=16723.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.563416/21082, current 539.580139/21083: elapsed=16723.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.580139
last_received_ts = 539.579712
last_seq = 21083
current_ts = 539.596863
current_received_ts = 539.596436
current_seq = 21084
count = 442
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.580139/21083, current 539.596863/21084: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.580139/21083, current 539.596863/21084: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.596863
last_received_ts = 539.596436
last_seq = 21084
current_ts = 539.613586
current_received_ts = 539.613159
current_seq = 21085
count = 443
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.596863/21084, current 539.613586/21085: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.596863/21084, current 539.613586/21085: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.613586
last_received_ts = 539.613159
last_seq = 21085
current_ts = 539.630310
current_received_ts = 539.629883
current_seq = 21086
count = 444
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.613586/21085, current 539.630310/21086: elapsed=16725.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.613586/21085, current 539.630310/21086: elapsed=16725.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.630310
last_received_ts = 539.629883
last_seq = 21086
current_ts = 539.647034
current_received_ts = 539.646606
current_seq = 21087
count = 445
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.630310/21086, current 539.647034/21087: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.630310/21086, current 539.647034/21087: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.647034
last_received_ts = 539.646606
last_seq = 21087
current_ts = 539.663818
current_received_ts = 539.663330
current_seq = 21088
count = 446
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.647034/21087, current 539.663818/21088: elapsed=16725.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.647034/21087, current 539.663818/21088: elapsed=16725.0us expected=16817.8us
(kms_flip:7652) DEBUG: name = flip
last_ts = 539.663818
last_received_ts = 539.663330
last_seq = 21088
current_ts = 539.680542
current_received_ts = 539.680115
current_seq = 21089
count = 447
seq_step = 1
(kms_flip:7652) DEBUG: flip ts/seq: last 539.663818/21088, current 539.680542/21089: elapsed=16724.0us expected=16817.8us +- 84.1us, error 0.6%
(kms_flip:7652) DEBUG: inconsistent flip ts/seq: last 539.663818/21088, current 539.680542/21089: elapsed=16724.0us expected=16817.8us
(kms_flip:7652) INFO: Event flip: expected 446, counted 448, passrate = 82.14%, encoder type 2
(kms_flip:7652) DEBUG: dropped frames, expected 446, counted 448, passrate = 82.14%, encoder type 2
(kms_flip:7652) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:7652) CRITICAL: Failed assertion: !retried
(kms_flip:7652) igt_core-INFO: Stack trace:
(kms_flip:7652) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:7652) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:7652) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:7652) igt_core-INFO:   #3 ../tests/kms_flip.c:2126 __igt_unique____real_main2047()
(kms_flip:7652) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:7652) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:7652) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:7652) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (19.160s)
Dmesg
<6> [519.371786] Console: switching to colour dummy device 80x25
<6> [519.372711] [IGT] kms_flip: executing
<7> [519.376493] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [519.377633] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [519.377985] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [519.391131] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1]
<7> [519.391140] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:588:DP-1]
<7> [519.391405] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [519.391558] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [519.391891] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [519.413016] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.434156] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.455273] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.476390] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.497503] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.518625] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.539735] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.560904] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.582049] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.603190] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.624329] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.645476] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.666616] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.687791] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.708934] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.730070] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.751209] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.772346] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.793484] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.814621] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.835805] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.856949] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.878088] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.899227] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.920364] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.941502] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.962639] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [519.983844] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.004988] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.026125] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.047273] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.068410] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.068578] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<7> [520.068623] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1] disconnected
<7> [520.069474] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2]
<7> [520.069480] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:607:HDMI-A-2]
<7> [520.073697] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2] disconnected
<7> [520.074248] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2]
<7> [520.074254] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:611:DP-2]
<7> [520.078443] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2] disconnected
<7> [520.078969] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3]
<7> [520.078974] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [520.083180] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3] disconnected
<7> [520.083642] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3]
<7> [520.083647] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:623:DP-3]
<7> [520.087880] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3] disconnected
<7> [520.088364] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4]
<7> [520.088370] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:631:HDMI-A-4]
<7> [520.092571] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4] disconnected
<7> [520.093359] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1]
<7> [520.093364] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:588:DP-1]
<7> [520.114484] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.135588] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.156699] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.177829] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.198958] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.220116] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.241240] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.262353] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.283493] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.304676] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.325826] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.346964] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.368102] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.389243] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.410399] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.431549] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.452720] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.473874] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.495023] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.516173] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.537320] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.558467] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.579650] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.600806] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.621956] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.643109] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.664258] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.685406] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.706596] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.727749] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.748902] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.770047] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [520.770228] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<7> [520.770259] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1] disconnected
<7> [520.770282] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1]
<7> [520.770288] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [520.795234] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:600:HDMI-A-1] ELD monitor FH-DP4K
<7> [520.795241] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:600:HDMI-A-1] ELD size 36, SAD count 2
<7> [520.795430] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK for addr: 0040 w(1)
<7> [520.795567] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK on first message, retry
<7> [520.795882] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK for addr: 0040 w(1)
<7> [520.796038] i915 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [520.796052] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] Assuming DP dual mode adaptor presence based on VBT
<7> [520.796185] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] DP dual mode adaptor (type 1 DVI) detected (max TMDS clock: 165000 kHz)
<7> [520.796966] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241700 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9 (CLOCK_HIGH)
<7> [520.796976] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 267820 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9 (CLOCK_HIGH)
<7> [520.796983] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [520.796990] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 266640 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9 (CLOCK_HIGH)
<7> [520.796997] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [520.797005] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1] probed modes:
<7> [520.797009] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [520.797013] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 17 151100 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9
<7> [520.797017] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 30 133910 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9
<7> [520.797021] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 30 120850 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9
<7> [520.797024] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [520.797028] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [520.797032] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [520.797035] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [520.797039] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [520.797043] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [520.797046] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [520.797050] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [520.797054] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [520.797058] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [520.797061] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [520.797065] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [520.797069] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [520.797072] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [520.797076] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [520.797080] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [520.797084] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [520.797087] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [520.797091] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [520.797095] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [520.797098] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [520.797102] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [520.797135] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2]
<7> [520.797140] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:607:HDMI-A-2]
<7> [520.801352] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2] disconnected
<7> [520.801374] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2]
<7> [520.801379] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:611:DP-2]
<7> [520.805567] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2] disconnected
<7> [520.805587] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3]
<7> [520.805592] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [520.809894] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3] disconnected
<7> [520.809911] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3]
<7> [520.809916] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:623:DP-3]
<7> [520.814119] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3] disconnected
<7> [520.814135] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4]
<7> [520.814139] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:631:HDMI-A-4]
<7> [520.818329] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4] disconnected
<6> [520.819476] [IGT] kms_flip: starting subtest plain-flip-ts-check
<6> [520.825097] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [520.825513] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:636]
<7> [520.825674] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:638]
<7> [520.826145] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.826673] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.870650] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [520.870861] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [520.871042] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [520.871196] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [520.873368] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.873751] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.881515] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.882032] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.901116] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.901445] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [520.927805] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [520.927923] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [520.928090] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [520.928240] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [520.928389] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [520.928538] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [520.928704] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [520.928855] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [520.929005] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [520.929153] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [520.929302] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [520.929450] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [520.929645] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [520.929794] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [520.929942] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [520.930090] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [520.930238] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [520.930386] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [520.930533] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [520.930728] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [520.930876] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [520.931024] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [520.931173] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [520.931321] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [520.931470] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [520.931660] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [520.931808] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [520.931956] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [520.932103] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [520.932252] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [520.932401] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [520.932549] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [520.932740] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [520.932888] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [520.933037] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [520.933185] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [520.933334] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [520.933482] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [520.933669] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [520.933819] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [520.933995] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [520.934141] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [520.934331] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [520.934475] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [520.934658] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [520.934803] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [520.934948] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [520.935092] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [520.935258] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [520.935405] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [520.935570] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [520.935778] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [520.935928] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [520.936076] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [520.936223] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [520.936370] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [520.936517] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [520.936701] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [520.936848] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [520.936995] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [520.937142] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [520.937354] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [520.937508] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [520.950120] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [520.966759] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [520.966949] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [520.967130] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [520.967303] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [520.967459] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [520.967667] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [520.967828] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [520.967975] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [520.968122] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [520.968268] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [520.968414] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [520.968560] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [520.968747] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [520.968894] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [520.969040] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [520.969187] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [520.969334] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [520.969480] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [520.969671] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [520.969818] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [520.969966] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [520.970232] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [520.970459] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [520.971143] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [520.971336] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [520.971514] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [520.971743] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [520.971781] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:600:HDMI-A-1]
<7> [520.971866] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:600:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [520.972025] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [520.972164] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [520.972314] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI A/PHY A] [CRTC:171:pipe A]
<7> [520.972457] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [520.972654] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [520.972803] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [520.972950] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [520.973098] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [520.973246] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [520.973393] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [520.973540] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [520.973733] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [520.973881] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [520.974029] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [520.974177] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [520.974325] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [520.974473] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [520.974669] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [520.974817] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [520.974966] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [520.975115] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [520.975262] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [520.975410] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [520.975558] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [520.975746] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [520.975894] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [520.976042] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [520.976190] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [520.976338] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [520.976486] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [520.976673] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [520.976821] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [520.976969] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [520.977117] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [520.977265] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [520.977413] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [520.977561] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [520.977816] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [520.977965] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [520.978112] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [520.978261] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [520.978437] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [520.978613] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [520.978816] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [520.978961] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [520.979106] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [520.979250] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [520.979395] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [520.979539] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [520.979747] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [520.979895] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [520.980041] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [520.980205] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [520.980370] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [520.980518] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [520.980710] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [520.980861] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [520.981009] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [520.981156] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [520.981304] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [520.981451] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [520.981637] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [520.981783] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [520.981931] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [520.982078] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [520.982225] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [520.982372] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [520.982521] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [520.982706] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [520.982854] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [520.983002] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [520.983151] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [520.983299] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [520.983448] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [520.983637] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [520.983785] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [520.983931] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [520.984078] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [520.984225] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [520.984372] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [520.984521] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [520.984709] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [520.984857] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [520.985004] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [520.985151] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [520.985298] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [520.985445] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [520.985633] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [520.985780] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [520.985927] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [520.986073] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [520.986220] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [520.986367] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [520.986513] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [520.986710] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [520.986888] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [520.987036] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [520.987183] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [520.987331] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [520.987478] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [520.987661] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [520.987807] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [520.987954] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [520.988375] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [520.988525] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [520.988713] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [520.988860] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [520.989007] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [520.989155] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [520.989302] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [520.989448] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [520.989633] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [520.989780] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [520.989927] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [520.990074] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [520.990221] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [520.990368] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [520.990515] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [520.990702] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [520.990928] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [520.991254] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [520.991404] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [520.991642] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [520.991874] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [520.992425] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [520.992605] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [521.009388] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [521.009673] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [521.078735] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [521.078997] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [521.079228] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [521.295394] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [521.296387] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [528.693812] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [528.694770] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [528.695666] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [531.668040] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [531.668667] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [531.669553] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [531.670425] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [531.671276] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [531.672128] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [531.672951] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [531.673281] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [531.673456] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [531.673602] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [531.673746] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [531.673892] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [531.674037] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [531.674224] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [531.674398] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [531.674543] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [531.674688] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [531.674833] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [531.674979] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [531.675163] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [531.675376] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [531.675551] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [531.675695] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [531.675840] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [531.675985] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [531.676162] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [531.676318] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [531.676474] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [531.676630] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [531.676785] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [531.676941] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [531.677104] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [531.677260] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [531.677416] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [531.677572] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [531.677729] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [531.677884] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [531.678047] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [531.678203] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [531.678360] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [531.678542] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [531.678696] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [531.678893] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [531.679052] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [531.679203] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [531.679354] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [531.679506] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [531.679657] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [531.679832] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [531.679988] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [531.680170] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [531.680345] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [531.680503] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [531.680659] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [531.680814] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [531.680968] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [531.681206] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [531.681393] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [531.681537] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [531.681681] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [531.681854] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [531.682101] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [531.682266] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [531.692694] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [531.709921] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [531.710116] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [531.710301] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [531.710478] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [531.710637] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [531.710822] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [531.710974] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [531.711126] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [531.711276] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [531.711427] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [531.711575] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [531.711726] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [531.711883] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [531.712033] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [531.712184] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [531.712335] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [531.712486] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [531.712636] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [531.712794] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [531.712944] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [531.713095] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [531.713367] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [531.713600] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [531.714232] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [531.714434] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [531.714619] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [531.714822] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [531.714861] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:600:HDMI-A-1]
<7> [531.714948] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:600:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [531.715112] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [531.715255] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [531.715408] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI A/PHY A] [CRTC:171:pipe A]
<7> [531.715555] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [531.715718] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [531.715872] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [531.716024] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [531.716176] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [531.716328] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [531.716479] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [531.716631] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [531.716793] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [531.716944] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [531.717096] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [531.717249] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [531.717401] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [531.717552] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [531.717711] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [531.717863] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [531.718015] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [531.718167] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [531.718320] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [531.718472] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [531.718624] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [531.718784] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [531.718937] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [531.719088] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [531.719241] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [531.719392] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [531.719544] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [531.719702] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [531.719854] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [531.720007] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [531.720158] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [531.720310] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [531.720462] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [531.720614] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [531.720848] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [531.721000] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [531.721153] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [531.721306] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [531.721488] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [531.721638] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [531.721838] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [531.721985] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [531.722133] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [531.722280] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [531.722428] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [531.722576] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [531.722752] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [531.722905] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [531.723056] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [531.723225] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [531.723395] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [531.723546] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [531.723709] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [531.723865] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [531.724016] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [531.724167] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [531.724318] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [531.724469] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [531.724629] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [531.724780] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [531.724931] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [531.725082] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [531.725233] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [531.725385] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [531.725537] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [531.725695] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [531.725847] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [531.725999] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [531.726159] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [531.726319] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [531.726467] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [531.726654] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [531.726841] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [531.726985] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [531.727129] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [531.727273] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [531.727417] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [531.727563] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [531.727785] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [531.727945] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [531.728090] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [531.728233] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [531.728377] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [531.728520] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [531.728754] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [531.728940] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [531.729087] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [531.729245] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [531.729389] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [531.729532] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [531.729731] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [531.729942] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [531.730086] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [531.730232] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [531.730390] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [531.730539] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [531.730753] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [531.730913] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [531.731058] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [531.731201] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [531.731485] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [531.731689] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [531.731863] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [531.732008] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [531.732152] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [531.732296] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [531.732440] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [531.732641] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [531.732816] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [531.732960] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [531.733105] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [531.733248] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [531.733391] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [531.733534] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [531.733748] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [531.733915] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [531.734137] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [531.734461] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [531.734649] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [531.734874] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [531.735103] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [531.735718] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [531.735866] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [531.752709] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [531.752988] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [531.815043] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [531.815265] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [531.815455] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [532.038154] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [532.039036] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [539.538409] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [539.539360] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [539.540276] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<6> [539.988889] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-03-26 04:29:42