[74.087575] Initializing watchdogs
[74.087944]   /dev/watchdog0
[74.169534] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:22b1 Intel Cherryview (Gen8) Atom/Celeron/Pentium Processor x5-E8000/J3xxx/N3xxx Integrated Graphics Controller
[74.193599] [001/161] (960s left) i915_module_load (load)
[74.494665] Starting subtest: load
[80.391121] Subtest load: SUCCESS (5.966s)
[80.845113] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[80.874706] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[80.912210] [002/161] (954s left) core_auth (basic-auth)
[81.606307] Starting subtest: basic-auth
[81.608265] Subtest basic-auth: SUCCESS (0.005s)
[82.209643] [003/161] (953s left) debugfs_test (read_all_entries)
[82.691166] Starting subtest: read_all_entries
[83.181866] Subtest read_all_entries: SUCCESS (0.490s)
[83.918419] [004/161] (951s left) debugfs_test (basic-hwmon)
[84.195167] Starting subtest: basic-hwmon
[84.201391] Subtest basic-hwmon: SKIP (0.000s)
[84.879692] [005/161] (951s left) debugfs_test (sysfs)
[85.153498] Starting subtest: sysfs
[85.557074] Subtest sysfs: SUCCESS (0.403s)
[86.283928] [006/161] (949s left) fbdev (eof)
[86.572740] Starting subtest: eof
[86.581137] Subtest eof: SUCCESS (0.005s)
[86.791351] [007/161] (949s left) fbdev (info)
[87.058206] Starting subtest: info
[87.063576] Subtest info: SUCCESS (0.000s)
[87.295737] [008/161] (949s left) fbdev (nullptr)
[87.555259] Starting subtest: nullptr
[87.559604] Subtest nullptr: SUCCESS (0.001s)
[87.782407] [009/161] (948s left) fbdev (read)
[88.056924] Starting subtest: read
[90.213670] Subtest read: SUCCESS (2.161s)
[90.426866] [010/161] (946s left) fbdev (write)
[90.697127] Starting subtest: write
[91.840880] Subtest write: SUCCESS (1.146s)
[92.071258] [011/161] (944s left) gem_basic (bad-close)
[92.353309] Starting subtest: bad-close
[92.357515] Subtest bad-close: SUCCESS (0.000s)
[92.587420] [012/161] (944s left) gem_basic (create-close)
[92.848497] Starting subtest: create-close
[92.853215] Subtest create-close: SUCCESS (0.001s)
[93.082573] [013/161] (944s left) gem_basic (create-fd-close)
[93.351805] Starting subtest: create-fd-close
[93.357697] Subtest create-fd-close: SUCCESS (0.002s)
[93.597498] [014/161] (943s left) gem_busy (busy@all-engines)
[93.929769] Starting subtest: busy
[93.933215] Starting dynamic subtest: all-engines
[94.143062] Dynamic subtest all-engines: SUCCESS (0.214s)
[94.145277] Subtest busy: SUCCESS (0.220s)
[94.716532] [015/161] (943s left) gem_close_race (basic-process)
[95.061596] Starting subtest: basic-process
[95.248468] Subtest basic-process: SUCCESS (0.187s)
[95.610554] [016/161] (942s left) gem_close_race (basic-threads)
[95.958992] Starting subtest: basic-threads
[97.236119] Subtest basic-threads: SUCCESS (1.280s)
[97.607353] [017/161] (940s left) gem_ctx_create (basic)
[97.927229] Starting subtest: basic
[97.930234] Subtest basic: SUCCESS (0.000s)
[98.293785] [018/161] (939s left) gem_ctx_create (basic-files)
[98.607585] Starting subtest: basic-files
[100.688874] Subtest basic-files: SUCCESS (2.084s)
[101.109599] [019/161] (937s left) gem_ctx_exec (basic)
[101.385981] Starting subtest: basic
[101.402728] Subtest basic: SUCCESS (0.015s)
[101.642482] [020/161] (936s left) gem_exec_basic (basic)
[101.950893] Starting subtest: basic
[101.953776] Starting dynamic subtest: rcs0-smem
[101.963283] Dynamic subtest rcs0-smem: SUCCESS (0.005s)
[101.987482] Starting dynamic subtest: bcs0-smem
[101.989255] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[102.017226] Starting dynamic subtest: vcs0-smem
[102.023315] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[102.051232] Starting dynamic subtest: vecs0-smem
[102.053673] Dynamic subtest vecs0-smem: SUCCESS (0.003s)
[102.056420] Subtest basic: SUCCESS (0.018s)
[102.328604] [021/161] (936s left) gem_exec_create (basic)
[102.650991] Starting subtest: basic
[102.656327] Starting dynamic subtest: smem
[104.752477] Dynamic subtest smem: SUCCESS (2.100s)
[104.754940] Subtest basic: SUCCESS (2.101s)
[105.105452] [022/161] (933s left) gem_exec_fence (basic-busy)
[105.597818] Starting subtest: basic-busy
[105.601185] Starting dynamic subtest: rcs0
[105.635165] Dynamic subtest rcs0: SUCCESS (0.038s)
[105.663090] Starting dynamic subtest: bcs0
[105.678891] Dynamic subtest bcs0: SUCCESS (0.041s)
[105.704065] Starting dynamic subtest: vcs0
[105.733278] Dynamic subtest vcs0: SUCCESS (0.055s)
[105.762854] Starting dynamic subtest: vecs0
[105.783530] Dynamic subtest vecs0: SUCCESS (0.044s)
[105.785455] Subtest basic-busy: SUCCESS (0.187s)
[106.811161] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[106.821389] [023/161] (932s left) gem_exec_fence (basic-wait)
[107.324593] Starting subtest: basic-wait
[107.332798] Starting dynamic subtest: rcs0
[107.370155] Dynamic subtest rcs0: SUCCESS (0.041s)
[107.395535] Starting dynamic subtest: bcs0
[107.413766] Dynamic subtest bcs0: SUCCESS (0.044s)
[107.441448] Starting dynamic subtest: vcs0
[107.465172] Dynamic subtest vcs0: SUCCESS (0.046s)
[107.494451] Starting dynamic subtest: vecs0
[107.513918] Dynamic subtest vecs0: SUCCESS (0.041s)
[107.516440] Subtest basic-wait: SUCCESS (0.184s)
[108.393595] [024/161] (930s left) gem_exec_fence (basic-await)
[108.885714] Starting subtest: basic-await
[108.888237] Starting dynamic subtest: rcs0
[109.124978] Dynamic subtest rcs0: SUCCESS (0.234s)
[109.151214] Starting dynamic subtest: bcs0
[109.356098] Dynamic subtest bcs0: SUCCESS (0.227s)
[109.382281] Starting dynamic subtest: vcs0
[109.590120] Dynamic subtest vcs0: SUCCESS (0.229s)
[109.626313] Starting dynamic subtest: vecs0
[109.824862] Dynamic subtest vecs0: SUCCESS (0.230s)
[109.827338] Subtest basic-await: SUCCESS (0.940s)
[110.682557] [025/161] (928s left) gem_exec_fence (nb-await)
[111.178293] Starting subtest: nb-await
[111.180241] Starting dynamic subtest: rcs0
[111.268977] Dynamic subtest rcs0: SUCCESS (0.094s)
[111.304844] Starting dynamic subtest: bcs0
[111.376964] Dynamic subtest bcs0: SUCCESS (0.096s)
[111.404684] Starting dynamic subtest: vcs0
[111.474401] Dynamic subtest vcs0: SUCCESS (0.104s)
[111.508663] Starting dynamic subtest: vecs0
[111.577720] Dynamic subtest vecs0: SUCCESS (0.097s)
[111.579765] Subtest nb-await: SUCCESS (0.397s)
[112.432494] [026/161] (927s left) gem_exec_gttfill (basic)
[112.751880] Starting subtest: basic
[144.106758] Subtest basic: SUCCESS (31.353s)
[144.541487] [027/161] (895s left) gem_exec_parallel (engines)
[145.041620] Starting subtest: engines
[145.045762] Starting dynamic subtest: basic
[153.794846] Dynamic subtest basic: SUCCESS (8.751s)
[153.835923] Starting dynamic subtest: contexts
[164.339160] Dynamic subtest contexts: SUCCESS (10.541s)
[164.367226] Starting dynamic subtest: fds
[175.829954] Dynamic subtest fds: SUCCESS (11.485s)
[175.865905] Starting dynamic subtest: userptr
[182.362224] Dynamic subtest userptr: SUCCESS (6.532s)
[182.364436] Subtest engines: SUCCESS (37.324s)
[182.787377] [028/161] (857s left) gem_exec_store (basic)
[183.110696] Starting subtest: basic
[183.183989] Subtest basic: SUCCESS (0.074s)
[183.597878] [029/161] (856s left) gem_flink_basic (bad-flink)
[183.857057] Starting subtest: bad-flink
[183.859586] Subtest bad-flink: SUCCESS (0.000s)
[184.114880] [030/161] (856s left) gem_flink_basic (bad-open)
[184.381545] Starting subtest: bad-open
[184.384575] Subtest bad-open: SUCCESS (0.000s)
[184.631927] [031/161] (856s left) gem_flink_basic (basic)
[184.897215] Starting subtest: basic
[184.901555] Subtest basic: SUCCESS (0.000s)
[185.158516] [032/161] (855s left) gem_flink_basic (double-flink)
[185.432775] Starting subtest: double-flink
[185.436369] Subtest double-flink: SUCCESS (0.000s)
[185.697359] [033/161] (855s left) gem_flink_basic (flink-lifetime)
[185.958213] Starting subtest: flink-lifetime
[185.966403] Subtest flink-lifetime: SUCCESS (0.010s)
[186.223798] [034/161] (855s left) gem_huc_copy (huc-copy)
[186.495263] Subtest huc-copy: SKIP (0.000s)
[186.728852] [035/161] (854s left) gem_linear_blits (basic)
[187.366433] Starting subtest: basic
[187.406189] Subtest basic: SUCCESS (0.041s)
[187.715528] [036/161] (854s left) gem_mmap (basic)
[188.147320] Starting subtest: basic
[188.160873] Subtest basic: SUCCESS (0.003s)
[188.401941] [037/161] (853s left) gem_mmap_gtt (basic)
[188.668753] Starting subtest: basic
[188.673197] Subtest basic: SUCCESS (0.004s)
[188.928960] [038/161] (853s left) gem_render_linear_blits (basic)
[189.212827] Starting subtest: basic
[189.344080] Subtest basic: SUCCESS (0.125s)
[189.565393] [039/161] (852s left) gem_render_tiled_blits (basic)
[189.836170] Starting subtest: basic
[190.003889] Subtest basic: SUCCESS (0.161s)
[190.225939] [040/161] (852s left) gem_ringfill (basic-all)
[190.763408] Starting subtest: basic-all
[192.235292] Subtest basic-all: SUCCESS (1.477s)
[192.502885] [041/161] (850s left) gem_softpin (allocator-basic)
[192.783747] Starting subtest: allocator-basic
[193.170177] Subtest allocator-basic: SUCCESS (0.381s)
[193.498772] [042/161] (849s left) gem_softpin (allocator-basic-reserve)
[193.787794] Starting subtest: allocator-basic-reserve
[194.161337] Subtest allocator-basic-reserve: SUCCESS (0.359s)
[194.504852] [043/161] (848s left) gem_softpin (safe-alignment)
[194.783644] Starting subtest: safe-alignment
[194.844361] Subtest safe-alignment: SUCCESS (0.052s)
[195.116335] [044/161] (848s left) gem_sync (basic-all)
[195.450307] Starting subtest: basic-all
[198.623642] Subtest basic-all: SUCCESS (3.170s)
[199.031715] [045/161] (844s left) gem_sync (basic-each)
[199.357687] Starting subtest: basic-each
[202.177989] Subtest basic-each: SUCCESS (2.819s)
[202.593543] [046/161] (841s left) gem_tiled_blits (basic)
[203.095051] Starting subtest: basic
[203.188108] Subtest basic: SUCCESS (0.093s)
[203.501953] [047/161] (840s left) gem_tiled_fence_blits (basic)
[204.186158] Starting subtest: basic
[204.256762] Subtest basic: SUCCESS (0.070s)
[204.555434] [048/161] (839s left) gem_tiled_pread_basic
[206.105368] [049/161] (838s left) gem_wait (busy@all-engines)
[206.461780] Starting subtest: busy
[206.465348] Starting dynamic subtest: all-engines
[207.002206] Dynamic subtest all-engines: SUCCESS (0.540s)
[207.006781] Subtest busy: SUCCESS (0.544s)
[207.565184] [050/161] (837s left) gem_wait (wait@all-engines)
[207.901997] Starting subtest: wait
[207.906570] Starting dynamic subtest: all-engines
[208.943101] Dynamic subtest all-engines: SUCCESS (1.040s)
[208.945631] Subtest wait: SUCCESS (1.041s)
[209.425870] [051/161] (835s left) i915_getparams_basic (basic-eu-total)
[209.697381] Starting subtest: basic-eu-total
[209.706147] Subtest basic-eu-total: SUCCESS (0.000s)
[209.975846] [052/161] (835s left) i915_getparams_basic (basic-subslice-total)
[210.233800] Starting subtest: basic-subslice-total
[210.241405] Subtest basic-subslice-total: SUCCESS (0.000s)
[210.471502] [053/161] (834s left) i915_hangman (error-state-basic)
[210.780625] Starting subtest: error-state-basic
[210.813283] Subtest error-state-basic: SUCCESS (0.025s)
[211.106282] [054/161] (834s left) i915_pciid
[211.630851] [055/161] (834s left) kms_addfb_basic (addfb25-4-tiled)
[212.386218] Starting subtest: addfb25-4-tiled
[212.388220] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[212.648961] [056/161] (833s left) kms_addfb_basic (addfb25-bad-modifier)
[213.385295] Starting subtest: addfb25-bad-modifier
[213.389671] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[213.643898] [057/161] (832s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[214.391893] Starting subtest: addfb25-framebuffer-vs-set-tiling
[214.396252] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[214.669577] [058/161] (831s left) kms_addfb_basic (addfb25-modifier-no-flag)
[215.410737] Starting subtest: addfb25-modifier-no-flag
[215.416811] Subtest addfb25-modifier-no-flag: SUCCESS (0.002s)
[215.689848] [059/161] (830s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[216.442199] Starting subtest: addfb25-x-tiled-legacy
[216.444103] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[216.693964] [060/161] (830s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[217.413314] Starting subtest: addfb25-x-tiled-mismatch-legacy
[217.415715] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[217.692614] [061/161] (829s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[218.450742] Starting subtest: addfb25-yf-tiled-legacy
[218.457514] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[218.697076] [062/161] (828s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[219.430639] Starting subtest: addfb25-y-tiled-legacy
[219.433789] Subtest addfb25-y-tiled-legacy: SUCCESS (0.002s)
[219.694567] [063/161] (827s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[220.439434] Starting subtest: addfb25-y-tiled-small-legacy
[220.444602] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[220.713441] [064/161] (826s left) kms_addfb_basic (bad-pitch-0)
[220.985728] Starting subtest: bad-pitch-0
[220.989113] Subtest bad-pitch-0: SUCCESS (0.000s)
[221.718674] [065/161] (826s left) kms_addfb_basic (bad-pitch-1024)
[221.993235] Starting subtest: bad-pitch-1024
[221.995387] Subtest bad-pitch-1024: SUCCESS (0.000s)
[222.796370] [066/161] (825s left) kms_addfb_basic (bad-pitch-128)
[223.068794] Starting subtest: bad-pitch-128
[223.071267] Subtest bad-pitch-128: SUCCESS (0.000s)
[223.835420] [067/161] (824s left) kms_addfb_basic (bad-pitch-256)
[224.101626] Starting subtest: bad-pitch-256
[224.103969] Subtest bad-pitch-256: SUCCESS (0.000s)
[224.863089] [068/161] (823s left) kms_addfb_basic (bad-pitch-32)
[225.129449] Starting subtest: bad-pitch-32
[225.134054] Subtest bad-pitch-32: SUCCESS (0.000s)
[225.862817] [069/161] (822s left) kms_addfb_basic (bad-pitch-63)
[226.127348] Starting subtest: bad-pitch-63
[226.129490] Subtest bad-pitch-63: SUCCESS (0.000s)
[226.865703] [070/161] (821s left) kms_addfb_basic (bad-pitch-65536)
[227.126263] Starting subtest: bad-pitch-65536
[227.130320] Subtest bad-pitch-65536: SUCCESS (0.001s)
[227.898487] [071/161] (821s left) kms_addfb_basic (bad-pitch-999)
[228.161979] Starting subtest: bad-pitch-999
[228.163976] Subtest bad-pitch-999: SUCCESS (0.000s)
[228.905046] [072/161] (820s left) kms_addfb_basic (basic)
[229.173919] Starting subtest: basic
[229.176125] Subtest basic: SUCCESS (0.000s)
[229.933487] [073/161] (819s left) kms_addfb_basic (basic-x-tiled-legacy)
[230.199651] Starting subtest: basic-x-tiled-legacy
[230.203408] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[230.955431] [074/161] (818s left) kms_addfb_basic (basic-y-tiled-legacy)
[231.228628] Starting subtest: basic-y-tiled-legacy
[231.230993] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[231.967467] [075/161] (817s left) kms_addfb_basic (bo-too-small)
[232.750966] Starting subtest: bo-too-small
[232.754379] Subtest bo-too-small: SUCCESS (0.002s)
[233.019868] [076/161] (817s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[233.799872] Starting subtest: bo-too-small-due-to-tiling
[233.803192] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[234.072429] [077/161] (816s left) kms_addfb_basic (clobberred-modifier)
[234.346799] Starting subtest: clobberred-modifier
[234.351188] Subtest clobberred-modifier: SUCCESS (0.002s)
[235.086594] [078/161] (815s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[235.362030] Starting subtest: framebuffer-vs-set-tiling
[235.364186] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[236.102354] [079/161] (814s left) kms_addfb_basic (invalid-get-prop)
[236.368784] Starting subtest: invalid-get-prop
[236.370560] Subtest invalid-get-prop: SUCCESS (0.000s)
[237.178961] [080/161] (813s left) kms_addfb_basic (invalid-get-prop-any)
[237.459194] Starting subtest: invalid-get-prop-any
[237.462423] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[238.193330] [081/161] (812s left) kms_addfb_basic (invalid-set-prop)
[238.460810] Starting subtest: invalid-set-prop
[238.463485] Subtest invalid-set-prop: SUCCESS (0.000s)
[239.204210] [082/161] (812s left) kms_addfb_basic (invalid-set-prop-any)
[239.477703] Starting subtest: invalid-set-prop-any
[239.480283] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[240.255115] [083/161] (811s left) kms_addfb_basic (no-handle)
[240.522722] Starting subtest: no-handle
[240.526583] Subtest no-handle: SUCCESS (0.000s)
[241.286260] [084/161] (810s left) kms_addfb_basic (size-max)
[242.036942] Starting subtest: size-max
[242.039735] Subtest size-max: SUCCESS (0.000s)
[242.310483] [085/161] (809s left) kms_addfb_basic (small-bo)
[243.067391] Starting subtest: small-bo
[243.069153] Subtest small-bo: SUCCESS (0.000s)
[243.339626] [086/161] (808s left) kms_addfb_basic (tile-pitch-mismatch)
[243.607420] Starting subtest: tile-pitch-mismatch
[243.609986] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[244.374217] [087/161] (808s left) kms_addfb_basic (too-high)
[245.108841] Starting subtest: too-high
[245.112619] Subtest too-high: SUCCESS (0.004s)
[245.390056] [088/161] (807s left) kms_addfb_basic (too-wide)
[246.102478] Starting subtest: too-wide
[246.106191] Subtest too-wide: SUCCESS (0.000s)
[246.385816] [089/161] (806s left) kms_addfb_basic (unused-handle)
[246.654394] Starting subtest: unused-handle
[246.657061] Subtest unused-handle: SUCCESS (0.000s)
[247.413139] [090/161] (805s left) kms_addfb_basic (unused-modifier)
[247.686666] Starting subtest: unused-modifier
[247.689254] Subtest unused-modifier: SUCCESS (0.000s)
[248.416653] [091/161] (804s left) kms_addfb_basic (unused-offsets)
[248.686161] Starting subtest: unused-offsets
[248.689263] Subtest unused-offsets: SUCCESS (0.000s)
[249.419315] [092/161] (804s left) kms_addfb_basic (unused-pitches)
[249.684974] Starting subtest: unused-pitches
[249.688354] Subtest unused-pitches: SUCCESS (0.000s)
[250.487339] [093/161] (803s left) kms_busy (basic)
[251.288603] Starting subtest: basic
[251.293169] Starting dynamic subtest: flip
[252.395269] Dynamic subtest flip: SUCCESS (1.101s)
[252.429499] Starting dynamic subtest: modeset
[254.378603] Dynamic subtest modeset: SUCCESS (1.963s)
[254.385218] Subtest basic: SUCCESS (3.082s)
[255.044742] [094/161] (798s left) kms_prop_blob (basic)
[255.332688] Starting subtest: basic
[255.337526] Subtest basic: SUCCESS (0.000s)
[255.594340] [095/161] (798s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[256.350202] Starting subtest: basic-busy-flip-before-cursor-atomic
[257.413368] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (1.067s)
[258.069501] [096/161] (796s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[258.842710] Starting subtest: basic-busy-flip-before-cursor-legacy
[259.908182] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (1.064s)
[260.610504] [097/161] (793s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[261.373057] Starting subtest: basic-flip-after-cursor-atomic
[262.421984] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.052s)
[263.051581] [098/161] (791s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[263.805290] Starting subtest: basic-flip-after-cursor-legacy
[264.860080] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.056s)
[265.482221] [099/161] (789s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[266.244260] Starting subtest: basic-flip-after-cursor-varying-size
[267.277477] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.040s)
[267.927108] [100/161] (787s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[268.706080] Starting subtest: basic-flip-before-cursor-atomic
[269.743495] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.035s)
[270.389481] [101/161] (784s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[271.140176] Starting subtest: basic-flip-before-cursor-legacy
[272.188344] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.047s)
[272.862549] [102/161] (782s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[273.615093] Starting subtest: basic-flip-before-cursor-varying-size
[274.671567] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.055s)
[275.316121] [103/161] (780s left) kms_dsc (dsc-basic)
[276.107940] Subtest dsc-basic: SKIP (0.000s)
[276.333274] [104/161] (779s left) kms_flip (basic-flip-vs-dpms)
[277.124068] Starting subtest: basic-flip-vs-dpms
[277.131806] Starting dynamic subtest: C-HDMI-A2
[279.766177] Dynamic subtest C-HDMI-A2: SUCCESS (2.615s)
[279.772857] Subtest basic-flip-vs-dpms: SUCCESS (2.632s)
[280.439184] [105/161] (775s left) kms_flip (basic-flip-vs-modeset)
[281.221587] Starting subtest: basic-flip-vs-modeset
[281.237617] Starting dynamic subtest: C-HDMI-A2
[284.259159] Dynamic subtest C-HDMI-A2: SUCCESS (3.003s)
[284.264957] Subtest basic-flip-vs-modeset: SUCCESS (3.019s)
[285.002197] [106/161] (771s left) kms_flip (basic-flip-vs-wf_vblank)
[285.801653] Starting subtest: basic-flip-vs-wf_vblank
[285.817367] Starting dynamic subtest: C-HDMI-A2
[288.818266] Dynamic subtest C-HDMI-A2: SUCCESS (2.973s)
[288.821108] Subtest basic-flip-vs-wf_vblank: SUCCESS (2.992s)
[289.126290] [107/161] (767s left) kms_flip (basic-plain-flip)
[289.875976] Starting subtest: basic-plain-flip
[289.880375] Starting dynamic subtest: C-HDMI-A2
[292.367275] Dynamic subtest C-HDMI-A2: SUCCESS (2.481s)
[292.369309] Subtest basic-plain-flip: SUCCESS (2.499s)
[292.723430] [108/161] (764s left) kms_force_connector_basic (force-connector-state)
[293.117619] Starting subtest: force-connector-state
[293.971178] Subtest force-connector-state: SUCCESS (0.849s)
[295.554511] [109/161] (761s left) kms_force_connector_basic (force-edid)
[296.000397] Starting subtest: force-edid
[296.123598] Subtest force-edid: SUCCESS (0.121s)
[297.160150] [110/161] (760s left) kms_force_connector_basic (force-load-detect)
[297.616470] Starting subtest: force-load-detect
[297.621547] Subtest force-load-detect: SKIP (0.000s)
[298.316410] [111/161] (759s left) kms_force_connector_basic (prune-stale-modes)
[298.735683] Starting subtest: prune-stale-modes
[298.896223] Subtest prune-stale-modes: SUCCESS (0.160s)
[299.913426] [112/161] (757s left) kms_frontbuffer_tracking (basic)
[300.721166] Starting subtest: basic
[305.722870] Subtest basic: SUCCESS (5.004s)
[306.031394] [113/161] (751s left) kms_hdmi_inject (inject-audio)
[306.418107] Starting subtest: inject-audio
[307.047416] Subtest inject-audio: SUCCESS (0.638s)
[307.815777] [114/161] (750s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[308.609098] Starting subtest: compare-crc-sanitycheck-xr24
[308.610955] Starting dynamic subtest: pipe-C-HDMI-A-2
[309.194875] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.584s)
[309.196766] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.595s)
[309.840402] [115/161] (748s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[310.568987] Starting subtest: compare-crc-sanitycheck-nv12
[310.581645] Starting dynamic subtest: pipe-C-HDMI-A-2
[310.597414] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[310.602446] Subtest compare-crc-sanitycheck-nv12: SKIP (0.020s)
[310.851433] [116/161] (747s left) kms_pipe_crc_basic (hang-read-crc)
[311.632580] Starting subtest: hang-read-crc
[311.638183] Starting dynamic subtest: pipe-C-HDMI-A-2
[313.423815] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.791s)
[313.430347] Subtest hang-read-crc: SUCCESS (1.802s)
[314.184180] [117/161] (744s left) kms_pipe_crc_basic (nonblocking-crc)
[314.976243] Starting subtest: nonblocking-crc
[314.985826] Starting dynamic subtest: pipe-C-HDMI-A-2
[315.749879] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.758s)
[315.751787] Subtest nonblocking-crc: SUCCESS (0.772s)
[316.389465] [118/161] (742s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[317.212457] Starting subtest: nonblocking-crc-frame-sequence
[317.221260] Starting dynamic subtest: pipe-C-HDMI-A-2
[317.944694] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.721s)
[317.953694] Subtest nonblocking-crc-frame-sequence: SUCCESS (0.739s)
[318.584194] [119/161] (740s left) kms_pipe_crc_basic (read-crc)
[319.412813] Starting subtest: read-crc
[319.415661] Starting dynamic subtest: pipe-C-HDMI-A-2
[320.105049] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.681s)
[320.106941] Subtest read-crc: SUCCESS (0.700s)
[320.781536] [120/161] (738s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[321.554262] Starting subtest: read-crc-frame-sequence
[321.555992] Starting dynamic subtest: pipe-C-HDMI-A-2
[322.207997] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.659s)
[322.211470] Subtest read-crc-frame-sequence: SUCCESS (0.668s)
[322.860084] [121/161] (736s left) kms_pm_backlight (basic-brightness)
[323.670338] Subtest basic-brightness: SKIP (0.000s)
[323.881157] [122/161] (735s left) kms_pm_rpm (basic-pci-d3-state)
[326.292332] Starting subtest: basic-pci-d3-state
[326.682118] Subtest basic-pci-d3-state: SUCCESS (0.376s)
[327.227225] [123/161] (732s left) kms_pm_rpm (basic-rte)
[327.488626] Starting subtest: basic-rte
[329.910530] Subtest basic-rte: SUCCESS (2.406s)
[331.064225] [124/161] (729s left) kms_psr (psr-primary-page-flip)
[331.873382] Subtest psr-primary-page-flip: SKIP (0.000s)
[332.101303] [125/161] (728s left) kms_psr (psr-cursor-plane-move)
[332.886517] Subtest psr-cursor-plane-move: SKIP (0.000s)
[333.121905] [126/161] (727s left) kms_psr (psr-sprite-plane-onoff)
[333.923208] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[334.174946] [127/161] (726s left) kms_psr (psr-primary-mmap-gtt)
[334.975215] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[335.215393] [128/161] (725s left) kms_setmode (basic-clone-single-crtc)
[335.498446] Starting subtest: basic-clone-single-crtc
[335.506334] Subtest basic-clone-single-crtc: SKIP (0.001s)
[335.745538] [129/161] (725s left) i915_pm_rps (basic-api)
[336.038171] Starting subtest: basic-api
[336.054454] Subtest basic-api: SUCCESS (0.017s)
[336.316716] [130/161] (725s left) prime_self_import (basic-llseek-bad)
[336.580758] Starting subtest: basic-llseek-bad
[336.598220] Subtest basic-llseek-bad: SUCCESS (0.018s)
[336.875595] [131/161] (724s left) prime_self_import (basic-llseek-size)
[337.135786] Starting subtest: basic-llseek-size
[337.151746] Subtest basic-llseek-size: SUCCESS (0.017s)
[337.419623] [132/161] (724s left) prime_self_import (basic-with_fd_dup)
[337.677714] Starting subtest: basic-with_fd_dup
[337.693883] Subtest basic-with_fd_dup: SUCCESS (0.017s)
[337.969400] [133/161] (724s left) prime_self_import (basic-with_one_bo)
[338.224148] Starting subtest: basic-with_one_bo
[338.238688] Subtest basic-with_one_bo: SUCCESS (0.017s)
[338.516220] [134/161] (723s left) prime_self_import (basic-with_one_bo_two_files)
[338.777978] Starting subtest: basic-with_one_bo_two_files
[338.794921] Subtest basic-with_one_bo_two_files: SUCCESS (0.014s)
[339.116861] [135/161] (723s left) prime_self_import (basic-with_two_bos)
[339.385349] Starting subtest: basic-with_two_bos
[339.401116] Subtest basic-with_two_bos: SUCCESS (0.014s)
[339.660668] [136/161] (723s left) prime_vgem (basic-fence-flip)
[339.976164] Starting subtest: basic-fence-flip
[340.887093] Subtest basic-fence-flip: SKIP (0.898s)
[341.179609] [137/161] (721s left) prime_vgem (basic-fence-mmap)
[341.507895] Starting subtest: basic-fence-mmap
[341.632306] Subtest basic-fence-mmap: SUCCESS (0.129s)
[341.910540] [138/161] (721s left) prime_vgem (basic-fence-read)
[342.272237] Starting subtest: basic-fence-read
[342.533203] Subtest basic-fence-read: SUCCESS (0.261s)
[342.813515] [139/161] (720s left) prime_vgem (basic-gtt)
[343.112965] Starting subtest: basic-gtt
[343.170655] Subtest basic-gtt: SUCCESS (0.049s)
[343.444310] [140/161] (720s left) prime_vgem (basic-read)
[343.747484] Starting subtest: basic-read
[343.867304] Subtest basic-read: SUCCESS (0.118s)
[344.141458] [141/161] (719s left) prime_vgem (basic-write)
[344.452593] Starting subtest: basic-write
[344.614467] Subtest basic-write: SUCCESS (0.162s)
[344.890453] [142/161] (719s left) vgem_basic (setversion)
[345.183170] Starting subtest: setversion
[345.187828] Subtest setversion: SUCCESS (0.000s)
[345.419715] [143/161] (718s left) vgem_basic (create)
[345.706242] Starting subtest: create
[345.708053] Subtest create: SUCCESS (0.003s)
[345.978263] [144/161] (718s left) vgem_basic (debugfs)
[346.256465] Starting subtest: debugfs
[346.260895] Subtest debugfs: SUCCESS (0.003s)
[346.505223] [145/161] (718s left) vgem_basic (dmabuf-export)
[346.774656] Starting subtest: dmabuf-export
[346.780435] Subtest dmabuf-export: SUCCESS (0.010s)
[347.042618] [146/161] (717s left) vgem_basic (dmabuf-fence)
[347.324270] Starting subtest: dmabuf-fence
[347.328143] Subtest dmabuf-fence: SUCCESS (0.002s)
[347.614442] [147/161] (717s left) vgem_basic (dmabuf-fence-before)
[347.885191] Starting subtest: dmabuf-fence-before
[347.887688] Subtest dmabuf-fence-before: SUCCESS (0.002s)
[348.126203] [148/161] (717s left) vgem_basic (dmabuf-mmap)
[348.402815] Starting subtest: dmabuf-mmap
[348.448218] Subtest dmabuf-mmap: SUCCESS (0.043s)
[348.687223] [149/161] (716s left) vgem_basic (mmap)
[348.972230] Starting subtest: mmap
[349.008378] Subtest mmap: SUCCESS (0.037s)
[349.249706] [150/161] (716s left) vgem_basic (second-client)
[349.523584] Starting subtest: second-client
[349.555740] Subtest second-client: SUCCESS (0.032s)
[349.803898] [151/161] (716s left) vgem_basic (sysfs)
[350.093833] Starting subtest: sysfs
[350.095729] Subtest sysfs: SUCCESS (0.005s)
[350.347104] [152/161] (715s left) core_hotunplug (unbind-rebind)
[350.824355] Starting subtest: unbind-rebind
[356.956433] Subtest unbind-rebind: SUCCESS (6.129s)
[357.247186] [153/161] (709s left) vgem_basic (unload)
[357.572573] Starting subtest: unload
[386.877130] Subtest unload: SUCCESS (29.299s)
[387.324257] [154/161] (679s left) i915_module_load (reload)
[387.582557] Starting subtest: reload
[395.014319] Subtest reload: SUCCESS (7.414s)
[395.990194] [155/161] (671s left) gem_lmem_swapping (basic)
[396.549242] Subtest basic: SKIP (0.000s)
[396.808731] [156/161] (671s left) gem_lmem_swapping (parallel-random-engines)
[397.102892] Subtest parallel-random-engines: SKIP (0.000s)
[397.336352] [157/161] (670s left) gem_lmem_swapping (random-engines)
[397.619269] Subtest random-engines: SKIP (0.000s)
[397.845768] [158/161] (670s left) gem_lmem_swapping (verify-random)
[398.140251] Subtest verify-random: SKIP (0.000s)
[398.361349] [159/161] (670s left) i915_pm_rpm (module-reload)
[401.099302] Starting subtest: module-reload
[420.438304] Subtest module-reload: SUCCESS (19.358s)
[420.579916] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[420.585650] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[420.594399] [160/161] (648s left) i915_selftest (live)
[420.886381] Starting subtest: live
[420.888617] Starting dynamic subtest: sanitycheck
[425.475863] Dynamic subtest sanitycheck: SUCCESS (4.586s)
[425.522958] Starting dynamic subtest: uncore
[430.035319] Dynamic subtest uncore: SUCCESS (4.549s)
[430.076845] Starting dynamic subtest: workarounds
[434.821337] Dynamic subtest workarounds: SUCCESS (4.781s)
[434.854035] Starting dynamic subtest: gt_engines
[439.846128] Dynamic subtest gt_engines: SUCCESS (5.008s)
[439.889178] Starting dynamic subtest: gt_timelines
[472.175684] Dynamic subtest gt_timelines: SUCCESS (32.321s)
[472.218261] Starting dynamic subtest: gt_contexts
[476.831425] Dynamic subtest gt_contexts: SUCCESS (4.632s)
[476.878451] Starting dynamic subtest: gt_lrc
[484.994244] Dynamic subtest gt_lrc: SUCCESS (8.151s)
[485.042878] Starting dynamic subtest: gt_mocs
[489.742143] Dynamic subtest gt_mocs: SUCCESS (4.733s)
[489.809360] Starting dynamic subtest: gt_pm
[495.542088] Dynamic subtest gt_pm: SUCCESS (5.781s)
[495.577817] Starting dynamic subtest: gt_heartbeat
[500.300797] Dynamic subtest gt_heartbeat: SUCCESS (4.747s)
[500.341649] Starting dynamic subtest: gt_tlb
[505.540994] Dynamic subtest gt_tlb: SUCCESS (5.232s)
[505.580383] Starting dynamic subtest: requests
[516.500261] Dynamic subtest requests: SUCCESS (10.940s)
[516.550579] Starting dynamic subtest: migrate
[524.780163] Dynamic subtest migrate: SUCCESS (8.272s)
[524.828346] Starting dynamic subtest: active
[529.566302] Dynamic subtest active: SUCCESS (4.772s)
[529.610356] Starting dynamic subtest: objects
[536.648432] Dynamic subtest objects: SUCCESS (7.067s)
[536.720359] Starting dynamic subtest: mman
[549.634178] Dynamic subtest mman: SUCCESS (12.961s)
[549.678391] Starting dynamic subtest: dmabuf
[554.367160] Dynamic subtest dmabuf: SUCCESS (4.721s)
[554.447278] Starting dynamic subtest: vma
[558.836164] Dynamic subtest vma: SUCCESS (4.447s)
[558.911548] Starting dynamic subtest: coherency
[569.957821] Dynamic subtest coherency: SUCCESS (11.115s)
[569.996476] Starting dynamic subtest: gtt
[582.262218] Dynamic subtest gtt: SUCCESS (12.288s)
[582.329531] Starting dynamic subtest: gem
[588.108495] Dynamic subtest gem: SUCCESS (5.835s)
[588.148367] Starting dynamic subtest: evict
[599.486084] Dynamic subtest evict: SUCCESS (11.372s)
[599.528323] Starting dynamic subtest: hugepages
[604.519166] Dynamic subtest hugepages: SUCCESS (5.023s)
[604.579678] Starting dynamic subtest: gem_contexts
[634.145886] Dynamic subtest gem_contexts: SUCCESS (29.613s)
[634.183962] Starting dynamic subtest: client
[638.817429] Dynamic subtest client: SUCCESS (4.654s)
[638.885875] Starting dynamic subtest: gem_migrate
[643.468915] Dynamic subtest gem_migrate: SUCCESS (4.635s)
[643.501572] Starting dynamic subtest: reset
[656.333216] Dynamic subtest reset: SUCCESS (12.853s)
[656.392887] Starting dynamic subtest: memory_region
[660.930166] Dynamic subtest memory_region: SUCCESS (4.579s)
[660.987612] Starting dynamic subtest: hangcheck
[688.805361] Dynamic subtest hangcheck: SUCCESS (27.875s)
[688.853141] Starting dynamic subtest: execlists
[699.880431] Dynamic subtest execlists: SUCCESS (11.069s)
[699.914534] Starting dynamic subtest: ring_submission
[704.460819] Dynamic subtest ring_submission: SUCCESS (4.562s)
[704.508693] Starting dynamic subtest: perf
[709.436522] Dynamic subtest perf: SUCCESS (4.971s)
[709.477546] Starting dynamic subtest: slpc
[714.050411] Dynamic subtest slpc: SUCCESS (4.601s)
[714.088369] Starting dynamic subtest: guc
[718.852962] Dynamic subtest guc: SUCCESS (4.794s)
[718.892827] Starting dynamic subtest: guc_multi_lrc
[723.593792] Dynamic subtest guc_multi_lrc: SUCCESS (4.724s)
[723.659058] Starting dynamic subtest: guc_hang
[728.253916] Dynamic subtest guc_hang: SUCCESS (4.652s)
[728.301698] Starting dynamic subtest: late_gt_pm
[733.001500] Dynamic subtest late_gt_pm: SUCCESS (4.736s)
[733.013964] Subtest live: SUCCESS (312.119s)
[733.182199] [161/161] (335s left) dmabuf (all-tests)
[733.494719] Starting subtest: all-tests
[733.496908] Starting dynamic subtest: sanitycheck
[733.564172] Dynamic subtest sanitycheck: SUCCESS (0.065s)
[733.665330] Starting dynamic subtest: dma_fence
[733.897678] Dynamic subtest dma_fence: SUCCESS (0.325s)
[733.961836] Starting dynamic subtest: dma_fence_chain