[44.781726] Initializing watchdogs
[44.781820]   /dev/watchdog0
[44.796499] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0412 Intel Haswell (Gen7) Xeon E3-1200 v3/4th Gen Core Processor Integrated Graphics Controller (ThinkCentre M83)
[44.800904] [001/162] (960s left) i915_module_load (load)
[45.121358] Starting subtest: load
[46.553222] Subtest load: SUCCESS (1.431s)
[46.587276] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[46.589050] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[46.591815] [002/162] (958s left) core_auth (basic-auth)
[46.639242] Starting subtest: basic-auth
[46.640276] Subtest basic-auth: SUCCESS (0.000s)
[46.741684] [003/162] (958s left) core_debugfs (read-all-entries)
[46.786384] Starting subtest: read-all-entries
[46.854022] Subtest read-all-entries: SUCCESS (0.067s)
[46.957556] [004/162] (958s left) core_sysfs (read-all-entries)
[47.001110] Starting subtest: read-all-entries
[47.004281] Subtest read-all-entries: SUCCESS (0.004s)
[47.108652] [005/162] (958s left) fbdev (eof)
[47.154050] Starting subtest: eof
[47.155256] Subtest eof: SUCCESS (0.000s)
[47.258081] [006/162] (958s left) fbdev (info)
[47.298214] Starting subtest: info
[47.299458] Subtest info: SUCCESS (0.000s)
[47.393539] [007/162] (958s left) fbdev (nullptr)
[47.435709] Starting subtest: nullptr
[47.436990] Subtest nullptr: SUCCESS (0.000s)
[47.527383] [008/162] (958s left) fbdev (read)
[47.587659] Starting subtest: read
[47.667973] Subtest read: SUCCESS (0.081s)
[47.756678] [009/162] (957s left) fbdev (write)
[47.803742] Starting subtest: write
[47.844377] Subtest write: SUCCESS (0.042s)
[47.944715] [010/162] (957s left) gem_basic (bad-close)
[47.988100] Starting subtest: bad-close
[47.990202] Subtest bad-close: SUCCESS (0.000s)
[48.095983] [011/162] (957s left) gem_basic (create-close)
[48.136604] Starting subtest: create-close
[48.138747] Subtest create-close: SUCCESS (0.000s)
[48.258878] [012/162] (957s left) gem_basic (create-fd-close)
[48.302571] Starting subtest: create-fd-close
[48.304823] Subtest create-fd-close: SUCCESS (0.000s)
[48.426342] [013/162] (957s left) gem_busy (busy@all-engines)
[48.483355] Starting subtest: busy
[48.484356] Starting dynamic subtest: all-engines
[48.508555] Dynamic subtest all-engines: SUCCESS (0.026s)
[48.509758] Subtest busy: SUCCESS (0.027s)
[48.876808] [014/162] (956s left) gem_close_race (basic-process)
[48.935477] Starting subtest: basic-process
[48.975723] Subtest basic-process: SUCCESS (0.040s)
[49.188743] [015/162] (956s left) gem_close_race (basic-threads)
[49.247720] Starting subtest: basic-threads
[50.425037] Subtest basic-threads: SUCCESS (1.177s)
[50.644138] [016/162] (955s left) gem_ctx_create (basic)
[50.696234] Starting subtest: basic
[50.697238] Subtest basic: SUCCESS (0.000s)
[50.888628] [017/162] (955s left) gem_ctx_create (basic-files)
[50.939784] Starting subtest: basic-files
[52.953111] Subtest basic-files: SUCCESS (2.013s)
[53.224156] [018/162] (952s left) gem_ctx_exec (basic)
[53.269691] Starting subtest: basic
[53.272365] Subtest basic: SUCCESS (0.003s)
[53.408161] [019/162] (952s left) gem_exec_basic (basic)
[53.455406] Starting subtest: basic
[53.456471] Starting dynamic subtest: rcs0-smem
[53.457519] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[53.465983] Starting dynamic subtest: bcs0-smem
[53.467190] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[53.478329] Starting dynamic subtest: vcs0-smem
[53.479394] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[53.487471] Starting dynamic subtest: vecs0-smem
[53.488494] Dynamic subtest vecs0-smem: SUCCESS (0.000s)
[53.489593] Subtest basic: SUCCESS (0.003s)
[53.679139] [020/162] (952s left) gem_exec_create (basic)
[53.732377] Starting subtest: basic
[53.733304] Starting dynamic subtest: smem
[55.790215] Dynamic subtest smem: SUCCESS (2.058s)
[55.791496] Subtest basic: SUCCESS (2.059s)
[56.008586] [021/162] (950s left) gem_exec_fence (basic-busy)
[56.178278] Starting subtest: basic-busy
[56.179464] Starting dynamic subtest: rcs0
[56.217400] Dynamic subtest rcs0: SUCCESS (0.039s)
[56.227167] Starting dynamic subtest: bcs0
[56.253423] Dynamic subtest bcs0: SUCCESS (0.035s)
[56.265900] Starting dynamic subtest: vcs0
[56.294229] Dynamic subtest vcs0: SUCCESS (0.040s)
[56.303576] Starting dynamic subtest: vecs0
[56.326257] Dynamic subtest vecs0: SUCCESS (0.031s)
[56.327355] Subtest basic-busy: SUCCESS (0.149s)
[56.842110] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[56.844127] [022/162] (949s left) gem_exec_fence (basic-wait)
[57.011340] Starting subtest: basic-wait
[57.012461] Starting dynamic subtest: rcs0
[57.049499] Dynamic subtest rcs0: SUCCESS (0.036s)
[57.062241] Starting dynamic subtest: bcs0
[57.101365] Dynamic subtest bcs0: SUCCESS (0.051s)
[57.110203] Starting dynamic subtest: vcs0
[57.148131] Dynamic subtest vcs0: SUCCESS (0.047s)
[57.157864] Starting dynamic subtest: vecs0
[57.188951] Dynamic subtest vecs0: SUCCESS (0.039s)
[57.190215] Subtest basic-wait: SUCCESS (0.178s)
[57.677133] [023/162] (948s left) gem_exec_fence (basic-await)
[57.845492] Starting subtest: basic-await
[57.846571] Starting dynamic subtest: rcs0
[57.922101] Dynamic subtest rcs0: SUCCESS (0.075s)
[57.931181] Starting dynamic subtest: bcs0
[57.996705] Dynamic subtest bcs0: SUCCESS (0.073s)
[58.006165] Starting dynamic subtest: vcs0
[58.071344] Dynamic subtest vcs0: SUCCESS (0.073s)
[58.081163] Starting dynamic subtest: vecs0
[58.146362] Dynamic subtest vecs0: SUCCESS (0.074s)
[58.147502] Subtest basic-await: SUCCESS (0.301s)
[58.664520] [024/162] (947s left) gem_exec_fence (nb-await)
[58.829182] Starting subtest: nb-await
[58.830903] Starting dynamic subtest: rcs0
[58.884898] Dynamic subtest rcs0: SUCCESS (0.057s)
[58.893359] Starting dynamic subtest: bcs0
[58.939478] Dynamic subtest bcs0: SUCCESS (0.053s)
[58.950743] Starting dynamic subtest: vcs0
[58.995261] Dynamic subtest vcs0: SUCCESS (0.055s)
[59.007956] Starting dynamic subtest: vecs0
[59.052670] Dynamic subtest vecs0: SUCCESS (0.056s)
[59.053588] Subtest nb-await: SUCCESS (0.225s)
[59.544389] [025/162] (946s left) gem_exec_gttfill (basic)
[59.598076] Starting subtest: basic
[63.047891] Subtest basic: SUCCESS (3.449s)
[63.416244] [026/162] (943s left) gem_exec_parallel (engines)
[63.602554] Starting subtest: engines
[63.604145] Starting dynamic subtest: basic
[65.716165] Dynamic subtest basic: SUCCESS (2.113s)
[65.732397] Starting dynamic subtest: contexts
[67.377451] Dynamic subtest contexts: SUCCESS (1.662s)
[67.389780] Starting dynamic subtest: fds
[69.106758] Dynamic subtest fds: SUCCESS (1.729s)
[69.117861] Starting dynamic subtest: userptr
[71.070854] Dynamic subtest userptr: SUCCESS (1.963s)
[71.071933] Subtest engines: SUCCESS (7.471s)
[71.277971] [027/162] (935s left) gem_exec_store (basic)
[71.330836] Starting subtest: basic
[71.372584] Subtest basic: SUCCESS (0.041s)
[71.593273] [028/162] (934s left) gem_flink_basic (bad-flink)
[71.637505] Starting subtest: bad-flink
[71.638603] Subtest bad-flink: SUCCESS (0.000s)
[71.744473] [029/162] (934s left) gem_flink_basic (bad-open)
[71.789251] Starting subtest: bad-open
[71.790923] Subtest bad-open: SUCCESS (0.000s)
[71.895147] [030/162] (934s left) gem_flink_basic (basic)
[71.937423] Starting subtest: basic
[71.938413] Subtest basic: SUCCESS (0.000s)
[72.077764] [031/162] (934s left) gem_flink_basic (double-flink)
[72.120493] Starting subtest: double-flink
[72.121518] Subtest double-flink: SUCCESS (0.000s)
[72.241784] [032/162] (934s left) gem_flink_basic (flink-lifetime)
[72.285444] Starting subtest: flink-lifetime
[72.286703] Subtest flink-lifetime: SUCCESS (0.001s)
[72.410921] [033/162] (934s left) gem_huc_copy (huc-copy)
[72.457375] Subtest huc-copy: SKIP (0.000s)
[72.500265] [034/162] (934s left) gem_linear_blits (basic)
[72.609549] Starting subtest: basic
[72.618840] Subtest basic: SUCCESS (0.009s)
[72.752521] [035/162] (934s left) gem_mmap (basic)
[72.873291] Starting subtest: basic
[72.878638] Subtest basic: SUCCESS (0.000s)
[72.993101] [036/162] (933s left) gem_mmap_gtt (basic)
[73.037422] Starting subtest: basic
[73.038492] Subtest basic: SUCCESS (0.001s)
[73.160180] [037/162] (933s left) gem_render_linear_blits (basic)
[73.206453] Starting subtest: basic
[73.225116] Subtest basic: SUCCESS (0.019s)
[73.302452] [038/162] (933s left) gem_render_tiled_blits (basic)
[73.348349] Starting subtest: basic
[73.371949] Subtest basic: SUCCESS (0.024s)
[73.436671] [039/162] (933s left) gem_ringfill (basic-all)
[73.663248] Starting subtest: basic-all
[74.770435] Subtest basic-all: SUCCESS (1.108s)
[74.907055] [040/162] (932s left) gem_softpin (allocator-basic)
[74.954489] Subtest allocator-basic: SKIP (0.000s)
[75.055603] [041/162] (932s left) gem_softpin (allocator-basic-reserve)
[75.103629] Subtest allocator-basic-reserve: SKIP (0.000s)
[75.209705] [042/162] (932s left) gem_softpin (safe-alignment)
[75.258435] Starting subtest: safe-alignment
[75.266865] Subtest safe-alignment: SUCCESS (0.010s)
[75.421741] [043/162] (931s left) gem_sync (basic-all)
[75.476259] Starting subtest: basic-all
[77.654076] Subtest basic-all: SUCCESS (2.177s)
[77.872125] [044/162] (929s left) gem_sync (basic-each)
[77.924709] Starting subtest: basic-each
[80.049722] Subtest basic-each: SUCCESS (2.125s)
[80.254455] [045/162] (927s left) gem_tiled_blits (basic)
[80.356692] Starting subtest: basic
[80.368888] Subtest basic: SUCCESS (0.012s)
[80.500446] [046/162] (926s left) gem_tiled_fence_blits (basic)
[80.677132] Starting subtest: basic
[80.685181] Subtest basic: SUCCESS (0.008s)
[80.848618] [047/162] (926s left) gem_tiled_pread_basic
[81.152665] [048/162] (926s left) gem_wait (busy@all-engines)
[81.210336] Starting subtest: busy
[81.211415] Starting dynamic subtest: all-engines
[81.728748] Dynamic subtest all-engines: SUCCESS (0.518s)
[81.729966] Subtest busy: SUCCESS (0.519s)
[81.953356] [049/162] (925s left) gem_wait (wait@all-engines)
[82.012425] Starting subtest: wait
[82.013504] Starting dynamic subtest: all-engines
[83.041915] Dynamic subtest all-engines: SUCCESS (1.029s)
[83.043162] Subtest wait: SUCCESS (1.030s)
[83.278651] [050/162] (924s left) i915_getparams_basic (basic-eu-total)
[83.322617] Starting subtest: basic-eu-total
[83.324782] Subtest basic-eu-total: SUCCESS (0.000s)
[83.422081] [051/162] (924s left) i915_getparams_basic (basic-subslice-total)
[83.465174] Starting subtest: basic-subslice-total
[83.467145] Subtest basic-subslice-total: SUCCESS (0.000s)
[83.574878] [052/162] (924s left) i915_hangman (error-state-basic)
[83.626596] Starting subtest: error-state-basic
[83.630696] Subtest error-state-basic: SUCCESS (0.005s)
[83.754887] [053/162] (923s left) i915_pciid
[83.910451] [054/162] (923s left) intel_hwmon (hwmon-read)
[83.954650] Subtest hwmon-read: SKIP (0.000s)
[84.002842] [055/162] (923s left) intel_hwmon (hwmon-write)
[84.049455] Subtest hwmon-write: SKIP (0.000s)
[84.102150] [056/162] (923s left) kms_addfb_basic (addfb25-4-tiled)
[84.195208] Starting subtest: addfb25-4-tiled
[84.196252] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[84.325254] [057/162] (923s left) kms_addfb_basic (addfb25-bad-modifier)
[84.411325] Starting subtest: addfb25-bad-modifier
[84.412296] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[84.537691] [058/162] (923s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[84.623249] Starting subtest: addfb25-framebuffer-vs-set-tiling
[84.624261] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[84.755180] [059/162] (923s left) kms_addfb_basic (addfb25-modifier-no-flag)
[84.840739] Starting subtest: addfb25-modifier-no-flag
[84.841933] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[84.955283] [060/162] (923s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[85.041382] Starting subtest: addfb25-x-tiled-legacy
[85.042405] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[85.153847] [061/162] (922s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[85.237851] Starting subtest: addfb25-x-tiled-mismatch-legacy
[85.238983] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[85.359422] [062/162] (922s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[85.444788] Starting subtest: addfb25-yf-tiled-legacy
[85.445801] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[85.559669] [063/162] (922s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[85.643080] Starting subtest: addfb25-y-tiled-legacy
[85.644166] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[85.754547] [064/162] (922s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[85.838145] Starting subtest: addfb25-y-tiled-small-legacy
[85.840063] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[85.958755] [065/162] (922s left) kms_addfb_basic (bad-pitch-0)
[86.001905] Starting subtest: bad-pitch-0
[86.002936] Subtest bad-pitch-0: SUCCESS (0.000s)
[86.170308] [066/162] (922s left) kms_addfb_basic (bad-pitch-1024)
[86.214277] Starting subtest: bad-pitch-1024
[86.215308] Subtest bad-pitch-1024: SUCCESS (0.000s)
[86.375227] [067/162] (922s left) kms_addfb_basic (bad-pitch-128)
[86.419531] Starting subtest: bad-pitch-128
[86.420573] Subtest bad-pitch-128: SUCCESS (0.000s)
[86.588078] [068/162] (921s left) kms_addfb_basic (bad-pitch-256)
[86.632657] Starting subtest: bad-pitch-256
[86.633920] Subtest bad-pitch-256: SUCCESS (0.000s)
[86.788210] [069/162] (921s left) kms_addfb_basic (bad-pitch-32)
[86.831200] Starting subtest: bad-pitch-32
[86.832311] Subtest bad-pitch-32: SUCCESS (0.000s)
[86.986427] [070/162] (921s left) kms_addfb_basic (bad-pitch-63)
[87.032289] Starting subtest: bad-pitch-63
[87.033295] Subtest bad-pitch-63: SUCCESS (0.000s)
[87.187273] [071/162] (921s left) kms_addfb_basic (bad-pitch-65536)
[87.231815] Starting subtest: bad-pitch-65536
[87.232845] Subtest bad-pitch-65536: SUCCESS (0.000s)
[87.392169] [072/162] (921s left) kms_addfb_basic (bad-pitch-999)
[87.436343] Starting subtest: bad-pitch-999
[87.437393] Subtest bad-pitch-999: SUCCESS (0.000s)
[87.609766] [073/162] (921s left) kms_addfb_basic (basic)
[87.654283] Starting subtest: basic
[87.655320] Subtest basic: SUCCESS (0.000s)
[87.823133] [074/162] (920s left) kms_addfb_basic (basic-x-tiled-legacy)
[87.866177] Starting subtest: basic-x-tiled-legacy
[87.867260] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[88.026048] [075/162] (920s left) kms_addfb_basic (basic-y-tiled-legacy)
[88.071349] Starting subtest: basic-y-tiled-legacy
[88.072393] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[88.219786] [076/162] (920s left) kms_addfb_basic (bo-too-small)
[88.303816] Starting subtest: bo-too-small
[88.304839] Subtest bo-too-small: SUCCESS (0.000s)
[88.419688] [077/162] (920s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[88.504234] Starting subtest: bo-too-small-due-to-tiling
[88.505221] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[88.622615] [078/162] (920s left) kms_addfb_basic (clobberred-modifier)
[88.667401] Starting subtest: clobberred-modifier
[88.668414] Subtest clobberred-modifier: SUCCESS (0.000s)
[88.834941] [079/162] (920s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[88.877011] Starting subtest: framebuffer-vs-set-tiling
[88.878091] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[89.039105] [080/162] (919s left) kms_addfb_basic (invalid-get-prop)
[89.083589] Starting subtest: invalid-get-prop
[89.084727] Subtest invalid-get-prop: SUCCESS (0.000s)
[89.238123] [081/162] (919s left) kms_addfb_basic (invalid-get-prop-any)
[89.282769] Starting subtest: invalid-get-prop-any
[89.283887] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[89.455841] [082/162] (919s left) kms_addfb_basic (invalid-set-prop)
[89.499248] Starting subtest: invalid-set-prop
[89.500606] Subtest invalid-set-prop: SUCCESS (0.000s)
[89.652184] [083/162] (919s left) kms_addfb_basic (invalid-set-prop-any)
[89.697209] Starting subtest: invalid-set-prop-any
[89.698265] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[89.854106] [084/162] (919s left) kms_addfb_basic (no-handle)
[89.899758] Starting subtest: no-handle
[89.900821] Subtest no-handle: SUCCESS (0.000s)
[90.054866] [085/162] (919s left) kms_addfb_basic (size-max)
[90.138969] Starting subtest: size-max
[90.140033] Subtest size-max: SUCCESS (0.000s)
[90.278182] [086/162] (918s left) kms_addfb_basic (small-bo)
[90.361464] Starting subtest: small-bo
[90.362447] Subtest small-bo: SUCCESS (0.000s)
[90.490705] [087/162] (918s left) kms_addfb_basic (tile-pitch-mismatch)
[90.535731] Starting subtest: tile-pitch-mismatch
[90.536807] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[90.706840] [088/162] (918s left) kms_addfb_basic (too-high)
[90.790271] Starting subtest: too-high
[90.791418] Subtest too-high: SUCCESS (0.001s)
[90.922894] [089/162] (918s left) kms_addfb_basic (too-wide)
[91.011170] Starting subtest: too-wide
[91.012213] Subtest too-wide: SUCCESS (0.000s)
[91.140480] [090/162] (918s left) kms_addfb_basic (unused-handle)
[91.184011] Starting subtest: unused-handle
[91.185087] Subtest unused-handle: SUCCESS (0.000s)
[91.351617] [091/162] (918s left) kms_addfb_basic (unused-modifier)
[91.395234] Starting subtest: unused-modifier
[91.396333] Subtest unused-modifier: SUCCESS (0.000s)
[91.555624] [092/162] (917s left) kms_addfb_basic (unused-offsets)
[91.600253] Starting subtest: unused-offsets
[91.601357] Subtest unused-offsets: SUCCESS (0.000s)
[91.768436] [093/162] (917s left) kms_addfb_basic (unused-pitches)
[91.812413] Starting subtest: unused-pitches
[91.813543] Subtest unused-pitches: SUCCESS (0.000s)
[91.969124] [094/162] (917s left) kms_busy (basic)
[92.054683] Starting subtest: basic
[92.055939] Starting dynamic subtest: flip
[92.446788] Dynamic subtest flip: SUCCESS (0.390s)
[92.461921] Starting dynamic subtest: modeset
[92.988936] Dynamic subtest modeset: SUCCESS (0.542s)
[92.989996] Subtest basic: SUCCESS (0.934s)
[93.195097] [095/162] (916s left) kms_prop_blob (basic)
[93.241981] Starting subtest: basic
[93.243297] Subtest basic: SUCCESS (0.000s)
[93.345436] [096/162] (916s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[93.429404] Starting subtest: basic-busy-flip-before-cursor-atomic
[94.355543] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.926s)
[94.547537] [097/162] (915s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[94.629225] Starting subtest: basic-busy-flip-before-cursor-legacy
[95.572824] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.943s)
[95.760765] [098/162] (914s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[95.843986] Starting subtest: basic-flip-after-cursor-atomic
[96.756927] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.914s)
[96.949858] [099/162] (912s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[97.031220] Starting subtest: basic-flip-after-cursor-legacy
[97.961669] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.930s)
[98.143805] [100/162] (911s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[98.223447] Starting subtest: basic-flip-after-cursor-varying-size
[99.141456] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.918s)
[99.331316] [101/162] (910s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[99.416065] Starting subtest: basic-flip-before-cursor-atomic
[100.353585] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.937s)
[100.528710] [102/162] (909s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[100.617294] Starting subtest: basic-flip-before-cursor-legacy
[101.547046] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.930s)
[101.736635] [103/162] (908s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[101.821767] Starting subtest: basic-flip-before-cursor-varying-size
[102.760808] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.939s)
[102.958954] [104/162] (907s left) kms_dsc (dsc-basic)
[103.042494] Subtest dsc-basic: SKIP (0.000s)
[103.104215] [105/162] (907s left) kms_flip (basic-flip-vs-dpms)
[103.191873] Starting subtest: basic-flip-vs-dpms
[103.193019] Starting dynamic subtest: A-VGA1
[104.050806] Dynamic subtest A-VGA1: SUCCESS (0.859s)
[104.061306] Starting dynamic subtest: B-VGA1
[104.855313] Dynamic subtest B-VGA1: SUCCESS (0.804s)
[104.865671] Starting dynamic subtest: C-VGA1
[105.790784] Dynamic subtest C-VGA1: SUCCESS (0.935s)
[105.793261] Subtest basic-flip-vs-dpms: SUCCESS (2.602s)
[106.012405] [106/162] (904s left) kms_flip (basic-flip-vs-modeset)
[106.097675] Starting subtest: basic-flip-vs-modeset
[106.099839] Starting dynamic subtest: A-VGA1
[107.000428] Dynamic subtest A-VGA1: SUCCESS (0.901s)
[107.012473] Starting dynamic subtest: B-VGA1
[107.854047] Dynamic subtest B-VGA1: SUCCESS (0.853s)
[107.864323] Starting dynamic subtest: C-VGA1
[108.706299] Dynamic subtest C-VGA1: SUCCESS (0.851s)
[108.708456] Subtest basic-flip-vs-modeset: SUCCESS (2.611s)
[108.898979] [107/162] (901s left) kms_flip (basic-flip-vs-wf_vblank)
[108.985636] Starting subtest: basic-flip-vs-wf_vblank
[108.987926] Starting dynamic subtest: A-VGA1
[110.132429] Dynamic subtest A-VGA1: SUCCESS (1.145s)
[110.145081] Starting dynamic subtest: B-VGA1
[111.260899] Dynamic subtest B-VGA1: SUCCESS (1.125s)
[111.275058] Starting dynamic subtest: C-VGA1
[112.409200] Dynamic subtest C-VGA1: SUCCESS (1.146s)
[112.413230] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.427s)
[112.658843] [108/162] (897s left) kms_flip (basic-plain-flip)
[112.741902] Starting subtest: basic-plain-flip
[112.743033] Starting dynamic subtest: A-VGA1
[113.607679] Dynamic subtest A-VGA1: SUCCESS (0.864s)
[113.619516] Starting dynamic subtest: B-VGA1
[114.452522] Dynamic subtest B-VGA1: SUCCESS (0.843s)
[114.463851] Starting dynamic subtest: C-VGA1
[115.319304] Dynamic subtest C-VGA1: SUCCESS (0.864s)
[115.321819] Subtest basic-plain-flip: SUCCESS (2.581s)
[115.538601] [109/162] (894s left) kms_force_connector_basic (force-connector-state)
[115.595779] Starting subtest: force-connector-state
[115.772567] Subtest force-connector-state: SUCCESS (0.177s)
[116.022717] [110/162] (894s left) kms_force_connector_basic (force-edid)
[116.079398] Starting subtest: force-edid
[116.104833] Subtest force-edid: SUCCESS (0.025s)
[116.250357] [111/162] (894s left) kms_force_connector_basic (force-load-detect)
[116.305550] Starting subtest: force-load-detect
[116.474767] Subtest force-load-detect: SUCCESS (0.169s)
[116.718099] [112/162] (893s left) kms_force_connector_basic (prune-stale-modes)
[116.772308] Starting subtest: prune-stale-modes
[116.790123] Subtest prune-stale-modes: SUCCESS (0.017s)
[116.966366] [113/162] (893s left) kms_frontbuffer_tracking (basic)
[117.056194] Starting subtest: basic
[118.988724] Subtest basic: SUCCESS (1.934s)
[119.115617] [114/162] (891s left) kms_hdmi_inject (inject-audio)
[119.197681] Starting subtest: inject-audio
[119.304670] Subtest inject-audio: SUCCESS (0.107s)
[119.584404] [115/162] (891s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[119.670248] Starting subtest: compare-crc-sanitycheck-xr24
[119.671357] Starting dynamic subtest: pipe-A-VGA-1
[119.959969] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.289s)
[119.970981] Starting dynamic subtest: pipe-B-VGA-1
[120.230990] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.269s)
[120.248833] Starting dynamic subtest: pipe-C-VGA-1
[120.503886] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.272s)
[120.504983] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.835s)
[120.696650] [116/162] (889s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[120.780899] Starting subtest: compare-crc-sanitycheck-nv12
[120.781984] Starting dynamic subtest: pipe-A-VGA-1
[120.784210] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[120.794496] Starting dynamic subtest: pipe-B-VGA-1
[120.796720] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[120.811922] Starting dynamic subtest: pipe-C-VGA-1
[120.814306] Dynamic subtest pipe-C-VGA-1: SKIP (0.000s)
[120.816618] Subtest compare-crc-sanitycheck-nv12: SKIP (0.005s)
[120.894231] [117/162] (889s left) kms_pipe_crc_basic (hang-read-crc)
[120.978767] Starting subtest: hang-read-crc
[120.979873] Starting dynamic subtest: pipe-A-VGA-1
[121.741890] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.762s)
[121.752443] Starting dynamic subtest: pipe-B-VGA-1
[122.478753] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.735s)
[122.490194] Starting dynamic subtest: pipe-C-VGA-1
[123.213694] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.734s)
[123.214703] Subtest hang-read-crc: SUCCESS (2.237s)
[123.386935] [118/162] (887s left) kms_pipe_crc_basic (nonblocking-crc)
[123.471780] Starting subtest: nonblocking-crc
[123.472907] Starting dynamic subtest: pipe-A-VGA-1
[123.928490] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.457s)
[123.941536] Starting dynamic subtest: pipe-B-VGA-1
[124.363805] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.434s)
[124.375769] Starting dynamic subtest: pipe-C-VGA-1
[124.800251] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.434s)
[124.801375] Subtest nonblocking-crc: SUCCESS (1.329s)
[124.967296] [119/162] (885s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[125.054632] Starting subtest: nonblocking-crc-frame-sequence
[125.055687] Starting dynamic subtest: pipe-A-VGA-1
[125.513844] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.458s)
[125.525445] Starting dynamic subtest: pipe-B-VGA-1
[125.951136] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.436s)
[125.962637] Starting dynamic subtest: pipe-C-VGA-1
[126.387692] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.433s)
[126.388858] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.332s)
[126.553907] [120/162] (884s left) kms_pipe_crc_basic (read-crc)
[126.637203] Starting subtest: read-crc
[126.638465] Starting dynamic subtest: pipe-A-VGA-1
[127.032594] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.394s)
[127.044325] Starting dynamic subtest: pipe-B-VGA-1
[127.405952] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.372s)
[127.417900] Starting dynamic subtest: pipe-C-VGA-1
[127.774691] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.367s)
[127.775863] Subtest read-crc: SUCCESS (1.138s)
[127.942992] [121/162] (882s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[128.027236] Starting subtest: read-crc-frame-sequence
[128.028309] Starting dynamic subtest: pipe-A-VGA-1
[128.423255] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.396s)
[128.434648] Starting dynamic subtest: pipe-B-VGA-1
[128.792195] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.367s)
[128.802628] Starting dynamic subtest: pipe-C-VGA-1
[129.162182] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.369s)
[129.163257] Subtest read-crc-frame-sequence: SUCCESS (1.136s)
[129.336442] [122/162] (881s left) kms_pm_backlight (basic-brightness)
[129.421800] Subtest basic-brightness: SKIP (0.000s)
[129.477328] [123/162] (881s left) kms_pm_rpm (basic-pci-d3-state)
[130.850889] Starting subtest: basic-pci-d3-state
[131.068430] Subtest basic-pci-d3-state: SUCCESS (0.220s)
[131.176457] [124/162] (879s left) kms_pm_rpm (basic-rte)
[131.219598] Starting subtest: basic-rte
[132.768297] Subtest basic-rte: SUCCESS (1.549s)
[133.104764] [125/162] (877s left) kms_psr (psr-primary-page-flip)
[133.191464] Subtest psr-primary-page-flip: SKIP (0.000s)
[133.254989] [126/162] (877s left) kms_psr (psr-cursor-plane-move)
[133.343182] Subtest psr-cursor-plane-move: SKIP (0.000s)
[133.400523] [127/162] (877s left) kms_psr (psr-sprite-plane-onoff)
[133.488379] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[133.549612] [128/162] (877s left) kms_psr (psr-primary-mmap-gtt)
[133.637339] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[133.683639] [129/162] (877s left) kms_setmode (basic-clone-single-crtc)
[133.731271] Starting subtest: basic-clone-single-crtc
[133.734453] Subtest basic-clone-single-crtc: SKIP (0.000s)
[133.837565] [130/162] (877s left) i915_pm_rps (basic-api)
[133.890305] Starting subtest: basic-api
[133.893473] Subtest basic-api: SUCCESS (0.003s)
[134.005363] [131/162] (877s left) prime_self_import (basic-llseek-bad)
[134.048623] Starting subtest: basic-llseek-bad
[134.050802] Subtest basic-llseek-bad: SUCCESS (0.002s)
[134.171756] [132/162] (877s left) prime_self_import (basic-llseek-size)
[134.213762] Starting subtest: basic-llseek-size
[134.217395] Subtest basic-llseek-size: SUCCESS (0.004s)
[134.336371] [133/162] (877s left) prime_self_import (basic-with_fd_dup)
[134.378333] Starting subtest: basic-with_fd_dup
[134.380654] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[134.504532] [134/162] (876s left) prime_self_import (basic-with_one_bo)
[134.548200] Starting subtest: basic-with_one_bo
[134.550365] Subtest basic-with_one_bo: SUCCESS (0.003s)
[134.667698] [135/162] (876s left) prime_self_import (basic-with_one_bo_two_files)
[134.712224] Starting subtest: basic-with_one_bo_two_files
[134.714407] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[134.837533] [136/162] (876s left) prime_self_import (basic-with_two_bos)
[134.880224] Starting subtest: basic-with_two_bos
[134.883030] Subtest basic-with_two_bos: SUCCESS (0.003s)
[135.004318] [137/162] (876s left) prime_vgem (basic-fence-flip)
[135.068468] Starting subtest: basic-fence-flip
[135.411352] Subtest basic-fence-flip: SUCCESS (0.342s)
[135.537233] [138/162] (876s left) prime_vgem (basic-fence-mmap)
[135.601433] Starting subtest: basic-fence-mmap
[135.623238] Subtest basic-fence-mmap: SUCCESS (0.023s)
[135.755625] [139/162] (875s left) prime_vgem (basic-fence-read)
[135.816964] Starting subtest: basic-fence-read
[135.871678] Subtest basic-fence-read: SUCCESS (0.055s)
[136.004461] [140/162] (875s left) prime_vgem (basic-gtt)
[136.067532] Starting subtest: basic-gtt
[136.078345] Subtest basic-gtt: SUCCESS (0.012s)
[136.206582] [141/162] (875s left) prime_vgem (basic-read)
[136.266380] Starting subtest: basic-read
[136.290432] Subtest basic-read: SUCCESS (0.024s)
[136.420866] [142/162] (875s left) prime_vgem (basic-write)
[136.483358] Starting subtest: basic-write
[136.508430] Subtest basic-write: SUCCESS (0.025s)
[136.638554] [143/162] (875s left) vgem_basic (setversion)
[136.696792] Starting subtest: setversion
[136.699187] Subtest setversion: SUCCESS (0.000s)
[136.788607] [144/162] (875s left) vgem_basic (create)
[136.846972] Starting subtest: create
[136.848214] Subtest create: SUCCESS (0.000s)
[136.937199] [145/162] (875s left) vgem_basic (debugfs)
[136.994789] Starting subtest: debugfs
[136.995928] Subtest debugfs: SUCCESS (0.000s)
[137.088546] [146/162] (874s left) vgem_basic (dmabuf-export)
[137.145620] Starting subtest: dmabuf-export
[137.147918] Subtest dmabuf-export: SUCCESS (0.002s)
[137.270329] [147/162] (874s left) vgem_basic (dmabuf-fence)
[137.330275] Starting subtest: dmabuf-fence
[137.331412] Subtest dmabuf-fence: SUCCESS (0.001s)
[137.420577] [148/162] (874s left) vgem_basic (dmabuf-fence-before)
[137.480967] Starting subtest: dmabuf-fence-before
[137.482180] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[137.570410] [149/162] (874s left) vgem_basic (dmabuf-mmap)
[137.629765] Starting subtest: dmabuf-mmap
[137.641973] Subtest dmabuf-mmap: SUCCESS (0.015s)
[137.736905] [150/162] (874s left) vgem_basic (mmap)
[137.796247] Starting subtest: mmap
[137.804312] Subtest mmap: SUCCESS (0.009s)
[137.887994] [151/162] (874s left) vgem_basic (second-client)
[137.946371] Starting subtest: second-client
[137.981283] Subtest second-client: SUCCESS (0.033s)
[138.071586] [152/162] (874s left) vgem_basic (sysfs)
[138.129681] Starting subtest: sysfs
[138.130718] Subtest sysfs: SUCCESS (0.001s)
[138.220615] [153/162] (874s left) core_hotunplug (unbind-rebind)
[138.345723] Starting subtest: unbind-rebind
[142.257415] Subtest unbind-rebind: SUCCESS (3.911s)
[142.306655] [154/162] (870s left) vgem_basic (unload)
[142.358622] Starting subtest: unload
[171.410010] Subtest unload: SUCCESS (29.050s)
[171.545665] [155/162] (840s left) i915_module_load (reload)
[171.592762] Starting subtest: reload
[173.975888] Subtest reload: SUCCESS (2.383s)
[174.031404] [156/162] (838s left) gem_lmem_swapping (basic)
[174.087824] Subtest basic: SKIP (0.000s)
[174.149486] [157/162] (838s left) gem_lmem_swapping (parallel-random-engines)
[174.197408] Subtest parallel-random-engines: SKIP (0.000s)
[174.249934] [158/162] (838s left) gem_lmem_swapping (random-engines)
[174.297948] Subtest random-engines: SKIP (0.000s)
[174.347543] [159/162] (838s left) gem_lmem_swapping (verify-random)
[174.394434] Subtest verify-random: SKIP (0.000s)
[174.449225] [160/162] (838s left) i915_pm_rpm (module-reload)
[186.021234] Starting subtest: module-reload
[214.168520] Subtest module-reload: SUCCESS (28.146s)
[214.199499] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[214.200586] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[214.202990] [161/162] (798s left) i915_selftest (live)
[214.258390] Starting subtest: live
[214.259549] Starting dynamic subtest: sanitycheck
[215.966831] Dynamic subtest sanitycheck: SUCCESS (1.707s)
[215.978304] Starting dynamic subtest: uncore
[217.638858] Dynamic subtest uncore: SUCCESS (1.669s)
[217.650694] Starting dynamic subtest: workarounds
[219.335236] Dynamic subtest workarounds: SUCCESS (1.693s)
[219.348888] Starting dynamic subtest: gt_engines
[220.799219] Dynamic subtest gt_engines: SUCCESS (1.461s)
[220.813293] Starting dynamic subtest: gt_timelines
[228.387626] Dynamic subtest gt_timelines: SUCCESS (7.586s)
[228.399286] Starting dynamic subtest: gt_contexts
[230.062040] Dynamic subtest gt_contexts: SUCCESS (1.671s)
[230.074408] Starting dynamic subtest: gt_lrc
[231.749295] Dynamic subtest gt_lrc: SUCCESS (1.684s)
[231.759925] Starting dynamic subtest: gt_mocs
[233.436523] Dynamic subtest gt_mocs: SUCCESS (1.685s)
[233.449409] Starting dynamic subtest: gt_pm
[247.310303] Dynamic subtest gt_pm: SUCCESS (13.870s)
[247.321686] Starting dynamic subtest: gt_heartbeat
[248.846975] Dynamic subtest gt_heartbeat: SUCCESS (1.534s)
[248.860467] Starting dynamic subtest: gt_tlb
[250.531902] Dynamic subtest gt_tlb: SUCCESS (1.682s)
[250.543339] Starting dynamic subtest: requests
[258.030072] Dynamic subtest requests: SUCCESS (7.494s)
[258.047858] Starting dynamic subtest: migrate
[259.661976] Dynamic subtest migrate: SUCCESS (1.628s)
[259.676803] Starting dynamic subtest: active
[261.354915] Dynamic subtest active: SUCCESS (1.690s)
[261.364840] Starting dynamic subtest: objects
[263.373987] Dynamic subtest objects: SUCCESS (2.017s)
[263.385334] Starting dynamic subtest: mman
[268.364153] Dynamic subtest mman: SUCCESS (4.987s)
[268.375864] Starting dynamic subtest: dmabuf
[270.007473] Dynamic subtest dmabuf: SUCCESS (1.640s)
[270.018602] Starting dynamic subtest: vma
[271.696633] Dynamic subtest vma: SUCCESS (1.687s)
[271.709826] Starting dynamic subtest: coherency
[274.250452] Dynamic subtest coherency: SUCCESS (2.551s)
[274.261651] Starting dynamic subtest: gtt
[279.279767] Dynamic subtest gtt: SUCCESS (5.027s)
[279.292654] Starting dynamic subtest: gem