[90.536209] Initializing watchdogs
[90.536578]   /dev/watchdog0
[90.603561] [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
[90.625494] [001/162] (960s left) i915_module_load (load)
[90.876642] Starting subtest: load
[96.344892] Subtest load: SUCCESS (5.439s)
[96.565443] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[96.582845] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[96.605482] [002/162] (954s left) core_auth (basic-auth)
[97.072139] Starting subtest: basic-auth
[97.077669] Subtest basic-auth: SUCCESS (0.004s)
[97.285572] [003/162] (954s left) core_debugfs (read-all-entries)
[97.509862] Starting subtest: read-all-entries
[97.522169] Subtest read-all-entries: SUCCESS (0.015s)
[97.752716] [004/162] (953s left) core_sysfs (read-all-entries)
[97.970970] Starting subtest: read-all-entries
[97.988277] Subtest read-all-entries: SUCCESS (0.018s)
[98.214666] [005/162] (953s left) fbdev (eof)
[98.457561] Starting subtest: eof
[98.466474] Subtest eof: SUCCESS (0.006s)
[98.654654] [006/162] (953s left) fbdev (info)
[98.862400] Starting subtest: info
[98.864216] Subtest info: SUCCESS (0.000s)
[99.092756] [007/162] (953s left) fbdev (nullptr)
[99.320580] Starting subtest: nullptr
[99.323422] Subtest nullptr: SUCCESS (0.000s)
[99.534744] [008/162] (952s left) fbdev (read)
[99.775905] Starting subtest: read
[101.845448] Subtest read: SUCCESS (2.063s)
[102.029865] [009/162] (950s left) fbdev (write)
[102.258128] Starting subtest: write
[103.539141] Subtest write: SUCCESS (1.279s)
[103.783779] [010/162] (948s left) gem_basic (bad-close)
[104.121633] Starting subtest: bad-close
[104.133752] Subtest bad-close: SUCCESS (0.000s)
[104.449108] [011/162] (948s left) gem_basic (create-close)
[104.786715] Starting subtest: create-close
[104.795084] Subtest create-close: SUCCESS (0.003s)
[105.295981] [012/162] (948s left) gem_basic (create-fd-close)
[105.591592] Starting subtest: create-fd-close
[105.600250] Subtest create-fd-close: SUCCESS (0.006s)
[105.828774] [013/162] (947s left) gem_busy (busy@all-engines)
[106.122117] Starting subtest: busy
[106.123896] Starting dynamic subtest: all-engines
[106.292168] Dynamic subtest all-engines: SUCCESS (0.173s)
[106.294745] Subtest busy: SUCCESS (0.174s)
[106.810700] [014/162] (946s left) gem_close_race (basic-process)
[107.101114] Starting subtest: basic-process
[107.264560] Subtest basic-process: SUCCESS (0.162s)
[107.602921] [015/162] (946s left) gem_close_race (basic-threads)
[107.876243] Starting subtest: basic-threads
[109.110509] Subtest basic-threads: SUCCESS (1.230s)
[109.475323] [016/162] (944s left) gem_ctx_create (basic)
[109.746292] Starting subtest: basic
[109.749141] Subtest basic: SUCCESS (0.000s)
[110.080269] [017/162] (944s left) gem_ctx_create (basic-files)
[110.350238] Starting subtest: basic-files
[112.414156] Subtest basic-files: SUCCESS (2.064s)
[112.761923] [018/162] (941s left) gem_ctx_exec (basic)
[112.987116] Starting subtest: basic
[113.002406] Subtest basic: SUCCESS (0.014s)
[113.227216] [019/162] (941s left) gem_exec_basic (basic)
[113.479212] Starting subtest: basic
[113.481041] Starting dynamic subtest: rcs0-smem
[113.488820] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[113.521895] Starting dynamic subtest: bcs0-smem
[113.524366] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[113.551615] Starting dynamic subtest: vcs0-smem
[113.553114] Dynamic subtest vcs0-smem: SUCCESS (0.027s)
[113.580827] Starting dynamic subtest: vecs0-smem
[113.582423] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[113.583906] Subtest basic: SUCCESS (0.047s)
[113.879336] [020/162] (940s left) gem_exec_create (basic)
[114.141565] Starting subtest: basic
[114.145297] Starting dynamic subtest: smem
[116.216419] Dynamic subtest smem: SUCCESS (2.076s)
[116.218546] Subtest basic: SUCCESS (2.077s)
[116.560875] [021/162] (938s left) gem_exec_fence (basic-busy)
[116.979889] Starting subtest: basic-busy
[116.982957] Starting dynamic subtest: rcs0
[117.012402] Dynamic subtest rcs0: SUCCESS (0.032s)
[117.048813] Starting dynamic subtest: bcs0
[117.055208] Dynamic subtest bcs0: SUCCESS (0.039s)
[117.082836] Starting dynamic subtest: vcs0
[117.111892] Dynamic subtest vcs0: SUCCESS (0.054s)
[117.138865] Starting dynamic subtest: vecs0
[117.152905] Dynamic subtest vecs0: SUCCESS (0.040s)
[117.155278] Subtest basic-busy: SUCCESS (0.176s)
[118.143934] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[118.157426] [022/162] (936s left) gem_exec_fence (basic-wait)
[118.577365] Starting subtest: basic-wait
[118.580564] Starting dynamic subtest: rcs0
[118.607437] Dynamic subtest rcs0: SUCCESS (0.030s)
[118.640234] Starting dynamic subtest: bcs0
[118.657515] Dynamic subtest bcs0: SUCCESS (0.045s)
[118.684890] Starting dynamic subtest: vcs0
[118.706408] Dynamic subtest vcs0: SUCCESS (0.050s)
[118.733699] Starting dynamic subtest: vecs0
[118.754684] Dynamic subtest vecs0: SUCCESS (0.045s)
[118.756396] Subtest basic-wait: SUCCESS (0.178s)
[119.566696] [023/162] (935s left) gem_exec_fence (basic-await)
[119.991237] Starting subtest: basic-await
[119.995390] Starting dynamic subtest: rcs0
[120.287799] Dynamic subtest rcs0: SUCCESS (0.293s)
[120.324152] Starting dynamic subtest: bcs0
[120.484980] Dynamic subtest bcs0: SUCCESS (0.185s)
[120.513111] Starting dynamic subtest: vcs0
[120.662129] Dynamic subtest vcs0: SUCCESS (0.186s)
[120.689776] Starting dynamic subtest: vecs0
[120.848330] Dynamic subtest vecs0: SUCCESS (0.185s)
[120.851290] Subtest basic-await: SUCCESS (0.858s)
[121.625243] [024/162] (933s left) gem_exec_fence (nb-await)
[122.044090] Starting subtest: nb-await
[122.047604] Starting dynamic subtest: rcs0
[122.137033] Dynamic subtest rcs0: SUCCESS (0.092s)
[122.170196] Starting dynamic subtest: bcs0
[122.241603] Dynamic subtest bcs0: SUCCESS (0.105s)
[122.275545] Starting dynamic subtest: vcs0
[122.349649] Dynamic subtest vcs0: SUCCESS (0.098s)
[122.377106] Starting dynamic subtest: vecs0
[122.447769] Dynamic subtest vecs0: SUCCESS (0.102s)
[122.449543] Subtest nb-await: SUCCESS (0.405s)
[123.275181] [025/162] (932s left) gem_exec_gttfill (basic)
[123.542921] Starting subtest: basic
[124.652670] Subtest basic: SKIP (1.083s)
[125.001858] [026/162] (930s left) gem_exec_parallel (engines)
[125.447078] Starting subtest: engines
[125.449958] Starting dynamic subtest: basic
[131.547615] Dynamic subtest basic: SUCCESS (6.100s)
[131.577767] Starting dynamic subtest: contexts
[142.074386] Dynamic subtest contexts: SUCCESS (10.520s)
[142.104517] Starting dynamic subtest: fds
[155.909482] Dynamic subtest fds: SUCCESS (13.834s)
[155.942168] Starting dynamic subtest: userptr
[162.133655] Dynamic subtest userptr: SUCCESS (6.222s)
[162.135507] Subtest engines: SUCCESS (36.683s)
[162.567540] [027/162] (893s left) gem_exec_store (basic)
[162.835954] Starting subtest: basic
[162.900917] Subtest basic: SUCCESS (0.069s)
[163.267740] [028/162] (893s left) gem_flink_basic (bad-flink)
[163.484523] Starting subtest: bad-flink
[163.488522] Subtest bad-flink: SUCCESS (0.000s)
[163.732851] [029/162] (892s left) gem_flink_basic (bad-open)
[163.951175] Starting subtest: bad-open
[163.954868] Subtest bad-open: SUCCESS (0.000s)
[164.217908] [030/162] (892s left) gem_flink_basic (basic)
[164.438517] Starting subtest: basic
[164.441203] Subtest basic: SUCCESS (0.001s)
[164.670517] [031/162] (892s left) gem_flink_basic (double-flink)
[164.887834] Starting subtest: double-flink
[164.890495] Subtest double-flink: SUCCESS (0.001s)
[165.172708] [032/162] (891s left) gem_flink_basic (flink-lifetime)
[165.404765] Starting subtest: flink-lifetime
[165.410738] Subtest flink-lifetime: SUCCESS (0.010s)
[165.663893] [033/162] (891s left) gem_huc_copy (huc-copy)
[165.897780] Subtest huc-copy: SKIP (0.000s)
[166.105441] [034/162] (891s left) gem_linear_blits (basic)
[166.732749] Subtest basic: SKIP (0.000s)
[166.965622] [035/162] (890s left) gem_mmap (basic)
[167.370601] Starting subtest: basic
[167.380807] Subtest basic: SUCCESS (0.001s)
[167.600559] [036/162] (890s left) gem_mmap_gtt (basic)
[167.818926] Starting subtest: basic
[167.824953] Subtest basic: SUCCESS (0.004s)
[168.056472] [037/162] (889s left) gem_render_linear_blits (basic)
[168.300080] Starting subtest: basic
[168.430290] Subtest basic: SUCCESS (0.128s)
[168.635705] [038/162] (889s left) gem_render_tiled_blits (basic)
[168.860386] Starting subtest: basic
[169.020578] Subtest basic: SUCCESS (0.152s)
[169.220419] [039/162] (889s left) gem_ringfill (basic-all)
[169.708188] Starting subtest: basic-all
[171.085810] Subtest basic-all: SUCCESS (1.373s)
[171.334183] [040/162] (887s left) gem_softpin (allocator-basic)
[171.577337] Starting subtest: allocator-basic
[171.976691] Subtest allocator-basic: SUCCESS (0.392s)
[172.266341] [041/162] (886s left) gem_softpin (allocator-basic-reserve)
[172.501957] Starting subtest: allocator-basic-reserve
[172.903874] Subtest allocator-basic-reserve: SUCCESS (0.400s)
[173.192981] [042/162] (885s left) gem_softpin (safe-alignment)
[173.431139] Starting subtest: safe-alignment
[173.492352] Subtest safe-alignment: SUCCESS (0.057s)
[173.724825] [043/162] (885s left) gem_sync (basic-all)
[173.983766] Starting subtest: basic-all
[177.136937] Subtest basic-all: SUCCESS (3.151s)
[177.486217] [044/162] (881s left) gem_sync (basic-each)
[177.756435] Starting subtest: basic-each
[180.652106] Subtest basic-each: SUCCESS (2.891s)
[181.028718] [045/162] (878s left) gem_tiled_blits (basic)
[181.512706] Subtest basic: SKIP (0.000s)
[181.699446] [046/162] (878s left) gem_tiled_fence_blits (basic)
[182.322819] Starting subtest: basic
[182.391459] Subtest basic: SUCCESS (0.065s)
[182.659575] [047/162] (877s left) gem_tiled_pread_basic
[184.042848] [048/162] (876s left) gem_wait (busy@all-engines)
[184.333219] Starting subtest: busy
[184.338237] Starting dynamic subtest: all-engines
[184.874254] Dynamic subtest all-engines: SUCCESS (0.543s)
[184.877238] Subtest busy: SUCCESS (0.544s)
[185.319187] [049/162] (874s left) gem_wait (wait@all-engines)
[185.604095] Starting subtest: wait
[185.605745] Starting dynamic subtest: all-engines
[186.650974] Dynamic subtest all-engines: SUCCESS (1.046s)
[186.652935] Subtest wait: SUCCESS (1.049s)
[187.107233] [050/162] (873s left) i915_getparams_basic (basic-eu-total)
[187.327620] Starting subtest: basic-eu-total
[187.331847] Subtest basic-eu-total: SUCCESS (0.000s)
[187.567134] [051/162] (873s left) i915_getparams_basic (basic-subslice-total)
[187.783471] Starting subtest: basic-subslice-total
[187.788132] Subtest basic-subslice-total: SUCCESS (0.001s)
[188.025359] [052/162] (872s left) i915_hangman (error-state-basic)
[188.297422] Starting subtest: error-state-basic
[188.329111] Subtest error-state-basic: SUCCESS (0.020s)
[188.575467] [053/162] (872s left) i915_pciid
[189.029369] [054/162] (872s left) intel_hwmon (hwmon-read)
[189.247696] Subtest hwmon-read: SKIP (0.000s)
[189.437367] [055/162] (871s left) intel_hwmon (hwmon-write)
[189.660235] Subtest hwmon-write: SKIP (0.000s)
[189.857620] [056/162] (871s left) kms_addfb_basic (addfb25-4-tiled)
[190.596344] Starting subtest: addfb25-4-tiled
[190.598616] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[190.846246] [057/162] (870s left) kms_addfb_basic (addfb25-bad-modifier)
[191.615713] Starting subtest: addfb25-bad-modifier
[191.618391] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[191.880811] [058/162] (870s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[192.554475] Starting subtest: addfb25-framebuffer-vs-set-tiling
[192.560256] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[192.808944] [059/162] (869s left) kms_addfb_basic (addfb25-modifier-no-flag)
[193.505951] Starting subtest: addfb25-modifier-no-flag
[193.509416] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[193.764816] [060/162] (868s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[194.497272] Starting subtest: addfb25-x-tiled-legacy
[194.499499] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[194.743999] [061/162] (867s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[195.545104] Starting subtest: addfb25-x-tiled-mismatch-legacy
[195.549108] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.003s)
[195.797793] [062/162] (866s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[196.530815] Starting subtest: addfb25-yf-tiled-legacy
[196.534893] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.003s)
[196.781839] [063/162] (866s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[197.498894] Starting subtest: addfb25-y-tiled-legacy
[197.501750] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[197.751961] [064/162] (865s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[198.447317] Starting subtest: addfb25-y-tiled-small-legacy
[198.451119] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[198.683724] [065/162] (864s left) kms_addfb_basic (bad-pitch-0)
[198.914257] Starting subtest: bad-pitch-0
[198.918287] Subtest bad-pitch-0: SUCCESS (0.000s)
[199.622735] [066/162] (863s left) kms_addfb_basic (bad-pitch-1024)
[199.841543] Starting subtest: bad-pitch-1024
[199.845146] Subtest bad-pitch-1024: SUCCESS (0.000s)
[200.561732] [067/162] (863s left) kms_addfb_basic (bad-pitch-128)
[200.778276] Starting subtest: bad-pitch-128
[200.781968] Subtest bad-pitch-128: SUCCESS (0.000s)
[201.530448] [068/162] (862s left) kms_addfb_basic (bad-pitch-256)
[201.760197] Starting subtest: bad-pitch-256
[201.763183] Subtest bad-pitch-256: SUCCESS (0.000s)
[202.515657] [069/162] (861s left) kms_addfb_basic (bad-pitch-32)
[202.736192] Starting subtest: bad-pitch-32
[202.739134] Subtest bad-pitch-32: SUCCESS (0.000s)
[203.484523] [070/162] (860s left) kms_addfb_basic (bad-pitch-63)
[203.709682] Starting subtest: bad-pitch-63
[203.713336] Subtest bad-pitch-63: SUCCESS (0.000s)
[204.468804] [071/162] (859s left) kms_addfb_basic (bad-pitch-65536)
[204.689589] Starting subtest: bad-pitch-65536
[204.692635] Subtest bad-pitch-65536: SUCCESS (0.001s)
[205.433851] [072/162] (859s left) kms_addfb_basic (bad-pitch-999)
[205.662218] Starting subtest: bad-pitch-999
[205.665417] Subtest bad-pitch-999: SUCCESS (0.000s)
[206.401245] [073/162] (858s left) kms_addfb_basic (basic)
[206.628493] Starting subtest: basic
[206.630503] Subtest basic: SUCCESS (0.000s)
[207.329257] [074/162] (857s left) kms_addfb_basic (basic-x-tiled-legacy)
[207.556485] Starting subtest: basic-x-tiled-legacy
[207.558077] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[208.282434] [075/162] (856s left) kms_addfb_basic (basic-y-tiled-legacy)
[208.509392] Starting subtest: basic-y-tiled-legacy
[208.511056] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[209.237340] [076/162] (856s left) kms_addfb_basic (bo-too-small)
[209.932870] Starting subtest: bo-too-small
[209.937236] Subtest bo-too-small: SUCCESS (0.001s)
[210.291227] [077/162] (855s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[210.997750] Starting subtest: bo-too-small-due-to-tiling
[211.001433] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[211.250820] [078/162] (854s left) kms_addfb_basic (clobberred-modifier)
[211.474472] Starting subtest: clobberred-modifier
[211.476409] Subtest clobberred-modifier: SUCCESS (0.001s)
[212.195292] [079/162] (853s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[212.421146] Starting subtest: framebuffer-vs-set-tiling
[212.425523] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[213.172692] [080/162] (853s left) kms_addfb_basic (invalid-get-prop)
[213.394494] Starting subtest: invalid-get-prop
[213.398625] Subtest invalid-get-prop: SUCCESS (0.000s)
[214.114737] [081/162] (852s left) kms_addfb_basic (invalid-get-prop-any)
[214.341165] Starting subtest: invalid-get-prop-any
[214.343395] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[215.063742] [082/162] (851s left) kms_addfb_basic (invalid-set-prop)
[215.286217] Starting subtest: invalid-set-prop
[215.290573] Subtest invalid-set-prop: SUCCESS (0.000s)
[216.032645] [083/162] (850s left) kms_addfb_basic (invalid-set-prop-any)
[216.251355] Starting subtest: invalid-set-prop-any
[216.253395] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[217.014901] [084/162] (849s left) kms_addfb_basic (no-handle)
[217.235612] Starting subtest: no-handle
[217.237560] Subtest no-handle: SUCCESS (0.000s)
[217.968513] [085/162] (849s left) kms_addfb_basic (size-max)
[218.672674] Starting subtest: size-max
[218.678660] Subtest size-max: SUCCESS (0.000s)
[218.913390] [086/162] (848s left) kms_addfb_basic (small-bo)
[219.586544] Starting subtest: small-bo
[219.590359] Subtest small-bo: SUCCESS (0.000s)
[219.827586] [087/162] (847s left) kms_addfb_basic (tile-pitch-mismatch)
[220.053373] Starting subtest: tile-pitch-mismatch
[220.054959] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[220.772213] [088/162] (846s left) kms_addfb_basic (too-high)
[221.496621] Starting subtest: too-high
[221.500299] Subtest too-high: SUCCESS (0.006s)
[221.763760] [089/162] (846s left) kms_addfb_basic (too-wide)
[222.458230] Starting subtest: too-wide
[222.464532] Subtest too-wide: SUCCESS (0.000s)
[222.691929] [090/162] (845s left) kms_addfb_basic (unused-handle)
[222.915344] Starting subtest: unused-handle
[222.918340] Subtest unused-handle: SUCCESS (0.001s)
[223.665339] [091/162] (844s left) kms_addfb_basic (unused-modifier)
[223.881730] Starting subtest: unused-modifier
[223.883611] Subtest unused-modifier: SUCCESS (0.000s)
[224.628384] [092/162] (843s left) kms_addfb_basic (unused-offsets)
[224.856695] Starting subtest: unused-offsets
[224.858984] Subtest unused-offsets: SUCCESS (0.000s)
[225.663071] [093/162] (842s left) kms_addfb_basic (unused-pitches)
[225.882040] Starting subtest: unused-pitches
[225.884691] Subtest unused-pitches: SUCCESS (0.001s)
[226.613548] [094/162] (842s left) kms_busy (basic)
[227.358885] Starting subtest: basic
[227.361992] Starting dynamic subtest: flip
[228.292118] Dynamic subtest flip: SUCCESS (0.929s)
[228.339771] Starting dynamic subtest: modeset
[230.313268] Dynamic subtest modeset: SUCCESS (2.006s)
[230.318819] Subtest basic: SUCCESS (2.951s)
[230.997168] [095/162] (838s left) kms_prop_blob (basic)
[231.219040] Starting subtest: basic
[231.220706] Subtest basic: SUCCESS (0.000s)
[231.454435] [096/162] (837s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[232.186475] Starting subtest: basic-busy-flip-before-cursor-atomic
[233.326202] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (1.134s)
[233.935929] [097/162] (835s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[234.644922] Starting subtest: basic-busy-flip-before-cursor-legacy
[235.803108] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (1.151s)
[236.401951] [098/162] (833s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[237.122591] Starting subtest: basic-flip-after-cursor-atomic
[238.226582] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.108s)
[238.822632] [099/162] (830s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[239.553824] Starting subtest: basic-flip-after-cursor-legacy
[240.748416] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.192s)
[241.356228] [100/162] (828s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[242.069041] Starting subtest: basic-flip-after-cursor-varying-size
[243.184171] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.111s)
[243.805543] [101/162] (826s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[244.551177] Starting subtest: basic-flip-before-cursor-atomic
[245.670325] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.115s)
[246.317891] [102/162] (824s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[247.070803] Starting subtest: basic-flip-before-cursor-legacy
[248.177514] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.108s)
[248.790828] [103/162] (821s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[249.517849] Starting subtest: basic-flip-before-cursor-varying-size
[250.677775] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.132s)
[251.235275] [104/162] (819s left) kms_dsc (dsc-basic)
[251.963948] Subtest dsc-basic: SKIP (0.000s)
[252.183761] [105/162] (818s left) kms_flip (basic-flip-vs-dpms)
[252.934328] Starting subtest: basic-flip-vs-dpms
[252.954171] Starting dynamic subtest: C-HDMI-A2
[255.908296] Dynamic subtest C-HDMI-A2: SUCCESS (2.938s)
[255.912192] Subtest basic-flip-vs-dpms: SUCCESS (2.965s)
[256.581659] [106/162] (814s left) kms_flip (basic-flip-vs-modeset)
[257.319887] Starting subtest: basic-flip-vs-modeset
[257.328402] Starting dynamic subtest: C-HDMI-A2
[260.019598] Dynamic subtest C-HDMI-A2: SUCCESS (2.676s)
[260.027354] Subtest basic-flip-vs-modeset: SUCCESS (2.709s)
[260.876216] [107/162] (810s left) kms_flip (basic-flip-vs-wf_vblank)
[261.651936] Starting subtest: basic-flip-vs-wf_vblank
[261.665230] Starting dynamic subtest: C-HDMI-A2
[264.590305] Dynamic subtest C-HDMI-A2: SUCCESS (2.920s)
[264.593553] Subtest basic-flip-vs-wf_vblank: SUCCESS (2.944s)
[264.910389] [108/162] (806s left) kms_flip (basic-plain-flip)
[265.641892] Starting subtest: basic-plain-flip
[265.649704] Starting dynamic subtest: C-HDMI-A2
[268.192556] Dynamic subtest C-HDMI-A2: SUCCESS (2.533s)
[268.198235] Subtest basic-plain-flip: SUCCESS (2.548s)
[268.493174] [109/162] (803s left) kms_force_connector_basic (force-connector-state)
[268.850632] Starting subtest: force-connector-state
[269.770106] Subtest force-connector-state: SUCCESS (0.908s)
[271.779165] [110/162] (800s left) kms_force_connector_basic (force-edid)
[272.161593] Starting subtest: force-edid
[272.284995] Subtest force-edid: SUCCESS (0.120s)
[273.213375] [111/162] (798s left) kms_force_connector_basic (force-load-detect)
[273.589415] Starting subtest: force-load-detect
[273.592960] Subtest force-load-detect: SKIP (0.000s)
[274.259603] [112/162] (798s left) kms_force_connector_basic (prune-stale-modes)
[274.625214] Starting subtest: prune-stale-modes
[274.819231] Subtest prune-stale-modes: SUCCESS (0.191s)
[275.802321] [113/162] (796s left) kms_frontbuffer_tracking (basic)
[276.578084] Starting subtest: basic
[281.683080] Subtest basic: SUCCESS (5.099s)
[281.965215] [114/162] (790s left) kms_hdmi_inject (inject-audio)
[282.311824] Starting subtest: inject-audio
[282.941823] Subtest inject-audio: SUCCESS (0.640s)
[283.688518] [115/162] (789s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[284.467470] Starting subtest: compare-crc-sanitycheck-xr24
[284.470072] Starting dynamic subtest: pipe-C-HDMI-A-2
[285.029744] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.558s)
[285.032432] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.569s)
[285.779478] [116/162] (787s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[286.528091] Starting subtest: compare-crc-sanitycheck-nv12
[286.545437] Starting dynamic subtest: pipe-C-HDMI-A-2
[286.552817] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[286.558355] Subtest compare-crc-sanitycheck-nv12: SKIP (0.011s)
[286.790898] [117/162] (786s left) kms_pipe_crc_basic (hang-read-crc)
[287.510283] Starting subtest: hang-read-crc
[287.514217] Starting dynamic subtest: pipe-C-HDMI-A-2
[289.117738] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.605s)
[289.120326] Subtest hang-read-crc: SUCCESS (1.617s)
[289.757130] [118/162] (783s left) kms_pipe_crc_basic (nonblocking-crc)
[290.499297] Starting subtest: nonblocking-crc
[290.502572] Starting dynamic subtest: pipe-C-HDMI-A-2
[291.230439] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.723s)
[291.245056] Subtest nonblocking-crc: SUCCESS (0.743s)
[291.888759] [119/162] (781s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[292.634705] Starting subtest: nonblocking-crc-frame-sequence
[292.646185] Starting dynamic subtest: pipe-C-HDMI-A-2
[293.346443] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.702s)
[293.355298] Subtest nonblocking-crc-frame-sequence: SUCCESS (0.720s)
[294.008404] [120/162] (779s left) kms_pipe_crc_basic (read-crc)
[294.764219] Starting subtest: read-crc
[294.765769] Starting dynamic subtest: pipe-C-HDMI-A-2
[295.421834] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.662s)
[295.427890] Subtest read-crc: SUCCESS (0.672s)
[296.057966] [121/162] (777s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[296.814894] Starting subtest: read-crc-frame-sequence
[296.828354] Starting dynamic subtest: pipe-C-HDMI-A-2
[297.504196] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.670s)
[297.505930] Subtest read-crc-frame-sequence: SUCCESS (0.688s)
[298.179164] [122/162] (776s left) kms_pm_backlight (basic-brightness)
[298.943873] Subtest basic-brightness: SKIP (0.000s)
[299.166414] [123/162] (775s left) kms_pm_rpm (basic-pci-d3-state)
[301.602829] Starting subtest: basic-pci-d3-state
[301.936444] Subtest basic-pci-d3-state: SUCCESS (0.336s)
[302.445365] [124/162] (772s left) kms_pm_rpm (basic-rte)
[302.667614] Starting subtest: basic-rte
[305.128609] Subtest basic-rte: SUCCESS (2.455s)
[306.233731] [125/162] (768s left) kms_psr (psr-primary-page-flip)
[306.997235] Subtest psr-primary-page-flip: SKIP (0.000s)
[307.237321] [126/162] (767s left) kms_psr (psr-cursor-plane-move)
[308.011596] Subtest psr-cursor-plane-move: SKIP (0.000s)
[308.222873] [127/162] (766s left) kms_psr (psr-sprite-plane-onoff)
[309.039143] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[309.377391] [128/162] (766s left) kms_psr (psr-primary-mmap-gtt)
[310.228593] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[310.442894] [129/162] (765s left) kms_setmode (basic-clone-single-crtc)
[310.682780] Starting subtest: basic-clone-single-crtc
[310.690488] Subtest basic-clone-single-crtc: SKIP (0.000s)
[310.962408] [130/162] (764s left) i915_pm_rps (basic-api)
[311.225258] Starting subtest: basic-api
[311.237548] Subtest basic-api: SUCCESS (0.016s)
[311.529779] [131/162] (764s left) prime_self_import (basic-llseek-bad)
[311.743479] Starting subtest: basic-llseek-bad
[311.760618] Subtest basic-llseek-bad: SUCCESS (0.016s)
[312.007609] [132/162] (764s left) prime_self_import (basic-llseek-size)
[312.220562] Starting subtest: basic-llseek-size
[312.237593] Subtest basic-llseek-size: SUCCESS (0.017s)
[312.519595] [133/162] (763s left) prime_self_import (basic-with_fd_dup)
[312.731844] Starting subtest: basic-with_fd_dup
[312.744450] Subtest basic-with_fd_dup: SUCCESS (0.016s)
[313.004211] [134/162] (763s left) prime_self_import (basic-with_one_bo)
[313.218453] Starting subtest: basic-with_one_bo
[313.237639] Subtest basic-with_one_bo: SUCCESS (0.020s)
[313.491690] [135/162] (763s left) prime_self_import (basic-with_one_bo_two_files)
[313.723764] Starting subtest: basic-with_one_bo_two_files
[313.739134] Subtest basic-with_one_bo_two_files: SUCCESS (0.013s)
[313.976445] [136/162] (763s left) prime_self_import (basic-with_two_bos)
[314.190640] Starting subtest: basic-with_two_bos
[314.216339] Subtest basic-with_two_bos: SUCCESS (0.023s)
[314.475374] [137/162] (762s left) prime_vgem (basic-fence-flip)
[314.743184] Starting subtest: basic-fence-flip
[315.695722] Subtest basic-fence-flip: SKIP (0.936s)
[315.991185] [138/162] (761s left) prime_vgem (basic-fence-mmap)
[316.268163] Starting subtest: basic-fence-mmap
[316.375304] Subtest basic-fence-mmap: SUCCESS (0.107s)
[316.631413] [139/162] (761s left) prime_vgem (basic-fence-read)
[316.912345] Starting subtest: basic-fence-read
[317.163347] Subtest basic-fence-read: SUCCESS (0.247s)
[317.427853] [140/162] (760s left) prime_vgem (basic-gtt)
[317.693555] Starting subtest: basic-gtt
[317.739126] Subtest basic-gtt: SUCCESS (0.043s)
[318.008932] [141/162] (760s left) prime_vgem (basic-read)
[318.270243] Starting subtest: basic-read
[318.396891] Subtest basic-read: SUCCESS (0.113s)
[318.636671] [142/162] (759s left) prime_vgem (basic-write)
[318.887653] Starting subtest: basic-write
[319.063711] Subtest basic-write: SUCCESS (0.171s)
[319.308995] [143/162] (759s left) vgem_basic (setversion)
[319.548519] Starting subtest: setversion
[319.553559] Subtest setversion: SUCCESS (0.000s)
[319.777515] [144/162] (758s left) vgem_basic (create)
[320.021999] Starting subtest: create
[320.023762] Subtest create: SUCCESS (0.001s)
[320.241626] [145/162] (758s left) vgem_basic (debugfs)
[320.475968] Starting subtest: debugfs
[320.477863] Subtest debugfs: SUCCESS (0.002s)
[320.716827] [146/162] (758s left) vgem_basic (dmabuf-export)
[320.967925] Starting subtest: dmabuf-export
[320.972523] Subtest dmabuf-export: SUCCESS (0.010s)
[321.236137] [147/162] (757s left) vgem_basic (dmabuf-fence)
[321.483198] Starting subtest: dmabuf-fence
[321.487237] Subtest dmabuf-fence: SUCCESS (0.002s)
[321.716136] [148/162] (757s left) vgem_basic (dmabuf-fence-before)
[321.963058] Starting subtest: dmabuf-fence-before
[321.967092] Subtest dmabuf-fence-before: SUCCESS (0.002s)
[322.201463] [149/162] (757s left) vgem_basic (dmabuf-mmap)
[322.431922] Starting subtest: dmabuf-mmap
[322.469880] Subtest dmabuf-mmap: SUCCESS (0.043s)
[322.700972] [150/162] (757s left) vgem_basic (mmap)
[322.933710] Starting subtest: mmap
[322.964148] Subtest mmap: SUCCESS (0.035s)
[323.208966] [151/162] (756s left) vgem_basic (second-client)
[323.450157] Starting subtest: second-client
[323.491599] Subtest second-client: SUCCESS (0.045s)
[323.707234] [152/162] (756s left) vgem_basic (sysfs)
[323.929218] Starting subtest: sysfs
[323.932197] Subtest sysfs: SUCCESS (0.002s)
[324.162910] [153/162] (756s left) core_hotunplug (unbind-rebind)
[324.593108] Starting subtest: unbind-rebind
[330.337245] Subtest unbind-rebind: SUCCESS (5.744s)
[330.567373] [154/162] (750s left) vgem_basic (unload)
[330.792281] Starting subtest: unload
[360.081815] Subtest unload: SUCCESS (29.284s)
[360.543154] [155/162] (720s left) i915_module_load (reload)
[360.767124] Starting subtest: reload
[368.166443] Subtest reload: SUCCESS (7.389s)
[368.638123] [156/162] (712s left) gem_lmem_swapping (basic)
[368.956938] Subtest basic: SKIP (0.000s)
[369.165457] [157/162] (712s left) gem_lmem_swapping (parallel-random-engines)
[369.427559] Subtest parallel-random-engines: SKIP (0.000s)
[369.650554] [158/162] (712s left) gem_lmem_swapping (random-engines)
[369.893372] Subtest random-engines: SKIP (0.000s)
[370.113977] [159/162] (711s left) gem_lmem_swapping (verify-random)
[370.366095] Subtest verify-random: SKIP (0.000s)
[370.581624] [160/162] (711s left) i915_pm_rpm (module-reload)
[373.204183] Starting subtest: module-reload
[391.479492] Subtest module-reload: SUCCESS (18.271s)
[391.597495] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[391.602858] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[391.622408] [161/162] (690s left) i915_selftest (live)
[391.875236] Starting subtest: live
[391.877359] Starting dynamic subtest: sanitycheck
[396.858341] Dynamic subtest sanitycheck: SUCCESS (4.979s)
[396.917149] Starting dynamic subtest: uncore
[401.539649] Dynamic subtest uncore: SUCCESS (4.672s)
[401.567870] Starting dynamic subtest: workarounds
[406.651909] Dynamic subtest workarounds: SUCCESS (5.106s)
[406.698994] Starting dynamic subtest: gt_engines
[411.927737] Dynamic subtest gt_engines: SUCCESS (5.270s)
[411.955847] Starting dynamic subtest: gt_timelines
[446.355962] Dynamic subtest gt_timelines: SUCCESS (34.417s)
[446.384330] Starting dynamic subtest: gt_contexts
[451.423303] Dynamic subtest gt_contexts: SUCCESS (5.063s)
[451.451913] Starting dynamic subtest: gt_lrc
[459.979038] Dynamic subtest gt_lrc: SUCCESS (8.548s)
[460.012082] Starting dynamic subtest: gt_mocs
[464.798354] Dynamic subtest gt_mocs: SUCCESS (4.810s)
[464.864179] Starting dynamic subtest: gt_pm
[471.118762] Dynamic subtest gt_pm: SUCCESS (6.307s)
[471.148355] Starting dynamic subtest: gt_heartbeat
[475.908285] Dynamic subtest gt_heartbeat: SUCCESS (4.781s)
[475.944789] Starting dynamic subtest: gt_tlb
[481.386404] Dynamic subtest gt_tlb: SUCCESS (5.468s)
[481.415434] Starting dynamic subtest: requests
[492.535814] Dynamic subtest requests: SUCCESS (11.137s)
[492.567045] Starting dynamic subtest: migrate
[500.831498] Dynamic subtest migrate: SUCCESS (8.292s)
[500.865314] Starting dynamic subtest: active
[505.747705] Dynamic subtest active: SUCCESS (4.902s)
[505.778197] Starting dynamic subtest: objects
[512.148826] Dynamic subtest objects: SUCCESS (6.394s)
[512.176185] Starting dynamic subtest: mman
[525.257175] Dynamic subtest mman: SUCCESS (13.100s)
[525.301307] Starting dynamic subtest: dmabuf
[530.224512] Dynamic subtest dmabuf: SUCCESS (4.951s)
[530.284364] Starting dynamic subtest: vma
[534.864190] Dynamic subtest vma: SUCCESS (4.629s)
[534.900225] Starting dynamic subtest: coherency
[547.054109] Dynamic subtest coherency: SUCCESS (12.182s)
[547.082168] Starting dynamic subtest: gtt
[559.426534] Dynamic subtest gtt: SUCCESS (12.372s)
[559.465613] Starting dynamic subtest: gem
[565.410511] Dynamic subtest gem: SUCCESS (5.977s)
[565.442797] Starting dynamic subtest: evict
[577.616104] Dynamic subtest evict: SUCCESS (12.197s)
[577.666183] Starting dynamic subtest: hugepages
[583.122729] Dynamic subtest hugepages: SUCCESS (5.493s)
[583.151543] Starting dynamic subtest: gem_contexts
[614.710939] Dynamic subtest gem_contexts: SUCCESS (31.587s)
[614.752834] Starting dynamic subtest: client
[619.568527] Dynamic subtest client: SUCCESS (4.846s)
[619.609153] Starting dynamic subtest: gem_migrate
[624.303372] Dynamic subtest gem_migrate: SUCCESS (4.729s)
[624.341542] Starting dynamic subtest: reset
[636.794576] Dynamic subtest reset: SUCCESS (12.484s)
[636.830179] Starting dynamic subtest: memory_region
[642.209860] Dynamic subtest memory_region: SUCCESS (5.403s)
[642.270225] Starting dynamic subtest: hangcheck
[670.735395] Dynamic subtest hangcheck: SUCCESS (28.511s)
[670.763532] Starting dynamic subtest: execlists
[682.021196] Dynamic subtest execlists: SUCCESS (11.278s)
[682.051842] Starting dynamic subtest: ring_submission
[687.039083] Dynamic subtest ring_submission: SUCCESS (5.011s)
[687.093709] Starting dynamic subtest: perf
[692.430424] Dynamic subtest perf: SUCCESS (5.389s)
[692.464952] Starting dynamic subtest: slpc
[697.099671] Dynamic subtest slpc: SUCCESS (4.661s)
[697.135230] Starting dynamic subtest: guc
[701.848399] Dynamic subtest guc: SUCCESS (4.740s)
[701.934051] Starting dynamic subtest: guc_multi_lrc
[706.854155] Dynamic subtest guc_multi_lrc: SUCCESS (4.963s)
[706.909295] Starting dynamic subtest: guc_hang
[711.375931] Dynamic subtest guc_hang: SUCCESS (4.518s)
[711.413296] Starting dynamic subtest: late_gt_pm
[716.126523] Dynamic subtest late_gt_pm: SUCCESS (4.747s)
[716.128387] Subtest live: SUCCESS (324.248s)
[716.319528] [162/162] (366s left) dmabuf (all-tests)
[716.551876] Starting subtest: all-tests
[716.555540] Starting dynamic subtest: sanitycheck
[716.630076] Dynamic subtest sanitycheck: SUCCESS (0.075s)
[716.674695] Starting dynamic subtest: dma_fence
[716.898148] Dynamic subtest dma_fence: SUCCESS (0.257s)
[716.937102] Starting dynamic subtest: dma_fence_chain