[96.816123] Initializing watchdogs
[96.816351]   /dev/watchdog0
[96.868645] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[96.900978] [001/161] (960s left) i915_module_load (load)
[97.568997] Starting subtest: load
[101.960619] Subtest load: SUCCESS (4.396s)
[102.105530] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[102.109048] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[102.159155] [002/161] (955s left) core_auth (basic-auth)
[102.454774] Starting subtest: basic-auth
[102.477716] Subtest basic-auth: SUCCESS (0.002s)
[102.634688] [003/161] (955s left) debugfs_test (read_all_entries)
[102.897029] Starting subtest: read_all_entries
[103.034306] Subtest read_all_entries: SUCCESS (0.156s)
[103.337753] [004/161] (954s left) debugfs_test (basic-hwmon)
[103.613029] Starting subtest: basic-hwmon
[103.652285] Subtest basic-hwmon: SKIP (0.000s)
[103.880716] [005/161] (954s left) debugfs_test (sysfs)
[104.170350] Starting subtest: sysfs
[104.310937] Subtest sysfs: SUCCESS (0.178s)
[104.619881] [006/161] (953s left) fbdev (eof)
[104.872998] Starting subtest: eof
[104.893448] Subtest eof: SUCCESS (0.002s)
[105.050527] [007/161] (953s left) fbdev (info)
[105.302643] Starting subtest: info
[105.325290] Subtest info: SUCCESS (0.000s)
[105.508776] [008/161] (953s left) fbdev (nullptr)
[105.733215] Starting subtest: nullptr
[105.754137] Subtest nullptr: SUCCESS (0.000s)
[105.912534] [009/161] (952s left) fbdev (read)
[106.164297] Starting subtest: read
[107.645083] Subtest read: SUCCESS (1.502s)
[107.806956] [010/161] (951s left) fbdev (write)
[108.032734] Starting subtest: write
[108.785638] Subtest write: SUCCESS (0.776s)
[108.962872] [011/161] (950s left) gem_basic (bad-close)
[109.208133] Starting subtest: bad-close
[109.250415] Subtest bad-close: SUCCESS (0.000s)
[109.414667] [012/161] (949s left) gem_basic (create-close)
[109.685727] Starting subtest: create-close
[109.728375] Subtest create-close: SUCCESS (0.000s)
[109.883187] [013/161] (949s left) gem_basic (create-fd-close)
[110.152294] Starting subtest: create-fd-close
[110.201924] Subtest create-fd-close: SUCCESS (0.000s)
[110.365149] [014/161] (949s left) gem_busy (busy@all-engines)
[110.620188] Starting subtest: busy
[110.642144] Starting dynamic subtest: all-engines
[110.712779] Dynamic subtest all-engines: SUCCESS (0.114s)
[110.736760] Subtest busy: SUCCESS (0.115s)
[111.191082] [015/161] (948s left) gem_close_race (basic-process)
[111.452831] Starting subtest: basic-process
[111.562835] Subtest basic-process: SUCCESS (0.120s)
[111.856426] [016/161] (948s left) gem_close_race (basic-threads)
[112.113688] Starting subtest: basic-threads
[113.389350] Subtest basic-threads: SUCCESS (1.259s)
[113.677624] [017/161] (946s left) gem_ctx_create (basic)
[113.929397] Starting subtest: basic
[113.958581] Subtest basic: SUCCESS (0.000s)
[114.226815] [018/161] (946s left) gem_ctx_create (basic-files)
[114.474380] Starting subtest: basic-files
[116.500783] Subtest basic-files: SUCCESS (2.046s)
[116.826076] [019/161] (943s left) gem_ctx_exec (basic)
[117.071563] Starting subtest: basic
[117.095274] Subtest basic: SUCCESS (0.011s)
[117.272090] [020/161] (943s left) gem_exec_basic (basic)
[117.516933] Starting subtest: basic
[117.538242] Starting dynamic subtest: rcs0-smem
[117.559684] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[117.609555] Starting dynamic subtest: bcs0-smem
[117.630719] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[117.683324] Starting dynamic subtest: vcs0-smem
[117.706784] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[117.769651] Starting dynamic subtest: vecs0-smem
[117.791142] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[117.812446] Subtest basic: SUCCESS (0.014s)
[117.976746] [021/161] (942s left) gem_exec_create (basic)
[118.245561] Starting subtest: basic
[118.265678] Starting dynamic subtest: smem
[120.316983] Dynamic subtest smem: SUCCESS (2.090s)
[120.340538] Subtest basic: SUCCESS (2.090s)
[120.616485] [022/161] (940s left) gem_exec_fence (basic-busy)
[121.027048] Starting subtest: basic-busy
[121.049103] Starting dynamic subtest: rcs0
[121.094388] Dynamic subtest rcs0: SUCCESS (0.034s)
[121.150796] Starting dynamic subtest: bcs0
[121.196582] Dynamic subtest bcs0: SUCCESS (0.034s)
[121.258775] Starting dynamic subtest: vcs0
[121.304373] Dynamic subtest vcs0: SUCCESS (0.025s)
[121.347741] Starting dynamic subtest: vecs0
[121.392576] Dynamic subtest vecs0: SUCCESS (0.056s)
[121.415627] Subtest basic-busy: SUCCESS (0.152s)
[122.078636] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[122.104081] [023/161] (938s left) gem_exec_fence (basic-wait)
[122.493296] Starting subtest: basic-wait
[122.519727] Starting dynamic subtest: rcs0
[122.548796] Dynamic subtest rcs0: SUCCESS (0.032s)
[122.611175] Starting dynamic subtest: bcs0
[122.635915] Dynamic subtest bcs0: SUCCESS (0.032s)
[122.705138] Starting dynamic subtest: vcs0
[122.731921] Dynamic subtest vcs0: SUCCESS (0.027s)
[122.779229] Starting dynamic subtest: vecs0
[122.803688] Dynamic subtest vecs0: SUCCESS (0.034s)
[122.830240] Subtest basic-wait: SUCCESS (0.128s)
[123.480846] [024/161] (937s left) gem_exec_fence (basic-await)
[123.844534] Starting subtest: basic-await
[123.865456] Starting dynamic subtest: rcs0
[124.016234] Dynamic subtest rcs0: SUCCESS (0.169s)
[124.061134] Starting dynamic subtest: bcs0
[124.192366] Dynamic subtest bcs0: SUCCESS (0.170s)
[124.239163] Starting dynamic subtest: vcs0
[124.364660] Dynamic subtest vcs0: SUCCESS (0.176s)
[124.413094] Starting dynamic subtest: vecs0
[124.532672] Dynamic subtest vecs0: SUCCESS (0.169s)
[124.556147] Subtest basic-await: SUCCESS (0.688s)
[125.380645] [025/161] (936s left) gem_exec_fence (nb-await)
[125.767614] Starting subtest: nb-await
[125.790232] Starting dynamic subtest: rcs0
[125.849443] Dynamic subtest rcs0: SUCCESS (0.083s)
[125.902119] Starting dynamic subtest: bcs0
[125.926934] Dynamic subtest bcs0: SUCCESS (0.073s)
[125.984955] Starting dynamic subtest: vcs0
[126.006099] Dynamic subtest vcs0: SUCCESS (0.072s)
[126.075154] Starting dynamic subtest: vecs0
[126.097803] Dynamic subtest vecs0: SUCCESS (0.074s)
[126.118825] Subtest nb-await: SUCCESS (0.306s)
[126.910928] [026/161] (934s left) gem_exec_gttfill (basic)
[127.175402] Starting subtest: basic
[157.768616] Subtest basic: SUCCESS (30.594s)
[158.092918] [027/161] (903s left) gem_exec_parallel (engines)
[158.489049] Starting subtest: engines
[158.510248] Starting dynamic subtest: basic
[162.851243] Dynamic subtest basic: SUCCESS (4.190s)
[162.898915] Starting dynamic subtest: contexts
[168.697625] Dynamic subtest contexts: SUCCESS (5.847s)
[168.747615] Starting dynamic subtest: fds
[174.593183] Dynamic subtest fds: SUCCESS (5.894s)
[174.639209] Starting dynamic subtest: userptr
[178.779747] Dynamic subtest userptr: SUCCESS (4.184s)
[178.800720] Subtest engines: SUCCESS (20.118s)
[178.982162] [028/161] (883s left) gem_exec_store (basic)
[179.317572] Starting subtest: basic
[179.340659] Subtest basic: SUCCESS (0.084s)
[179.620650] [029/161] (882s left) gem_flink_basic (bad-flink)
[179.873620] Starting subtest: bad-flink
[179.896104] Subtest bad-flink: SUCCESS (0.000s)
[180.089572] [030/161] (882s left) gem_flink_basic (bad-open)
[180.343406] Starting subtest: bad-open
[180.363688] Subtest bad-open: SUCCESS (0.000s)
[180.535084] [031/161] (882s left) gem_flink_basic (basic)
[180.776643] Starting subtest: basic
[180.799476] Subtest basic: SUCCESS (0.000s)
[180.967905] [032/161] (881s left) gem_flink_basic (double-flink)
[181.191191] Starting subtest: double-flink
[181.211502] Subtest double-flink: SUCCESS (0.000s)
[181.380801] [033/161] (881s left) gem_flink_basic (flink-lifetime)
[181.621983] Starting subtest: flink-lifetime
[181.644941] Subtest flink-lifetime: SUCCESS (0.004s)
[181.815303] [034/161] (881s left) gem_huc_copy (huc-copy)
[182.117412] Starting subtest: huc-copy
[182.173155] Subtest huc-copy: SKIP (0.000s)
[182.362092] [035/161] (880s left) gem_linear_blits (basic)
[182.784376] Starting subtest: basic
[182.812134] Subtest basic: SUCCESS (0.030s)
[183.128474] [036/161] (880s left) gem_mmap (basic)
[183.563121] Starting subtest: basic
[183.671708] Subtest basic: SUCCESS (0.002s)
[183.842766] [037/161] (879s left) gem_mmap_gtt (basic)
[184.111552] Starting subtest: basic
[184.133927] Subtest basic: SUCCESS (0.002s)
[184.301262] [038/161] (879s left) gem_render_linear_blits (basic)
[184.538959] Starting subtest: basic
[184.644044] Subtest basic: SUCCESS (0.098s)
[184.815398] [039/161] (879s left) gem_render_tiled_blits (basic)
[185.092064] Starting subtest: basic
[185.224918] Subtest basic: SUCCESS (0.134s)
[185.395422] [040/161] (878s left) gem_ringfill (basic-all)
[185.958716] Starting subtest: basic-all
[187.168890] Subtest basic-all: SUCCESS (1.238s)
[187.387728] [041/161] (876s left) gem_softpin (allocator-basic)
[187.637667] Starting subtest: allocator-basic
[187.901648] Subtest allocator-basic: SUCCESS (0.284s)
[188.137794] [042/161] (876s left) gem_softpin (allocator-basic-reserve)
[188.400650] Starting subtest: allocator-basic-reserve
[188.639595] Subtest allocator-basic-reserve: SUCCESS (0.284s)
[188.870211] [043/161] (875s left) gem_softpin (safe-alignment)
[189.130834] Starting subtest: safe-alignment
[189.152179] Subtest safe-alignment: SUCCESS (0.038s)
[189.353736] [044/161] (875s left) gem_sync (basic-all)
[189.782855] Starting subtest: basic-all
[192.109404] Subtest basic-all: SUCCESS (2.340s)
[192.282459] [045/161] (872s left) gem_sync (basic-each)
[192.698858] Starting subtest: basic-each
[194.912177] Subtest basic-each: SUCCESS (2.370s)
[195.211689] [046/161] (869s left) gem_tiled_blits (basic)
[195.587318] Starting subtest: basic
[195.629727] Subtest basic: SUCCESS (0.061s)
[195.920555] [047/161] (869s left) gem_tiled_fence_blits (basic)
[196.468358] Starting subtest: basic
[196.493720] Subtest basic: SUCCESS (0.045s)
[196.787665] [048/161] (868s left) gem_tiled_pread_basic
[197.639735] [049/161] (868s left) gem_wait (busy@all-engines)
[197.906860] Starting subtest: busy
[197.929922] Starting dynamic subtest: all-engines
[198.447194] Dynamic subtest all-engines: SUCCESS (0.534s)
[198.470554] Subtest busy: SUCCESS (0.534s)
[198.794502] [050/161] (867s left) gem_wait (wait@all-engines)
[199.086184] Starting subtest: wait
[199.113313] Starting dynamic subtest: all-engines
[200.125650] Dynamic subtest all-engines: SUCCESS (1.046s)
[200.153773] Subtest wait: SUCCESS (1.046s)
[200.471947] [051/161] (865s left) i915_getparams_basic (basic-eu-total)
[200.735192] Starting subtest: basic-eu-total
[200.788475] Subtest basic-eu-total: SUCCESS (0.000s)
[200.984506] [052/161] (865s left) i915_getparams_basic (basic-subslice-total)
[201.240263] Starting subtest: basic-subslice-total
[201.282932] Subtest basic-subslice-total: SUCCESS (0.000s)
[201.455937] [053/161] (865s left) i915_hangman (error-state-basic)
[201.703645] Starting subtest: error-state-basic
[201.726120] Subtest error-state-basic: SUCCESS (0.025s)
[201.935937] [054/161] (864s left) i915_pciid
[202.392279] [055/161] (864s left) kms_addfb_basic (addfb25-4-tiled)
[202.839263] Starting subtest: addfb25-4-tiled
[202.860180] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[203.039899] [056/161] (863s left) kms_addfb_basic (addfb25-bad-modifier)
[203.390552] Starting subtest: addfb25-bad-modifier
[203.413574] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[203.592546] [057/161] (863s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[203.930338] Starting subtest: addfb25-framebuffer-vs-set-tiling
[203.952156] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[204.124854] [058/161] (863s left) kms_addfb_basic (addfb25-modifier-no-flag)
[204.476418] Starting subtest: addfb25-modifier-no-flag
[204.497785] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[204.678667] [059/161] (862s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[205.034381] Starting subtest: addfb25-x-tiled-legacy
[205.055195] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[205.247731] [060/161] (862s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[205.588201] Starting subtest: addfb25-x-tiled-mismatch-legacy
[205.610517] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[205.782075] [061/161] (862s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[206.135505] Starting subtest: addfb25-yf-tiled-legacy
[206.157593] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[206.331478] [062/161] (861s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[206.686273] Starting subtest: addfb25-y-tiled-legacy
[206.706304] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[206.880092] [063/161] (861s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[207.215258] Starting subtest: addfb25-y-tiled-small-legacy
[207.237969] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[207.429807] [064/161] (860s left) kms_addfb_basic (bad-pitch-0)
[207.676165] Starting subtest: bad-pitch-0
[207.697047] Subtest bad-pitch-0: SUCCESS (0.000s)
[207.985162] [065/161] (860s left) kms_addfb_basic (bad-pitch-1024)
[208.252331] Starting subtest: bad-pitch-1024
[208.274185] Subtest bad-pitch-1024: SUCCESS (0.000s)
[208.537773] [066/161] (860s left) kms_addfb_basic (bad-pitch-128)
[208.780859] Starting subtest: bad-pitch-128
[208.800820] Subtest bad-pitch-128: SUCCESS (0.000s)
[209.090878] [067/161] (859s left) kms_addfb_basic (bad-pitch-256)
[209.333898] Starting subtest: bad-pitch-256
[209.354718] Subtest bad-pitch-256: SUCCESS (0.000s)
[209.678837] [068/161] (859s left) kms_addfb_basic (bad-pitch-32)
[209.967993] Starting subtest: bad-pitch-32
[209.992618] Subtest bad-pitch-32: SUCCESS (0.000s)
[210.265390] [069/161] (858s left) kms_addfb_basic (bad-pitch-63)
[210.560517] Starting subtest: bad-pitch-63
[210.586873] Subtest bad-pitch-63: SUCCESS (0.000s)
[210.828108] [070/161] (858s left) kms_addfb_basic (bad-pitch-65536)
[211.070864] Starting subtest: bad-pitch-65536
[211.092595] Subtest bad-pitch-65536: SUCCESS (0.001s)
[211.391529] [071/161] (858s left) kms_addfb_basic (bad-pitch-999)
[211.656550] Starting subtest: bad-pitch-999
[211.677166] Subtest bad-pitch-999: SUCCESS (0.000s)
[211.945406] [072/161] (857s left) kms_addfb_basic (basic)
[212.194103] Starting subtest: basic
[212.215363] Subtest basic: SUCCESS (0.000s)
[212.509614] [073/161] (857s left) kms_addfb_basic (basic-x-tiled-legacy)
[212.748351] Starting subtest: basic-x-tiled-legacy
[212.768361] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[213.101815] [074/161] (857s left) kms_addfb_basic (basic-y-tiled-legacy)
[213.366667] Starting subtest: basic-y-tiled-legacy
[213.387093] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[213.656819] [075/161] (856s left) kms_addfb_basic (bo-too-small)
[214.008646] Starting subtest: bo-too-small
[214.029723] Subtest bo-too-small: SUCCESS (0.001s)
[214.206584] [076/161] (856s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[214.559596] Starting subtest: bo-too-small-due-to-tiling
[214.581984] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[214.757685] [077/161] (855s left) kms_addfb_basic (clobberred-modifier)
[215.018447] Starting subtest: clobberred-modifier
[215.040338] Subtest clobberred-modifier: SUCCESS (0.000s)
[215.303394] [078/161] (855s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[215.559989] Starting subtest: framebuffer-vs-set-tiling
[215.581113] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[215.838107] [079/161] (855s left) kms_addfb_basic (invalid-get-prop)
[216.081157] Starting subtest: invalid-get-prop
[216.102065] Subtest invalid-get-prop: SUCCESS (0.000s)
[216.393924] [080/161] (854s left) kms_addfb_basic (invalid-get-prop-any)
[216.635459] Starting subtest: invalid-get-prop-any
[216.657985] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[216.965233] [081/161] (854s left) kms_addfb_basic (invalid-set-prop)
[217.229632] Starting subtest: invalid-set-prop
[217.251858] Subtest invalid-set-prop: SUCCESS (0.000s)
[217.525838] [082/161] (854s left) kms_addfb_basic (invalid-set-prop-any)
[217.748936] Starting subtest: invalid-set-prop-any
[217.769482] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[218.060133] [083/161] (853s left) kms_addfb_basic (no-handle)
[218.304081] Starting subtest: no-handle
[218.326713] Subtest no-handle: SUCCESS (0.000s)
[218.624256] [084/161] (853s left) kms_addfb_basic (size-max)
[218.978048] Starting subtest: size-max
[219.000308] Subtest size-max: SUCCESS (0.001s)
[219.193697] [085/161] (852s left) kms_addfb_basic (small-bo)
[219.567127] Starting subtest: small-bo
[219.592844] Subtest small-bo: SUCCESS (0.000s)
[219.779555] [086/161] (852s left) kms_addfb_basic (tile-pitch-mismatch)
[220.064101] Starting subtest: tile-pitch-mismatch
[220.091242] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[220.363211] [087/161] (852s left) kms_addfb_basic (too-high)
[220.713315] Starting subtest: too-high
[220.733648] Subtest too-high: SUCCESS (0.002s)
[220.908134] [088/161] (851s left) kms_addfb_basic (too-wide)
[221.264532] Starting subtest: too-wide
[221.285104] Subtest too-wide: SUCCESS (0.000s)
[221.464852] [089/161] (851s left) kms_addfb_basic (unused-handle)
[221.703989] Starting subtest: unused-handle
[221.724820] Subtest unused-handle: SUCCESS (0.000s)
[222.018668] [090/161] (850s left) kms_addfb_basic (unused-modifier)
[222.279216] Starting subtest: unused-modifier
[222.300187] Subtest unused-modifier: SUCCESS (0.000s)
[222.560740] [091/161] (850s left) kms_addfb_basic (unused-offsets)
[222.803517] Starting subtest: unused-offsets
[222.824057] Subtest unused-offsets: SUCCESS (0.000s)
[223.110490] [092/161] (850s left) kms_addfb_basic (unused-pitches)
[223.373975] Starting subtest: unused-pitches
[223.394709] Subtest unused-pitches: SUCCESS (0.000s)
[223.657276] [093/161] (849s left) kms_busy (basic)
[224.025638] Starting subtest: basic
[224.048605] Starting dynamic subtest: flip
[224.865434] Dynamic subtest flip: SUCCESS (0.843s)
[224.921393] Starting dynamic subtest: modeset
[226.308392] Dynamic subtest modeset: SUCCESS (1.455s)
[226.329846] Subtest basic: SUCCESS (2.302s)
[226.801106] [094/161] (846s left) kms_prop_blob (basic)
[227.058389] Starting subtest: basic
[227.079133] Subtest basic: SUCCESS (0.000s)
[227.257950] [095/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[227.620446] Starting subtest: basic-busy-flip-before-cursor-atomic
[227.660874] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[227.833619] [096/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[228.171379] Starting subtest: basic-busy-flip-before-cursor-legacy
[228.213101] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[228.390861] [097/161] (845s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[228.752714] Starting subtest: basic-flip-after-cursor-atomic
[229.741811] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.999s)
[230.202791] [098/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[230.559017] Starting subtest: basic-flip-after-cursor-legacy
[231.549104] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.992s)
[232.015087] [099/161] (842s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[232.365620] Starting subtest: basic-flip-after-cursor-varying-size
[233.354504] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.998s)
[233.816615] [100/161] (840s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[234.151168] Starting subtest: basic-flip-before-cursor-atomic
[235.135506] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.000s)
[235.600226] [101/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[235.953133] Starting subtest: basic-flip-before-cursor-legacy
[236.933204] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.996s)
[237.389625] [102/161] (837s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[237.754790] Starting subtest: basic-flip-before-cursor-varying-size
[238.724184] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.989s)
[239.173780] [103/161] (836s left) kms_dsc (dsc-basic)
[239.567621] Subtest dsc-basic: SKIP (0.000s)
[239.739713] [104/161] (835s left) kms_flip (basic-flip-vs-dpms)
[240.122269] Starting subtest: basic-flip-vs-dpms
[240.144581] Starting dynamic subtest: A-DP1
[241.372513] Dynamic subtest A-DP1: SUCCESS (1.266s)
[241.422796] Starting dynamic subtest: B-DP1
[242.547153] Dynamic subtest B-DP1: SUCCESS (1.174s)
[242.596118] Starting dynamic subtest: C-DP1
[243.736543] Dynamic subtest C-DP1: SUCCESS (1.181s)
[243.758078] Subtest basic-flip-vs-dpms: SUCCESS (3.631s)
[244.218470] [105/161] (831s left) kms_flip (basic-flip-vs-modeset)
[244.601690] Starting subtest: basic-flip-vs-modeset
[244.623899] Starting dynamic subtest: A-DP1
[245.850913] Dynamic subtest A-DP1: SUCCESS (1.273s)
[245.898631] Starting dynamic subtest: B-DP1
[247.023560] Dynamic subtest B-DP1: SUCCESS (1.169s)
[247.074750] Starting dynamic subtest: C-DP1
[248.219435] Dynamic subtest C-DP1: SUCCESS (1.173s)
[248.245081] Subtest basic-flip-vs-modeset: SUCCESS (3.629s)
[248.679224] [106/161] (827s left) kms_flip (basic-flip-vs-wf_vblank)
[249.065911] Starting subtest: basic-flip-vs-wf_vblank
[249.089929] Starting dynamic subtest: A-DP1
[250.482243] Dynamic subtest A-DP1: SUCCESS (1.441s)
[250.531855] Starting dynamic subtest: B-DP1
[251.913445] Dynamic subtest B-DP1: SUCCESS (1.426s)
[251.963312] Starting dynamic subtest: C-DP1
[253.341456] Dynamic subtest C-DP1: SUCCESS (1.423s)
[253.361964] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.302s)
[253.881387] [107/161] (822s left) kms_flip (basic-plain-flip)
[254.261736] Starting subtest: basic-plain-flip
[254.283069] Starting dynamic subtest: A-DP1
[255.420987] Dynamic subtest A-DP1: SUCCESS (1.179s)
[255.468565] Starting dynamic subtest: B-DP1
[256.560592] Dynamic subtest B-DP1: SUCCESS (1.142s)
[256.609628] Starting dynamic subtest: C-DP1
[257.703695] Dynamic subtest C-DP1: SUCCESS (1.140s)
[257.731379] Subtest basic-plain-flip: SUCCESS (3.474s)
[258.239217] [108/161] (817s left) kms_force_connector_basic (force-connector-state)
[258.538900] Subtest force-connector-state: SKIP (0.000s)
[258.712402] [109/161] (817s left) kms_force_connector_basic (force-edid)
[258.978130] Subtest force-edid: SKIP (0.000s)
[259.155591] [110/161] (817s left) kms_force_connector_basic (force-load-detect)
[259.445617] Subtest force-load-detect: SKIP (0.000s)
[259.623350] [111/161] (817s left) kms_force_connector_basic (prune-stale-modes)
[259.905169] Subtest prune-stale-modes: SKIP (0.000s)
[260.082370] [112/161] (816s left) kms_frontbuffer_tracking (basic)
[260.519367] Starting subtest: basic
[264.299443] Subtest basic: SUCCESS (3.845s)
[264.519228] [113/161] (812s left) kms_hdmi_inject (inject-audio)
[264.814673] Subtest inject-audio: SKIP (0.000s)
[264.989595] [114/161] (812s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[265.353112] Starting subtest: compare-crc-sanitycheck-xr24
[265.373063] Starting dynamic subtest: pipe-A-DP-1
[265.817033] Dynamic subtest pipe-A-DP-1: SUCCESS (0.477s)
[265.868073] Starting dynamic subtest: pipe-B-DP-1
[266.453954] Dynamic subtest pipe-B-DP-1: SUCCESS (0.633s)
[266.503036] Starting dynamic subtest: pipe-C-DP-1
[267.141668] Dynamic subtest pipe-C-DP-1: SUCCESS (0.641s)
[267.164163] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.765s)
[267.561300] [115/161] (809s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[267.909320] Starting subtest: compare-crc-sanitycheck-nv12
[267.931481] Starting dynamic subtest: pipe-A-DP-1
[267.972185] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[268.043669] Starting dynamic subtest: pipe-B-DP-1
[268.087936] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[268.156080] Starting dynamic subtest: pipe-C-DP-1
[268.202094] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[268.243318] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[268.419636] [116/161] (809s left) kms_pipe_crc_basic (hang-read-crc)
[268.789405] Starting subtest: hang-read-crc
[268.809461] Starting dynamic subtest: pipe-A-DP-1
[270.086236] Dynamic subtest pipe-A-DP-1: SUCCESS (1.313s)
[270.138484] Starting dynamic subtest: pipe-B-DP-1
[271.577626] Dynamic subtest pipe-B-DP-1: SUCCESS (1.487s)
[271.634923] Starting dynamic subtest: pipe-C-DP-1
[273.055000] Dynamic subtest pipe-C-DP-1: SUCCESS (1.471s)
[273.075670] Subtest hang-read-crc: SUCCESS (4.287s)
[273.496305] [117/161] (804s left) kms_pipe_crc_basic (nonblocking-crc)
[273.861304] Starting subtest: nonblocking-crc
[273.882411] Starting dynamic subtest: pipe-A-DP-1
[274.511424] Dynamic subtest pipe-A-DP-1: SUCCESS (0.644s)
[274.560204] Starting dynamic subtest: pipe-B-DP-1
[275.293961] Dynamic subtest pipe-B-DP-1: SUCCESS (0.794s)
[275.344272] Starting dynamic subtest: pipe-C-DP-1
[276.114850] Dynamic subtest pipe-C-DP-1: SUCCESS (0.799s)
[276.136117] Subtest nonblocking-crc: SUCCESS (2.252s)
[276.538782] [118/161] (801s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[276.890040] Starting subtest: nonblocking-crc-frame-sequence
[276.910348] Starting dynamic subtest: pipe-A-DP-1
[277.531319] Dynamic subtest pipe-A-DP-1: SUCCESS (0.642s)
[277.582218] Starting dynamic subtest: pipe-B-DP-1
[278.321135] Dynamic subtest pipe-B-DP-1: SUCCESS (0.788s)
[278.370407] Starting dynamic subtest: pipe-C-DP-1
[279.150584] Dynamic subtest pipe-C-DP-1: SUCCESS (0.795s)
[279.176088] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.241s)
[279.543943] [119/161] (798s left) kms_pipe_crc_basic (read-crc)
[279.910378] Starting subtest: read-crc
[279.936266] Starting dynamic subtest: pipe-A-DP-1
[280.517992] Dynamic subtest pipe-A-DP-1: SUCCESS (0.599s)
[280.595475] Starting dynamic subtest: pipe-B-DP-1
[281.275701] Dynamic subtest pipe-B-DP-1: SUCCESS (0.762s)
[281.325442] Starting dynamic subtest: pipe-C-DP-1
[282.011556] Dynamic subtest pipe-C-DP-1: SUCCESS (0.748s)
[282.033610] Subtest read-crc: SUCCESS (2.124s)
[282.457757] [120/161] (795s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[282.815265] Starting subtest: read-crc-frame-sequence
[282.834752] Starting dynamic subtest: pipe-A-DP-1
[283.365819] Dynamic subtest pipe-A-DP-1: SUCCESS (0.560s)
[283.432092] Starting dynamic subtest: pipe-B-DP-1
[284.113657] Dynamic subtest pipe-B-DP-1: SUCCESS (0.728s)
[284.162210] Starting dynamic subtest: pipe-C-DP-1
[284.858720] Dynamic subtest pipe-C-DP-1: SUCCESS (0.734s)
[284.880977] Subtest read-crc-frame-sequence: SUCCESS (2.037s)
[285.282712] [121/161] (793s left) kms_pm_backlight (basic-brightness)
[285.673965] Subtest basic-brightness: SKIP (0.000s)
[285.848423] [122/161] (792s left) kms_pm_rpm (basic-pci-d3-state)
[287.671454] Starting subtest: basic-pci-d3-state
[288.337758] Subtest basic-pci-d3-state: SUCCESS (0.667s)
[288.662809] [123/161] (790s left) kms_pm_rpm (basic-rte)
[288.887150] Starting subtest: basic-rte
[291.133751] Subtest basic-rte: SUCCESS (2.254s)
[292.104364] [124/161] (786s left) kms_psr (psr-primary-page-flip)
[292.452980] Subtest psr-primary-page-flip: SKIP (0.000s)
[292.630178] [125/161] (786s left) kms_psr (psr-cursor-plane-move)
[292.991574] Subtest psr-cursor-plane-move: SKIP (0.000s)
[293.172765] [126/161] (786s left) kms_psr (psr-sprite-plane-onoff)
[293.548967] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[293.727324] [127/161] (785s left) kms_psr (psr-primary-mmap-gtt)
[294.118619] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[294.296188] [128/161] (785s left) kms_setmode (basic-clone-single-crtc)
[294.549080] Starting subtest: basic-clone-single-crtc
[294.614991] Subtest basic-clone-single-crtc: SKIP (0.000s)
[294.793642] [129/161] (785s left) i915_pm_rps (basic-api)
[295.067144] Starting subtest: basic-api
[295.093898] Subtest basic-api: SUCCESS (0.007s)
[295.290849] [130/161] (784s left) prime_self_import (basic-llseek-bad)
[295.540050] Starting subtest: basic-llseek-bad
[295.584700] Subtest basic-llseek-bad: SUCCESS (0.005s)
[295.760206] [131/161] (784s left) prime_self_import (basic-llseek-size)
[296.005680] Starting subtest: basic-llseek-size
[296.050186] Subtest basic-llseek-size: SUCCESS (0.008s)
[296.230402] [132/161] (784s left) prime_self_import (basic-with_fd_dup)
[296.473945] Starting subtest: basic-with_fd_dup
[296.516511] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[296.694850] [133/161] (783s left) prime_self_import (basic-with_one_bo)
[296.937928] Starting subtest: basic-with_one_bo
[296.979753] Subtest basic-with_one_bo: SUCCESS (0.009s)
[297.157244] [134/161] (783s left) prime_self_import (basic-with_one_bo_two_files)
[297.393066] Starting subtest: basic-with_one_bo_two_files
[297.437899] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[297.614819] [135/161] (783s left) prime_self_import (basic-with_two_bos)
[297.857987] Starting subtest: basic-with_two_bos
[297.900408] Subtest basic-with_two_bos: SUCCESS (0.008s)
[298.090219] [136/161] (783s left) prime_vgem (basic-fence-flip)
[298.382665] Starting subtest: basic-fence-flip
[299.021504] Subtest basic-fence-flip: SUCCESS (0.673s)
[299.249157] [137/161] (782s left) prime_vgem (basic-fence-mmap)
[299.533536] Starting subtest: basic-fence-mmap
[299.554690] Subtest basic-fence-mmap: SUCCESS (0.071s)
[299.767800] [138/161] (781s left) prime_vgem (basic-fence-read)
[300.051205] Starting subtest: basic-fence-read
[300.150042] Subtest basic-fence-read: SUCCESS (0.145s)
[300.352357] [139/161] (781s left) prime_vgem (basic-gtt)
[300.659938] Starting subtest: basic-gtt
[300.688077] Subtest basic-gtt: SUCCESS (0.031s)
[300.919943] [140/161] (781s left) prime_vgem (basic-read)
[301.238111] Starting subtest: basic-read
[301.262803] Subtest basic-read: SUCCESS (0.067s)
[301.488914] [141/161] (780s left) prime_vgem (basic-write)
[301.821865] Starting subtest: basic-write
[301.851969] Subtest basic-write: SUCCESS (0.094s)
[302.061137] [142/161] (780s left) vgem_basic (setversion)
[302.332418] Starting subtest: setversion
[302.377145] Subtest setversion: SUCCESS (0.000s)
[302.553803] [143/161] (780s left) vgem_basic (create)
[302.816399] Starting subtest: create
[302.839420] Subtest create: SUCCESS (0.001s)
[303.013675] [144/161] (779s left) vgem_basic (debugfs)
[303.261949] Starting subtest: debugfs
[303.282504] Subtest debugfs: SUCCESS (0.001s)
[303.461588] [145/161] (779s left) vgem_basic (dmabuf-export)
[303.729706] Starting subtest: dmabuf-export
[303.771122] Subtest dmabuf-export: SUCCESS (0.012s)
[303.948442] [146/161] (779s left) vgem_basic (dmabuf-fence)
[304.213191] Starting subtest: dmabuf-fence
[304.235368] Subtest dmabuf-fence: SUCCESS (0.002s)
[304.412888] [147/161] (778s left) vgem_basic (dmabuf-fence-before)
[304.659589] Starting subtest: dmabuf-fence-before
[304.681589] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[304.857622] [148/161] (778s left) vgem_basic (dmabuf-mmap)
[305.123756] Starting subtest: dmabuf-mmap
[305.144564] Subtest dmabuf-mmap: SUCCESS (0.037s)
[305.322366] [149/161] (778s left) vgem_basic (mmap)
[305.586999] Starting subtest: mmap
[305.608760] Subtest mmap: SUCCESS (0.024s)
[305.785963] [150/161] (778s left) vgem_basic (second-client)
[306.028239] Starting subtest: second-client
[306.075279] Subtest second-client: SUCCESS (0.032s)
[306.260586] [151/161] (777s left) vgem_basic (sysfs)
[306.522979] Starting subtest: sysfs
[306.545351] Subtest sysfs: SUCCESS (0.001s)
[306.726858] [152/161] (777s left) core_hotunplug (unbind-rebind)
[307.115359] Starting subtest: unbind-rebind
[311.921114] Subtest unbind-rebind: SUCCESS (4.803s)
[312.102333] [153/161] (772s left) vgem_basic (unload)
[312.369498] Starting subtest: unload
[341.769419] Subtest unload: SUCCESS (29.251s)
[341.992332] [154/161] (742s left) i915_module_load (reload)
[342.251631] Starting subtest: reload
[348.530209] Subtest reload: SUCCESS (6.309s)
[348.778312] [155/161] (736s left) gem_lmem_swapping (basic)
[349.110061] Subtest basic: SKIP (0.000s)
[349.291666] [156/161] (735s left) gem_lmem_swapping (parallel-random-engines)
[349.562701] Subtest parallel-random-engines: SKIP (0.000s)
[349.741791] [157/161] (735s left) gem_lmem_swapping (random-engines)
[350.017581] Subtest random-engines: SKIP (0.000s)
[350.200392] [158/161] (735s left) gem_lmem_swapping (verify-random)
[350.473489] Subtest verify-random: SKIP (0.000s)
[350.648829] [159/161] (735s left) i915_pm_rpm (module-reload)
[352.828957] Starting subtest: module-reload
[367.539208] Subtest module-reload: SUCCESS (14.734s)
[367.667366] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[367.671229] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[367.695653] [160/161] (718s left) i915_selftest (live)
[367.950753] Starting subtest: live
[367.971922] Starting dynamic subtest: sanitycheck
[371.935063] Dynamic subtest sanitycheck: SUCCESS (3.980s)
[371.994552] Starting dynamic subtest: uncore
[375.938167] Dynamic subtest uncore: SUCCESS (4.001s)
[375.997898] Starting dynamic subtest: workarounds
[380.343300] Dynamic subtest workarounds: SUCCESS (4.398s)
[380.386048] Starting dynamic subtest: gt_engines
[384.894569] Dynamic subtest gt_engines: SUCCESS (4.548s)
[384.939848] Starting dynamic subtest: gt_timelines
[408.642923] Dynamic subtest gt_timelines: SUCCESS (23.742s)
[408.705874] Starting dynamic subtest: gt_contexts
[412.747825] Dynamic subtest gt_contexts: SUCCESS (4.102s)
[412.790395] Starting dynamic subtest: gt_lrc
[419.602152] Dynamic subtest gt_lrc: SUCCESS (6.845s)
[419.664751] Starting dynamic subtest: gt_mocs
[423.775407] Dynamic subtest gt_mocs: SUCCESS (4.170s)
[423.819879] Starting dynamic subtest: gt_pm
[436.997474] Dynamic subtest gt_pm: SUCCESS (13.217s)
[437.053647] Starting dynamic subtest: gt_heartbeat
[440.835537] Dynamic subtest gt_heartbeat: SUCCESS (3.836s)
[440.898904] Starting dynamic subtest: gt_tlb
[445.640481] Dynamic subtest gt_tlb: SUCCESS (4.800s)
[445.701543] Starting dynamic subtest: requests
[455.989974] Dynamic subtest requests: SUCCESS (10.343s)
[456.051938] Starting dynamic subtest: migrate
[463.079638] Dynamic subtest migrate: SUCCESS (7.087s)
[463.167247] Starting dynamic subtest: active
[467.106902] Dynamic subtest active: SUCCESS (4.020s)
[467.151664] Starting dynamic subtest: objects
[473.323675] Dynamic subtest objects: SUCCESS (6.213s)
[473.386398] Starting dynamic subtest: mman
[483.753494] Dynamic subtest mman: SUCCESS (10.422s)
[483.815644] Starting dynamic subtest: dmabuf
[487.769363] Dynamic subtest dmabuf: SUCCESS (4.004s)
[487.830981] Starting dynamic subtest: vma
[491.852170] Dynamic subtest vma: SUCCESS (4.085s)
[491.912436] Starting dynamic subtest: coherency
[505.123278] Dynamic subtest coherency: SUCCESS (13.268s)
[505.166090] Starting dynamic subtest: gtt
[516.551327] Dynamic subtest gtt: SUCCESS (11.424s)
[516.594086] Starting dynamic subtest: gem
[521.930794] Dynamic subtest gem: SUCCESS (5.373s)
[521.985263] Starting dynamic subtest: evict
[530.854085] Dynamic subtest evict: SUCCESS (8.919s)
[530.898470] Starting dynamic subtest: hugepages
[541.341735] Dynamic subtest hugepages: SUCCESS (10.484s)
[541.384817] Starting dynamic subtest: gem_contexts
[568.391978] Dynamic subtest gem_contexts: SUCCESS (26.861s)
[568.439234] Starting dynamic subtest: client
[572.201913] Dynamic subtest client: SUCCESS (3.990s)
[572.246141] Starting dynamic subtest: gem_migrate
[576.223084] Dynamic subtest gem_migrate: SUCCESS (4.016s)
[576.285103] Starting dynamic subtest: reset
[592.484132] Dynamic subtest reset: SUCCESS (16.260s)
[592.545378] Starting dynamic subtest: memory_region
[596.540702] Dynamic subtest memory_region: SUCCESS (4.053s)
[596.583472] Starting dynamic subtest: hangcheck
[623.708328] Dynamic subtest hangcheck: SUCCESS (27.163s)
[623.752630] Starting dynamic subtest: execlists
[638.459143] Dynamic subtest execlists: SUCCESS (14.747s)
[638.521074] Starting dynamic subtest: ring_submission
[642.245923] Dynamic subtest ring_submission: SUCCESS (3.783s)
[642.291283] Starting dynamic subtest: perf
[646.263215] Dynamic subtest perf: SUCCESS (4.011s)
[646.305887] Starting dynamic subtest: slpc
[649.960570] Dynamic subtest slpc: SUCCESS (3.692s)
[650.044513] Starting dynamic subtest: guc
[653.928993] Dynamic subtest guc: SUCCESS (3.960s)
[653.981335] Starting dynamic subtest: guc_multi_lrc
[657.900074] Dynamic subtest guc_multi_lrc: SUCCESS (3.971s)
[657.959928] Starting dynamic subtest: guc_hang
[661.826791] Dynamic subtest guc_hang: SUCCESS (3.920s)
[661.887160] Starting dynamic subtest: late_gt_pm
[666.018403] Dynamic subtest late_gt_pm: SUCCESS (4.192s)
[666.038672] Subtest live: SUCCESS (298.069s)
[666.195975] [161/161] (419s left) dmabuf (all-tests)
[666.465133] Starting subtest: all-tests
[666.486212] Starting dynamic subtest: sanitycheck
[666.557101] Dynamic subtest sanitycheck: SUCCESS (0.089s)
[666.603258] Starting dynamic subtest: dma_fence
[666.816939] Dynamic subtest dma_fence: SUCCESS (0.255s)
[666.861752] Starting dynamic subtest: dma_fence_chain
[687.006371] Dynamic subtest dma_fence_chain: SUCCESS (20.183s)
[687.067672] Starting dynamic subtest: dma_fence_unwrap
[687.090344] Dynamic subtest dma_fence_unwrap: SUCCESS (0.079s)
[687.151849] Starting dynamic subtest: dma_resv
[687.213571] Dynamic subtest dma_resv: SUCCESS (0.123s)
[687.237354] Subtest all-tests: SUCCESS (20.753s)
[687.367600] Closing watchdogs
Done.