Results for igt@kms_flip@plain-flip-fb-recreate@b-vga1

Machine description: shard-snb5

Result: Fail

git-log-oneline i915_display_info20 igt_runner20 runtimes20 results20.json results20-i915-load.json i915_display_info_post_exec20 boot20 dmesg20

DetailValue
Duration 9.09 seconds
Hostname 
shard-snb5
Igt-Version 
IGT-Version: 1.30-g61cac4a58 (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16247-g23e0800ae084+ x86_64)
Out 
Starting dynamic subtest: B-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 16848.5us +- 2.280us accuracy 0.04%
vblank interval differs from modeline! expected 16665.6us, measured 16848us +- 2.280us, difference 182.9us (80.2 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-VGA1: FAIL (9.089s)
Err 
Starting dynamic subtest: B-VGA1
(kms_flip:2662) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2662) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-VGA1 failed.
**** DEBUG ****
(kms_flip:2662) DEBUG: flip ts/seq: last 113.792816/285, current 113.809624/286: elapsed=16810.0us expected=16848.5us +- 84.2us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.809624
last_received_ts = 113.809044
last_seq = 286
current_ts = 113.826416
current_received_ts = 113.825844
current_seq = 287
count = 207
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.809624/286, current 113.826416/287: elapsed=16791.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.826416
last_received_ts = 113.825844
last_seq = 287
current_ts = 113.843208
current_received_ts = 113.842651
current_seq = 288
count = 208
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.826416/287, current 113.843208/288: elapsed=16790.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.843208
last_received_ts = 113.842651
last_seq = 288
current_ts = 113.860001
current_received_ts = 113.859436
current_seq = 289
count = 209
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.843208/288, current 113.860001/289: elapsed=16795.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.860001
last_received_ts = 113.859436
last_seq = 289
current_ts = 113.876801
current_received_ts = 113.876228
current_seq = 290
count = 210
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.860001/289, current 113.876801/290: elapsed=16800.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.876801
last_received_ts = 113.876228
last_seq = 290
current_ts = 113.893600
current_received_ts = 113.893089
current_seq = 291
count = 211
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.876801/290, current 113.893600/291: elapsed=16800.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.893600
last_received_ts = 113.893089
last_seq = 291
current_ts = 113.910400
current_received_ts = 113.909843
current_seq = 292
count = 212
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.893600/291, current 113.910400/292: elapsed=16801.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.910400
last_received_ts = 113.909843
last_seq = 292
current_ts = 113.927193
current_received_ts = 113.926521
current_seq = 293
count = 213
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.910400/292, current 113.927193/293: elapsed=16793.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.927193
last_received_ts = 113.926521
last_seq = 293
current_ts = 113.944000
current_received_ts = 113.943367
current_seq = 294
count = 214
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.927193/293, current 113.944000/294: elapsed=16802.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.944000
last_received_ts = 113.943367
last_seq = 294
current_ts = 113.960785
current_received_ts = 113.960083
current_seq = 295
count = 215
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.944000/294, current 113.960785/295: elapsed=16791.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.960785
last_received_ts = 113.960083
last_seq = 295
current_ts = 113.977585
current_received_ts = 113.976875
current_seq = 296
count = 216
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.960785/295, current 113.977585/296: elapsed=16800.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.977585
last_received_ts = 113.976875
last_seq = 296
current_ts = 113.994392
current_received_ts = 113.993828
current_seq = 297
count = 217
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.977585/296, current 113.994392/297: elapsed=16802.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 113.994392
last_received_ts = 113.993828
last_seq = 297
current_ts = 114.011185
current_received_ts = 114.010643
current_seq = 298
count = 218
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 113.994392/297, current 114.011185/298: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.011185
last_received_ts = 114.010643
last_seq = 298
current_ts = 114.027985
current_received_ts = 114.027435
current_seq = 299
count = 219
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.011185/298, current 114.027985/299: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.027985
last_received_ts = 114.027435
last_seq = 299
current_ts = 114.044785
current_received_ts = 114.044220
current_seq = 300
count = 220
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.027985/299, current 114.044785/300: elapsed=16800.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.044785
last_received_ts = 114.044220
last_seq = 300
current_ts = 114.061569
current_received_ts = 114.060989
current_seq = 301
count = 221
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.044785/300, current 114.061569/301: elapsed=16790.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.061569
last_received_ts = 114.060989
last_seq = 301
current_ts = 114.078369
current_received_ts = 114.077820
current_seq = 302
count = 222
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.061569/301, current 114.078369/302: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.078369
last_received_ts = 114.077820
last_seq = 302
current_ts = 114.095169
current_received_ts = 114.094612
current_seq = 303
count = 223
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.078369/302, current 114.095169/303: elapsed=16798.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.095169
last_received_ts = 114.094612
last_seq = 303
current_ts = 114.111969
current_received_ts = 114.111412
current_seq = 304
count = 224
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.095169/303, current 114.111969/304: elapsed=16802.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.111969
last_received_ts = 114.111412
last_seq = 304
current_ts = 114.128761
current_received_ts = 114.128212
current_seq = 305
count = 225
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.111969/304, current 114.128761/305: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.128761
last_received_ts = 114.128212
last_seq = 305
current_ts = 114.145561
current_received_ts = 114.145050
current_seq = 306
count = 226
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.128761/305, current 114.145561/306: elapsed=16798.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.145561
last_received_ts = 114.145050
last_seq = 306
current_ts = 114.162361
current_received_ts = 114.161797
current_seq = 307
count = 227
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.145561/306, current 114.162361/307: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.162361
last_received_ts = 114.161797
last_seq = 307
current_ts = 114.179153
current_received_ts = 114.178596
current_seq = 308
count = 228
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.162361/307, current 114.179153/308: elapsed=16796.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.179153
last_received_ts = 114.178596
last_seq = 308
current_ts = 114.195953
current_received_ts = 114.195396
current_seq = 309
count = 229
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.179153/308, current 114.195953/309: elapsed=16799.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.195953
last_received_ts = 114.195396
last_seq = 309
current_ts = 114.212746
current_received_ts = 114.212151
current_seq = 310
count = 230
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.195953/309, current 114.212746/310: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.212746
last_received_ts = 114.212151
last_seq = 310
current_ts = 114.229546
current_received_ts = 114.228989
current_seq = 311
count = 231
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.212746/310, current 114.229546/311: elapsed=16799.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.229546
last_received_ts = 114.228989
last_seq = 311
current_ts = 114.246346
current_received_ts = 114.245758
current_seq = 312
count = 232
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.229546/311, current 114.246346/312: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.246346
last_received_ts = 114.245758
last_seq = 312
current_ts = 114.263138
current_received_ts = 114.262589
current_seq = 313
count = 233
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.246346/312, current 114.263138/313: elapsed=16798.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.263138
last_received_ts = 114.262589
last_seq = 313
current_ts = 114.279938
current_received_ts = 114.279350
current_seq = 314
count = 234
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.263138/313, current 114.279938/314: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.279938
last_received_ts = 114.279350
last_seq = 314
current_ts = 114.296738
current_received_ts = 114.296181
current_seq = 315
count = 235
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.279938/314, current 114.296738/315: elapsed=16807.0us expected=16848.5us +- 84.2us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.296738
last_received_ts = 114.296181
last_seq = 315
current_ts = 114.313530
current_received_ts = 114.312988
current_seq = 316
count = 236
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.296738/315, current 114.313530/316: elapsed=16791.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.313530
last_received_ts = 114.312988
last_seq = 316
current_ts = 114.330338
current_received_ts = 114.329788
current_seq = 317
count = 237
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.313530/316, current 114.330338/317: elapsed=16806.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.330338
last_received_ts = 114.329788
last_seq = 317
current_ts = 114.347130
current_received_ts = 114.346573
current_seq = 318
count = 238
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.330338/317, current 114.347130/318: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.347130
last_received_ts = 114.346573
last_seq = 318
current_ts = 114.363930
current_received_ts = 114.363380
current_seq = 319
count = 239
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.347130/318, current 114.363930/319: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.363930
last_received_ts = 114.363380
last_seq = 319
current_ts = 114.380737
current_received_ts = 114.380165
current_seq = 320
count = 240
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.363930/319, current 114.380737/320: elapsed=16807.0us expected=16848.5us +- 84.2us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.380737
last_received_ts = 114.380165
last_seq = 320
current_ts = 114.397522
current_received_ts = 114.396942
current_seq = 321
count = 241
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.380737/320, current 114.397522/321: elapsed=16787.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.397522
last_received_ts = 114.396942
last_seq = 321
current_ts = 114.414322
current_received_ts = 114.413780
current_seq = 322
count = 242
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.397522/321, current 114.414322/322: elapsed=16799.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.414322
last_received_ts = 114.413780
last_seq = 322
current_ts = 114.431122
current_received_ts = 114.430573
current_seq = 323
count = 243
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.414322/322, current 114.431122/323: elapsed=16801.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.431122
last_received_ts = 114.430573
last_seq = 323
current_ts = 114.447914
current_received_ts = 114.447365
current_seq = 324
count = 244
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.431122/323, current 114.447914/324: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.447914
last_received_ts = 114.447365
last_seq = 324
current_ts = 114.464706
current_received_ts = 114.464111
current_seq = 325
count = 245
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.447914/324, current 114.464706/325: elapsed=16793.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.464706
last_received_ts = 114.464111
last_seq = 325
current_ts = 114.481506
current_received_ts = 114.480972
current_seq = 326
count = 246
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.464706/325, current 114.481506/326: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.481506
last_received_ts = 114.480972
last_seq = 326
current_ts = 114.498306
current_received_ts = 114.497742
current_seq = 327
count = 247
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.481506/326, current 114.498306/327: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.498306
last_received_ts = 114.497742
last_seq = 327
current_ts = 114.515106
current_received_ts = 114.514496
current_seq = 328
count = 248
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.498306/327, current 114.515106/328: elapsed=16802.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.515106
last_received_ts = 114.514496
last_seq = 328
current_ts = 114.531906
current_received_ts = 114.531342
current_seq = 329
count = 249
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.515106/328, current 114.531906/329: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.531906
last_received_ts = 114.531342
last_seq = 329
current_ts = 114.548698
current_received_ts = 114.548134
current_seq = 330
count = 250
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.531906/329, current 114.548698/330: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.548698
last_received_ts = 114.548134
last_seq = 330
current_ts = 114.565491
current_received_ts = 114.564949
current_seq = 331
count = 251
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.548698/330, current 114.565491/331: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.565491
last_received_ts = 114.564949
last_seq = 331
current_ts = 114.582306
current_received_ts = 114.581696
current_seq = 332
count = 252
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.565491/331, current 114.582306/332: elapsed=16810.0us expected=16848.5us +- 84.2us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.582306
last_received_ts = 114.581696
last_seq = 332
current_ts = 114.599091
current_received_ts = 114.598534
current_seq = 333
count = 253
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.582306/332, current 114.599091/333: elapsed=16787.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.599091
last_received_ts = 114.598534
last_seq = 333
current_ts = 114.615891
current_received_ts = 114.615341
current_seq = 334
count = 254
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.599091/333, current 114.615891/334: elapsed=16800.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.615891
last_received_ts = 114.615341
last_seq = 334
current_ts = 114.632698
current_received_ts = 114.632103
current_seq = 335
count = 255
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.615891/334, current 114.632698/335: elapsed=16805.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.632698
last_received_ts = 114.632103
last_seq = 335
current_ts = 114.649483
current_received_ts = 114.648895
current_seq = 336
count = 256
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.632698/335, current 114.649483/336: elapsed=16783.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.649483
last_received_ts = 114.648895
last_seq = 336
current_ts = 114.666283
current_received_ts = 114.665741
current_seq = 337
count = 257
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.649483/336, current 114.666283/337: elapsed=16806.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.666283
last_received_ts = 114.665741
last_seq = 337
current_ts = 114.683075
current_received_ts = 114.682503
current_seq = 338
count = 258
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.666283/337, current 114.683075/338: elapsed=16787.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.683075
last_received_ts = 114.682503
last_seq = 338
current_ts = 114.699852
current_received_ts = 114.699280
current_seq = 339
count = 259
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.683075/338, current 114.699852/339: elapsed=16782.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.699852
last_received_ts = 114.699280
last_seq = 339
current_ts = 114.716644
current_received_ts = 114.716110
current_seq = 340
count = 260
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.699852/339, current 114.716644/340: elapsed=16790.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.716644
last_received_ts = 114.716110
last_seq = 340
current_ts = 114.733429
current_received_ts = 114.732872
current_seq = 341
count = 261
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.716644/340, current 114.733429/341: elapsed=16788.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.733429
last_received_ts = 114.732872
last_seq = 341
current_ts = 114.750229
current_received_ts = 114.749626
current_seq = 342
count = 262
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.733429/341, current 114.750229/342: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.750229
last_received_ts = 114.749626
last_seq = 342
current_ts = 114.767006
current_received_ts = 114.766411
current_seq = 343
count = 263
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.750229/342, current 114.767006/343: elapsed=16779.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.767006
last_received_ts = 114.766411
last_seq = 343
current_ts = 114.783798
current_received_ts = 114.783165
current_seq = 344
count = 264
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.767006/343, current 114.783798/344: elapsed=16790.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.783798
last_received_ts = 114.783165
last_seq = 344
current_ts = 114.800583
current_received_ts = 114.800056
current_seq = 345
count = 265
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.783798/344, current 114.800583/345: elapsed=16788.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.800583
last_received_ts = 114.800056
last_seq = 345
current_ts = 114.817390
current_received_ts = 114.816803
current_seq = 346
count = 266
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.800583/345, current 114.817390/346: elapsed=16804.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.817390
last_received_ts = 114.816803
last_seq = 346
current_ts = 114.834167
current_received_ts = 114.833580
current_seq = 347
count = 267
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.817390/346, current 114.834167/347: elapsed=16778.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.834167
last_received_ts = 114.833580
last_seq = 347
current_ts = 114.850960
current_received_ts = 114.850418
current_seq = 348
count = 268
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.834167/347, current 114.850960/348: elapsed=16791.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.850960
last_received_ts = 114.850418
last_seq = 348
current_ts = 114.867744
current_received_ts = 114.867172
current_seq = 349
count = 269
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.850960/348, current 114.867744/349: elapsed=16785.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.867744
last_received_ts = 114.867172
last_seq = 349
current_ts = 114.884529
current_received_ts = 114.883972
current_seq = 350
count = 270
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.867744/349, current 114.884529/350: elapsed=16785.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.884529
last_received_ts = 114.883972
last_seq = 350
current_ts = 114.901329
current_received_ts = 114.900742
current_seq = 351
count = 271
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.884529/350, current 114.901329/351: elapsed=16799.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.901329
last_received_ts = 114.900742
last_seq = 351
current_ts = 114.918106
current_received_ts = 114.917542
current_seq = 352
count = 272
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.901329/351, current 114.918106/352: elapsed=16777.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.918106
last_received_ts = 114.917542
last_seq = 352
current_ts = 114.934898
current_received_ts = 114.934341
current_seq = 353
count = 273
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.918106/352, current 114.934898/353: elapsed=16793.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.934898
last_received_ts = 114.934341
last_seq = 353
current_ts = 114.951683
current_received_ts = 114.951141
current_seq = 354
count = 274
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.934898/353, current 114.951683/354: elapsed=16785.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.951683
last_received_ts = 114.951141
last_seq = 354
current_ts = 114.968475
current_received_ts = 114.967880
current_seq = 355
count = 275
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.951683/354, current 114.968475/355: elapsed=16792.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.968475
last_received_ts = 114.967880
last_seq = 355
current_ts = 114.985260
current_received_ts = 114.984688
current_seq = 356
count = 276
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.968475/355, current 114.985260/356: elapsed=16783.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 114.985260
last_received_ts = 114.984688
last_seq = 356
current_ts = 115.002052
current_received_ts = 115.001511
current_seq = 357
count = 277
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 114.985260/356, current 115.002052/357: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.002052
last_received_ts = 115.001511
last_seq = 357
current_ts = 115.018837
current_received_ts = 115.018211
current_seq = 358
count = 278
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.002052/357, current 115.018837/358: elapsed=16784.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.018837
last_received_ts = 115.018211
last_seq = 358
current_ts = 115.035622
current_received_ts = 115.035072
current_seq = 359
count = 279
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.018837/358, current 115.035622/359: elapsed=16788.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.035622
last_received_ts = 115.035072
last_seq = 359
current_ts = 115.052429
current_received_ts = 115.051865
current_seq = 360
count = 280
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.035622/359, current 115.052429/360: elapsed=16803.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.052429
last_received_ts = 115.051865
last_seq = 360
current_ts = 115.069214
current_received_ts = 115.068588
current_seq = 361
count = 281
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.052429/360, current 115.069214/361: elapsed=16785.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.069214
last_received_ts = 115.068588
last_seq = 361
current_ts = 115.085991
current_received_ts = 115.085442
current_seq = 362
count = 282
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.069214/361, current 115.085991/362: elapsed=16782.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.085991
last_received_ts = 115.085442
last_seq = 362
current_ts = 115.102783
current_received_ts = 115.102196
current_seq = 363
count = 283
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.085991/362, current 115.102783/363: elapsed=16787.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.102783
last_received_ts = 115.102196
last_seq = 363
current_ts = 115.119583
current_received_ts = 115.119034
current_seq = 364
count = 284
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.102783/363, current 115.119583/364: elapsed=16800.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.119583
last_received_ts = 115.119034
last_seq = 364
current_ts = 115.136353
current_received_ts = 115.135803
current_seq = 365
count = 285
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.119583/364, current 115.136353/365: elapsed=16773.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.136353
last_received_ts = 115.135803
last_seq = 365
current_ts = 115.153145
current_received_ts = 115.152557
current_seq = 366
count = 286
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.136353/365, current 115.153145/366: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.153145
last_received_ts = 115.152557
last_seq = 366
current_ts = 115.169945
current_received_ts = 115.169327
current_seq = 367
count = 287
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.153145/366, current 115.169945/367: elapsed=16793.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.169945
last_received_ts = 115.169327
last_seq = 367
current_ts = 115.186722
current_received_ts = 115.186134
current_seq = 368
count = 288
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.169945/367, current 115.186722/368: elapsed=16781.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.186722
last_received_ts = 115.186134
last_seq = 368
current_ts = 115.203506
current_received_ts = 115.202942
current_seq = 369
count = 289
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.186722/368, current 115.203506/369: elapsed=16784.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.203506
last_received_ts = 115.202942
last_seq = 369
current_ts = 115.220306
current_received_ts = 115.219757
current_seq = 370
count = 290
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.203506/369, current 115.220306/370: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.220306
last_received_ts = 115.219757
last_seq = 370
current_ts = 115.237083
current_received_ts = 115.236542
current_seq = 371
count = 291
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.220306/370, current 115.237083/371: elapsed=16781.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.237083
last_received_ts = 115.236542
last_seq = 371
current_ts = 115.253876
current_received_ts = 115.253281
current_seq = 372
count = 292
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.237083/371, current 115.253876/372: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.253876
last_received_ts = 115.253281
last_seq = 372
current_ts = 115.270668
current_received_ts = 115.270111
current_seq = 373
count = 293
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.253876/372, current 115.270668/373: elapsed=16789.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.270668
last_received_ts = 115.270111
last_seq = 373
current_ts = 115.287453
current_received_ts = 115.286903
current_seq = 374
count = 294
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.270668/373, current 115.287453/374: elapsed=16788.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.287453
last_received_ts = 115.286903
last_seq = 374
current_ts = 115.304237
current_received_ts = 115.303680
current_seq = 375
count = 295
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.287453/374, current 115.304237/375: elapsed=16783.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.304237
last_received_ts = 115.303680
last_seq = 375
current_ts = 115.321030
current_received_ts = 115.320435
current_seq = 376
count = 296
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.304237/375, current 115.321030/376: elapsed=16791.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.321030
last_received_ts = 115.320435
last_seq = 376
current_ts = 115.337822
current_received_ts = 115.337273
current_seq = 377
count = 297
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.321030/376, current 115.337822/377: elapsed=16791.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.337822
last_received_ts = 115.337273
last_seq = 377
current_ts = 115.354607
current_received_ts = 115.354073
current_seq = 378
count = 298
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.337822/377, current 115.354607/378: elapsed=16788.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.354607
last_received_ts = 115.354073
last_seq = 378
current_ts = 115.371407
current_received_ts = 115.370827
current_seq = 379
count = 299
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.354607/378, current 115.371407/379: elapsed=16797.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.371407
last_received_ts = 115.370827
last_seq = 379
current_ts = 115.388184
current_received_ts = 115.387627
current_seq = 380
count = 300
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.371407/379, current 115.388184/380: elapsed=16776.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.388184
last_received_ts = 115.387627
last_seq = 380
current_ts = 115.404976
current_received_ts = 115.404373
current_seq = 381
count = 301
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.388184/380, current 115.404976/381: elapsed=16794.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.404976
last_received_ts = 115.404373
last_seq = 381
current_ts = 115.421768
current_received_ts = 115.421196
current_seq = 382
count = 302
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.404976/381, current 115.421768/382: elapsed=16796.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.421768
last_received_ts = 115.421196
last_seq = 382
current_ts = 115.438545
current_received_ts = 115.437988
current_seq = 383
count = 303
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.421768/382, current 115.438545/383: elapsed=16776.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.438545
last_received_ts = 115.437988
last_seq = 383
current_ts = 115.455338
current_received_ts = 115.454773
current_seq = 384
count = 304
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.438545/383, current 115.455338/384: elapsed=16791.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.455338
last_received_ts = 115.454773
last_seq = 384
current_ts = 115.472130
current_received_ts = 115.471596
current_seq = 385
count = 305
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.455338/384, current 115.472130/385: elapsed=16789.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.472130
last_received_ts = 115.471596
last_seq = 385
current_ts = 115.488914
current_received_ts = 115.488335
current_seq = 386
count = 306
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.472130/385, current 115.488914/386: elapsed=16790.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.488914
last_received_ts = 115.488335
last_seq = 386
current_ts = 115.505699
current_received_ts = 115.505165
current_seq = 387
count = 307
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.488914/386, current 115.505699/387: elapsed=16783.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.505699
last_received_ts = 115.505165
last_seq = 387
current_ts = 115.522491
current_received_ts = 115.521942
current_seq = 388
count = 308
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.505699/387, current 115.522491/388: elapsed=16790.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.522491
last_received_ts = 115.521942
last_seq = 388
current_ts = 115.539291
current_received_ts = 115.538681
current_seq = 389
count = 309
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.522491/388, current 115.539291/389: elapsed=16801.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.539291
last_received_ts = 115.538681
last_seq = 389
current_ts = 115.556061
current_received_ts = 115.555519
current_seq = 390
count = 310
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.539291/389, current 115.556061/390: elapsed=16773.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.556061
last_received_ts = 115.555519
last_seq = 390
current_ts = 115.572868
current_received_ts = 115.572273
current_seq = 391
count = 311
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.556061/390, current 115.572868/391: elapsed=16805.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.572868
last_received_ts = 115.572273
last_seq = 391
current_ts = 115.589653
current_received_ts = 115.589073
current_seq = 392
count = 312
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.572868/391, current 115.589653/392: elapsed=16786.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.589653
last_received_ts = 115.589073
last_seq = 392
current_ts = 115.606430
current_received_ts = 115.605881
current_seq = 393
count = 313
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.589653/392, current 115.606430/393: elapsed=16776.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.606430
last_received_ts = 115.605881
last_seq = 393
current_ts = 115.623222
current_received_ts = 115.622673
current_seq = 394
count = 314
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.606430/393, current 115.623222/394: elapsed=16788.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.623222
last_received_ts = 115.622673
last_seq = 394
current_ts = 115.640007
current_received_ts = 115.639465
current_seq = 395
count = 315
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.623222/394, current 115.640007/395: elapsed=16787.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.640007
last_received_ts = 115.639465
last_seq = 395
current_ts = 115.656792
current_received_ts = 115.656189
current_seq = 396
count = 316
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.640007/395, current 115.656792/396: elapsed=16789.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.656792
last_received_ts = 115.656189
last_seq = 396
current_ts = 115.673592
current_received_ts = 115.672989
current_seq = 397
count = 317
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.656792/396, current 115.673592/397: elapsed=16795.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.673592
last_received_ts = 115.672989
last_seq = 397
current_ts = 115.690369
current_received_ts = 115.689774
current_seq = 398
count = 318
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.673592/397, current 115.690369/398: elapsed=16776.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.690369
last_received_ts = 115.689774
last_seq = 398
current_ts = 115.707146
current_received_ts = 115.706505
current_seq = 399
count = 319
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.690369/398, current 115.707146/399: elapsed=16782.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.707146
last_received_ts = 115.706505
last_seq = 399
current_ts = 115.723923
current_received_ts = 115.723335
current_seq = 400
count = 320
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.707146/399, current 115.723923/400: elapsed=16778.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.723923
last_received_ts = 115.723335
last_seq = 400
current_ts = 115.740707
current_received_ts = 115.740120
current_seq = 401
count = 321
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.723923/400, current 115.740707/401: elapsed=16783.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.740707
last_received_ts = 115.740120
last_seq = 401
current_ts = 115.757484
current_received_ts = 115.756897
current_seq = 402
count = 322
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.740707/401, current 115.757484/402: elapsed=16779.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.757484
last_received_ts = 115.756897
last_seq = 402
current_ts = 115.774261
current_received_ts = 115.773643
current_seq = 403
count = 323
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.757484/402, current 115.774261/403: elapsed=16774.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.774261
last_received_ts = 115.773643
last_seq = 403
current_ts = 115.791046
current_received_ts = 115.790459
current_seq = 404
count = 324
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.774261/403, current 115.791046/404: elapsed=16786.0us expected=16848.5us +- 84.2us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.791046
last_received_ts = 115.790459
last_seq = 404
current_ts = 115.807838
current_received_ts = 115.807167
current_seq = 405
count = 325
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.791046/404, current 115.807838/405: elapsed=16792.0us expected=16848.5us +- 84.2us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 115.807838
last_received_ts = 115.807167
last_seq = 405
current_ts = 115.824600
current_received_ts = 115.823990
current_seq = 406
count = 326
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 115.807838/405, current 115.824600/406: elapsed=16764.0us expected=16848.5us +- 84.2us, error 0.5%
(kms_flip:2662) DEBUG: inconsistent flip ts/seq: last 115.807838/405, current 115.824600/406: elapsed=16764.0us expected=16848.5us
(kms_flip:2662) DEBUG: expected 161460, counted 326, encoder type 1
(kms_flip:2662) DEBUG: dropped frames, expected 161460, counted 326, encoder type 1
(kms_flip:2662) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2662) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2662) igt_core-INFO: Stack trace:
(kms_flip:2662) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2662) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2662) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2662) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2662) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2662) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2662) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2662) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-VGA1: FAIL (9.089s)
Dmesg
<6> [109.634304] [IGT] kms_flip: starting dynamic subtest B-VGA1
<7> [109.634890] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [109.635065] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [109.671862] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [109.672109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [109.672510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [109.672870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [109.673357] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [109.673714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [109.674096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [109.674453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [109.674807] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [109.675192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [109.675547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [109.675920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [109.676210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [109.676434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [109.676658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [109.676893] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [109.677200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [109.677506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [109.677812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [109.678136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [109.678395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [109.678607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [109.678821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [109.679057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [109.679313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [109.679518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [109.679723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [109.679971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [109.680219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [109.680425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [109.680629] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [109.680833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [109.681093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [109.681350] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [109.681555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [109.681760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [109.682007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [109.682254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [109.682460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [109.682664] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [109.682921] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [109.683171] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [109.683432] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [109.683684] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [109.683943] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [109.684199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [109.684535] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [109.699018] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [109.699448] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [109.700024] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [109.715932] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:47:pipe A]
<7> [109.716414] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [109.717088] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [109.717441] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [109.717902] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [109.718298] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [109.718808] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [109.719117] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [109.719220] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [109.719245] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [109.719329] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:64:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [109.719587] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [109.719903] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 1
<7> [109.720221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [109.720564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [109.720888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [109.721214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [109.721550] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [109.721873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [109.722203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [109.722539] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [109.722871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [109.723186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [109.723523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [109.723852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [109.724171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [109.724489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [109.724834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [109.725170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [109.725487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [109.725833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [109.726168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [109.726505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [109.726848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [109.727166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [109.727516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [109.727844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [109.728162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [109.728518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [109.728850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [109.729170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [109.729516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [109.729851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [109.730167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [109.730533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [109.730869] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [109.731182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [109.731484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [109.731821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in dpll_hw_state
<7> [109.732056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [109.732262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [109.732511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [109.732798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [109.733016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [109.733221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [109.733506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [109.733833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [109.734152] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] fastset requirement not met, forcing full modeset
<7> [109.734712] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:48:primary B] min cdclk (64800 kHz) > [CRTC:63:pipe B] min cdclk (0 kHz)
<7> [109.735042] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [109.735362] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [109.735670] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:63:pipe B] allocated PCH DPLL A
<7> [109.735899] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:63:pipe B] reserving PCH DPLL A
<7> [109.736118] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:63:pipe B] enable: yes [modeset]
<7> [109.736353] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [109.736728] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [109.736702] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [109.737035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [109.737264] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [109.737488] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [109.737749] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [109.738076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [109.738371] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [109.738619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [109.738830] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [109.739041] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [109.739332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [109.739675] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [109.739911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [109.740124] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [109.740421] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [109.740748] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [109.740976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [109.741182] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [109.741497] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [109.741810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [109.742027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [109.742232] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [109.742569] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [109.742873] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [109.743090] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [109.743294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [109.743561] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [109.743877] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:48:primary B] fb: [FB:83] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [109.744192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [109.744449] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [109.745545] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [109.745799] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [109.746111] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [109.746368] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [109.747152] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [109.748272] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [109.748522] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [109.749476] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [109.749723] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [109.749925] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [109.750145] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:63:pipe B]
<7> [109.750411] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [109.802526] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [109.803065] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:63:pipe B]
<7> [110.103567] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.120226] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.136912] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.153669] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.170416] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.187081] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.203833] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.220546] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.237280] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.253949] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.270699] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.287435] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.304148] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.320850] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.337577] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.354257] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.370971] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.387686] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.404397] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.421155] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.437790] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.454491] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.471111] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.487843] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.504575] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.521275] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.538179] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.554874] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.571569] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.588341] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.605055] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.621791] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.638489] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.655167] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.671923] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.688548] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.705331] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.722060] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.738775] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.755444] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.772170] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.788818] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.805599] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.822344] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.839024] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.855779] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.872525] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.889246] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.905928] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.922685] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.939395] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [110.956077] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [110.972828] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [110.989543] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.006260] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.022997] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.039692] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.056406] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.073131] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.089842] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.106519] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.123297] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.139950] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.156704] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.173348] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.190187] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.206921] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.223597] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.240377] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.257055] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.273731] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.290432] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.307091] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.323852] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.340593] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.357264] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.374063] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.390747] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.407492] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.424227] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.440926] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.457619] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.474328] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.491047] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.507794] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.524600] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.541235] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.557906] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.574595] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.591369] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.608053] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.624764] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.641442] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.658201] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.674818] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.691652] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.708387] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.725079] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.741786] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.758503] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.775171] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.792022] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.808660] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.825393] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.842059] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.858775] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.875492] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.892259] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.908947] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.925644] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.942403] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [111.959059] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [111.975838] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [111.992540] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.009265] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.025994] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.042634] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.059419] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.076158] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.092830] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.109538] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.126297] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.143014] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.159729] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.176461] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.193141] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.209855] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.226582] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.243314] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.260044] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.276702] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.293460] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.310183] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.326899] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.343616] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.360372] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.377051] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.393754] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.410463] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.427165] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.443942] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.460629] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.477358] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.494005] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.510796] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.527602] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.544203] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.560877] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.577597] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.594298] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.611046] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.627726] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.644517] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.661140] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.677910] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.694653] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.711421] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.728074] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.744743] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.761355] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.778236] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.794941] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.811603] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.828385] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.845117] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.861745] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.878535] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.895245] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.911962] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.928713] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.945416] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [112.962184] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [112.978821] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [112.995511] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.012268] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.028998] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.045715] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.062474] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.079154] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.095864] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.112484] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.129251] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.145972] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.162687] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.179392] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.196081] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.212802] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.229624] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.246331] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.263024] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.279758] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.296453] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.313189] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.329899] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.346613] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.363328] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.379966] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.396785] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.413467] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.430213] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.446929] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.463649] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.480385] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.497096] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.513775] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.530487] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.547162] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.563919] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.580651] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.597390] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.614089] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.630800] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.647595] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.664237] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.680814] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.697625] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.714196] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.730911] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.747824] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.764550] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.781251] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.797984] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.814767] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.831427] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.848144] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.864849] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.881572] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.898380] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.915000] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.931714] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.948433] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [113.965106] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [113.981935] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [113.998565] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.015294] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.031969] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.048722] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.065492] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.082178] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.098873] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.115591] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.132307] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.149048] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.165759] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.182478] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.199183] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.215858] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.232689] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.249327] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.265997] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.282759] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.299483] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.316234] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.332883] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.349635] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.366350] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.383049] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.399751] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.416512] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.433182] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.449903] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.466657] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.483360] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.500041] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.516761] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.533438] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.550258] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.566935] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.583637] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.600411] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.617114] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.633855] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.650527] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.667246] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.683959] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.700692] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.717372] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.734108] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.750854] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.767492] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.784296] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.801001] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.817650] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.834432] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.851130] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.867892] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.884579] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.901269] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.917957] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.934693] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [114.951463] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [114.968165] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [114.984886] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.001554] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.018308] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.035035] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.051742] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.068420] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.085186] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.101918] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.118595] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.135329] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.152000] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.168747] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.185497] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.202190] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.218985] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.235644] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.252408] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.269144] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.285777] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.302545] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.319217] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.335942] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.352729] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.369413] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.386134] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.402785] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.419551] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.436245] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.452870] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.469633] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.486353] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.503068] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [115.519754] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [115.536501] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [115.553151] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<6> [118.723457] [IGT] kms_flip: finished subtest B-VGA1, FAIL
Created at 2025-03-09 10:19:50