[97.434569] Initializing watchdogs
[97.435212]   /dev/watchdog0
[97.465221] [001/165] (960s left) i915_module_load (load)
[97.914702] Starting subtest: load
[102.338798] Subtest load: SUCCESS (4.424s)
[102.464453] [002/165] (955s left) core_auth (basic-auth)
[103.401428] Starting subtest: basic-auth
[103.405496] Subtest basic-auth: SUCCESS (0.005s)
[103.533755] [003/165] (954s left) debugfs_test (read_all_entries)
[103.972623] Starting subtest: read_all_entries
[104.144846] Subtest read_all_entries: SUCCESS (0.170s)
[104.329338] [004/165] (953s left) debugfs_test (basic-hwmon)
[104.745238] Starting subtest: basic-hwmon
[104.753487] Subtest basic-hwmon: SKIP (0.000s)
[104.933992] [005/165] (953s left) fbdev (eof)
[105.355779] Starting subtest: eof
[105.359760] Subtest eof: SUCCESS (0.004s)
[105.477843] [006/165] (952s left) fbdev (info)
[105.887639] Starting subtest: info
[105.891435] Subtest info: SUCCESS (0.000s)
[106.011192] [007/165] (952s left) fbdev (nullptr)
[106.417815] Starting subtest: nullptr
[106.423186] Subtest nullptr: SUCCESS (0.000s)
[106.574444] [008/165] (951s left) fbdev (read)
[106.984281] Starting subtest: read
[107.281468] Subtest read: SUCCESS (0.297s)
[107.392413] [009/165] (951s left) fbdev (write)
[107.801844] Starting subtest: write
[107.979208] Subtest write: SUCCESS (0.178s)
[108.095109] [010/165] (950s left) gem_basic (bad-close)
[108.499982] Starting subtest: bad-close
[108.508137] Subtest bad-close: SUCCESS (0.000s)
[108.626310] [011/165] (950s left) gem_basic (create-close)
[109.060732] Starting subtest: create-close
[109.074657] Subtest create-close: SUCCESS (0.001s)
[109.200737] [012/165] (949s left) gem_basic (create-fd-close)
[109.610598] Starting subtest: create-fd-close
[109.618338] Subtest create-fd-close: SUCCESS (0.001s)
[109.749108] [013/165] (949s left) gem_busy (busy@all-engines)
[110.234966] Starting subtest: busy
[110.239638] Starting dynamic subtest: all-engines
[110.344850] Dynamic subtest all-engines: SUCCESS (0.113s)
[110.348690] Subtest busy: SUCCESS (0.115s)
[110.848160] [014/165] (948s left) gem_close_race (basic-process)
[111.334425] Starting subtest: basic-process
[111.510215] Subtest basic-process: SUCCESS (0.177s)
[111.779742] [015/165] (947s left) gem_close_race (basic-threads)
[112.269656] Starting subtest: basic-threads
[113.662151] Subtest basic-threads: SUCCESS (1.396s)
[113.936158] [016/165] (945s left) gem_ctx_create (basic)
[114.358708] Subtest basic: SKIP (0.000s)
[114.451134] [017/165] (944s left) gem_ctx_create (basic-files)
[114.863754] Subtest basic-files: SKIP (0.000s)
[114.952177] [018/165] (944s left) gem_ctx_exec (basic)
[115.372124] Subtest basic: SKIP (0.000s)
[115.501784] [019/165] (943s left) gem_exec_basic (basic)
[115.950919] Starting subtest: basic
[115.955190] Starting dynamic subtest: rcs0-smem
[115.958437] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[115.961436] Subtest basic: SUCCESS (0.006s)
[116.232919] [020/165] (943s left) gem_exec_create (basic)
[116.700765] Starting subtest: basic
[116.706340] Starting dynamic subtest: smem
[118.842084] Dynamic subtest smem: SUCCESS (2.140s)
[118.846597] Subtest basic: SUCCESS (2.142s)
[119.117077] [021/165] (940s left) gem_exec_fence (basic-busy)
[119.765725] Starting subtest: basic-busy
[119.770666] Starting dynamic subtest: rcs0
[119.792181] Dynamic subtest rcs0: SUCCESS (0.023s)
[119.795885] Subtest basic-busy: SUCCESS (0.026s)
[120.668228] [022/165] (938s left) gem_exec_fence (basic-wait)
[121.338520] Starting subtest: basic-wait
[121.342327] Starting dynamic subtest: rcs0
[121.363654] Dynamic subtest rcs0: SUCCESS (0.024s)
[121.366771] Subtest basic-wait: SUCCESS (0.027s)
[122.055278] [023/165] (937s left) gem_exec_fence (basic-await)
[122.717331] Starting subtest: basic-await
[122.721944] Starting dynamic subtest: rcs0
[122.832499] Dynamic subtest rcs0: SUCCESS (0.112s)
[122.836721] Subtest basic-await: SUCCESS (0.116s)
[123.542166] [024/165] (936s left) gem_exec_fence (nb-await)
[124.192738] Starting subtest: nb-await
[124.197641] Starting dynamic subtest: rcs0
[124.257825] Dynamic subtest rcs0: SUCCESS (0.063s)
[124.262222] Subtest nb-await: SUCCESS (0.066s)
[124.939292] [025/165] (934s left) gem_exec_gttfill (basic)
[125.418232] Starting subtest: basic
[140.123271] Subtest basic: SUCCESS (14.709s)
[140.459980] [026/165] (919s left) gem_exec_parallel (engines)
[141.318915] Starting subtest: engines
[141.322469] Starting dynamic subtest: basic
[144.744225] Dynamic subtest basic: SUCCESS (3.426s)
[144.787344] Starting dynamic subtest: contexts
[144.793134] Dynamic subtest contexts: SKIP (0.003s)
[144.827424] Starting dynamic subtest: fds
[144.832994] Dynamic subtest fds: SKIP (0.003s)
[144.866657] Starting dynamic subtest: userptr
[148.250112] Dynamic subtest userptr: SUCCESS (3.495s)
[148.253432] Subtest engines: SUCCESS (6.933s)
[148.531145] [027/165] (911s left) gem_exec_store (basic)
[149.002603] Starting subtest: basic
[149.059499] Subtest basic: SUCCESS (0.057s)
[149.347873] [028/165] (910s left) gem_flink_basic (bad-flink)
[149.761180] Starting subtest: bad-flink
[149.765619] Subtest bad-flink: SUCCESS (0.000s)
[149.894902] [029/165] (910s left) gem_flink_basic (bad-open)
[150.309238] Starting subtest: bad-open
[150.313792] Subtest bad-open: SUCCESS (0.000s)
[150.440335] [030/165] (909s left) gem_flink_basic (basic)
[150.852974] Starting subtest: basic
[150.858109] Subtest basic: SUCCESS (0.001s)
[150.990402] [031/165] (909s left) gem_flink_basic (double-flink)
[151.397485] Starting subtest: double-flink
[151.401293] Subtest double-flink: SUCCESS (0.000s)
[151.544473] [032/165] (908s left) gem_flink_basic (flink-lifetime)
[151.956144] Starting subtest: flink-lifetime
[151.960382] Subtest flink-lifetime: SUCCESS (0.007s)
[152.106074] [033/165] (908s left) gem_huc_copy (huc-copy)
[152.534634] Subtest huc-copy: SKIP (0.000s)
[152.649754] [034/165] (908s left) gem_linear_blits (basic)
[153.497518] Starting subtest: basic
[153.550228] Subtest basic: SUCCESS (0.053s)
[153.779236] [035/165] (906s left) gem_mmap (basic)
[154.485130] Starting subtest: basic
[154.500698] Subtest basic: SUCCESS (0.003s)
[154.645162] [036/165] (906s left) gem_mmap_gtt (basic)
[155.052693] Starting subtest: basic
[155.057658] Subtest basic: SUCCESS (0.004s)
[155.182074] [037/165] (905s left) gem_render_linear_blits (basic)
[155.597888] Starting subtest: basic
[155.723879] Subtest basic: SUCCESS (0.127s)
[155.850962] [038/165] (905s left) gem_render_tiled_blits (basic)
[156.266336] Starting subtest: basic
[156.407566] Subtest basic: SUCCESS (0.138s)
[156.532373] [039/165] (904s left) gem_ringfill (basic-all)
[156.993187] Starting subtest: basic-all
[158.181937] Subtest basic-all: SUCCESS (1.188s)
[158.332425] [040/165] (902s left) gem_softpin (allocator-basic)
[158.752109] Subtest allocator-basic: SKIP (0.000s)
[158.880481] [041/165] (902s left) gem_softpin (allocator-basic-reserve)
[159.304758] Subtest allocator-basic-reserve: SKIP (0.000s)
[159.436258] [042/165] (901s left) gem_softpin (safe-alignment)
[159.858215] Starting subtest: safe-alignment
[159.890278] Subtest safe-alignment: SUCCESS (0.037s)
[160.045482] [043/165] (901s left) gem_sync (basic-all)
[160.516703] Starting subtest: basic-all
[162.846537] Subtest basic-all: SUCCESS (2.330s)
[163.133910] [044/165] (898s left) gem_sync (basic-each)
[163.594831] Starting subtest: basic-each
[165.920789] Subtest basic-each: SUCCESS (2.326s)
[166.201302] [045/165] (895s left) gem_tiled_blits (basic)
[166.879188] Starting subtest: basic
[166.971330] Subtest basic: SUCCESS (0.097s)
[167.186303] [046/165] (894s left) gem_tiled_fence_blits (basic)
[168.193708] Starting subtest: basic
[168.262161] Subtest basic: SUCCESS (0.072s)
[168.492168] [047/165] (893s left) gem_tiled_pread_basic
[171.004160] [048/165] (890s left) gem_wait (busy@all-engines)
[171.505987] Starting subtest: busy
[171.510061] Starting dynamic subtest: all-engines
[172.042526] Dynamic subtest all-engines: SUCCESS (0.535s)
[172.046855] Subtest busy: SUCCESS (0.539s)
[172.455979] [049/165] (889s left) gem_wait (wait@all-engines)
[172.966138] Starting subtest: wait
[172.970821] Starting dynamic subtest: all-engines
[174.005594] Dynamic subtest all-engines: SUCCESS (1.040s)
[174.009239] Subtest wait: SUCCESS (1.043s)
[174.408616] [050/165] (887s left) i915_getparams_basic (basic-eu-total)
[174.826091] Starting subtest: basic-eu-total
[174.833705] Subtest basic-eu-total: SUCCESS (0.000s)
[174.952969] [051/165] (887s left) i915_getparams_basic (basic-subslice-total)
[175.352821] Starting subtest: basic-subslice-total
[175.360651] Subtest basic-subslice-total: SUCCESS (0.000s)
[175.488696] [052/165] (886s left) i915_hangman (error-state-basic)
[175.927365] Starting subtest: error-state-basic
[176.291337] Subtest error-state-basic: SUCCESS (0.351s)
[176.415840] [053/165] (885s left) i915_pciid
[176.969240] [054/165] (885s left) kms_addfb_basic (addfb25-4-tiled)
[177.465643] Starting subtest: addfb25-4-tiled
[177.469712] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[177.612185] [055/165] (884s left) kms_addfb_basic (addfb25-bad-modifier)
[178.041474] Starting subtest: addfb25-bad-modifier
[178.046137] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[178.254825] [056/165] (884s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[178.701825] Starting subtest: addfb25-framebuffer-vs-set-tiling
[178.706302] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[178.904195] [057/165] (883s left) kms_addfb_basic (addfb25-modifier-no-flag)
[179.313291] Starting subtest: addfb25-modifier-no-flag
[179.317426] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[179.531652] [058/165] (883s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[179.955886] Starting subtest: addfb25-x-tiled-legacy
[179.959327] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[180.153867] [059/165] (882s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[180.576946] Starting subtest: addfb25-x-tiled-mismatch-legacy
[180.582710] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[180.795521] [060/165] (882s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[181.252552] Starting subtest: addfb25-yf-tiled-legacy
[181.257739] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[181.416169] [061/165] (881s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[181.908528] Starting subtest: addfb25-y-tiled-legacy
[181.911914] Subtest addfb25-y-tiled-legacy: SUCCESS (0.002s)
[182.062434] [062/165] (881s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[182.565671] Starting subtest: addfb25-y-tiled-small-legacy
[182.572791] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[182.721278] [063/165] (880s left) kms_addfb_basic (bad-pitch-0)
[183.128240] Starting subtest: bad-pitch-0
[183.133586] Subtest bad-pitch-0: SUCCESS (0.000s)
[183.341963] [064/165] (880s left) kms_addfb_basic (bad-pitch-1024)
[183.759294] Starting subtest: bad-pitch-1024
[183.763399] Subtest bad-pitch-1024: SUCCESS (0.000s)
[183.955572] [065/165] (879s left) kms_addfb_basic (bad-pitch-128)
[184.364991] Starting subtest: bad-pitch-128
[184.369823] Subtest bad-pitch-128: SUCCESS (0.000s)
[184.551833] [066/165] (879s left) kms_addfb_basic (bad-pitch-256)
[184.976458] Starting subtest: bad-pitch-256
[184.980522] Subtest bad-pitch-256: SUCCESS (0.000s)
[185.159842] [067/165] (878s left) kms_addfb_basic (bad-pitch-32)
[185.564662] Starting subtest: bad-pitch-32
[185.569910] Subtest bad-pitch-32: SUCCESS (0.000s)
[185.778757] [068/165] (878s left) kms_addfb_basic (bad-pitch-63)
[186.216389] Starting subtest: bad-pitch-63
[186.220627] Subtest bad-pitch-63: SUCCESS (0.000s)
[186.412407] [069/165] (877s left) kms_addfb_basic (bad-pitch-65536)
[186.832585] Starting subtest: bad-pitch-65536
[186.836258] Subtest bad-pitch-65536: SUCCESS (0.002s)
[187.024407] [070/165] (876s left) kms_addfb_basic (bad-pitch-999)
[187.440270] Starting subtest: bad-pitch-999
[187.443439] Subtest bad-pitch-999: SUCCESS (0.000s)
[187.668113] [071/165] (876s left) kms_addfb_basic (basic)
[188.088752] Starting subtest: basic
[188.092589] Subtest basic: SUCCESS (0.000s)
[188.308244] [072/165] (875s left) kms_addfb_basic (basic-x-tiled-legacy)
[188.718211] Starting subtest: basic-x-tiled-legacy
[188.722460] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[188.908965] [073/165] (875s left) kms_addfb_basic (basic-y-tiled-legacy)
[189.337430] Starting subtest: basic-y-tiled-legacy
[189.341588] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[189.541243] [074/165] (874s left) kms_addfb_basic (bo-too-small)
[189.998765] Starting subtest: bo-too-small
[190.005124] Subtest bo-too-small: SUCCESS (0.002s)
[190.143547] [075/165] (874s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[190.602583] Starting subtest: bo-too-small-due-to-tiling
[190.607780] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[190.760258] [076/165] (873s left) kms_addfb_basic (clobberred-modifier)
[191.173575] Starting subtest: clobberred-modifier
[191.176910] Subtest clobberred-modifier: SUCCESS (0.001s)
[191.412798] [077/165] (873s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[191.837263] Starting subtest: framebuffer-vs-set-tiling
[191.841585] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[192.028757] [078/165] (872s left) kms_addfb_basic (invalid-get-prop)
[192.449797] Starting subtest: invalid-get-prop
[192.454299] Subtest invalid-get-prop: SUCCESS (0.000s)
[192.640444] [079/165] (872s left) kms_addfb_basic (invalid-get-prop-any)
[193.050682] Starting subtest: invalid-get-prop-any
[193.055083] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[193.246936] [080/165] (871s left) kms_addfb_basic (invalid-set-prop)
[193.669908] Starting subtest: invalid-set-prop
[193.673892] Subtest invalid-set-prop: SUCCESS (0.000s)
[193.863985] [081/165] (871s left) kms_addfb_basic (invalid-set-prop-any)
[194.274620] Starting subtest: invalid-set-prop-any
[194.278925] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[194.464679] [082/165] (870s left) kms_addfb_basic (no-handle)
[194.880786] Starting subtest: no-handle
[194.884925] Subtest no-handle: SUCCESS (0.000s)
[195.077141] [083/165] (870s left) kms_addfb_basic (size-max)
[195.543695] Starting subtest: size-max
[195.547752] Subtest size-max: SUCCESS (0.001s)
[195.698314] [084/165] (869s left) kms_addfb_basic (small-bo)
[196.170929] Starting subtest: small-bo
[196.175706] Subtest small-bo: SUCCESS (0.000s)
[196.320564] [085/165] (869s left) kms_addfb_basic (tile-pitch-mismatch)
[196.746667] Starting subtest: tile-pitch-mismatch
[196.750950] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[196.951757] [086/165] (868s left) kms_addfb_basic (too-high)
[197.424538] Starting subtest: too-high
[197.429879] Subtest too-high: SUCCESS (0.004s)
[197.579811] [087/165] (867s left) kms_addfb_basic (too-wide)
[198.044346] Starting subtest: too-wide
[198.049543] Subtest too-wide: SUCCESS (0.000s)
[198.179215] [088/165] (867s left) kms_addfb_basic (unused-handle)
[198.588972] Starting subtest: unused-handle
[198.593732] Subtest unused-handle: SUCCESS (0.000s)
[198.782882] [089/165] (866s left) kms_addfb_basic (unused-modifier)
[199.196827] Starting subtest: unused-modifier
[199.201577] Subtest unused-modifier: SUCCESS (0.000s)
[199.415729] [090/165] (866s left) kms_addfb_basic (unused-offsets)
[199.828313] Starting subtest: unused-offsets
[199.832403] Subtest unused-offsets: SUCCESS (0.000s)
[200.024876] [091/165] (865s left) kms_addfb_basic (unused-pitches)
[200.446815] Starting subtest: unused-pitches
[200.451673] Subtest unused-pitches: SUCCESS (0.000s)
[200.627278] [092/165] (865s left) kms_busy (basic)
[201.105411] Starting subtest: basic
[201.109995] Starting dynamic subtest: flip
[202.536217] Dynamic subtest flip: SUCCESS (1.425s)
[202.582837] Starting dynamic subtest: modeset
[202.588769] Dynamic subtest modeset: SKIP (0.000s)
[202.591874] Subtest basic: SUCCESS (1.431s)
[202.829787] [093/165] (863s left) kms_prop_blob (basic)
[203.245864] Starting subtest: basic
[203.249642] Subtest basic: SUCCESS (0.000s)
[203.384240] [094/165] (862s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[203.849545] Starting subtest: basic-busy-flip-before-cursor-atomic
[203.863185] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.014s)
[204.003404] [095/165] (862s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[204.461218] Starting subtest: basic-busy-flip-before-cursor-legacy
[205.738468] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (1.272s)
[206.005830] [096/165] (860s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[206.461190] Starting subtest: basic-flip-after-cursor-atomic
[206.469481] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s)
[206.599850] [097/165] (859s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[207.044450] Starting subtest: basic-flip-after-cursor-legacy
[208.298879] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.254s)
[208.565829] [098/165] (857s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[209.019166] Starting subtest: basic-flip-after-cursor-varying-size
[210.288755] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.271s)
[210.550441] [099/165] (856s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[211.021794] Starting subtest: basic-flip-before-cursor-atomic
[211.029365] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s)
[211.160854] [100/165] (855s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[211.620495] Starting subtest: basic-flip-before-cursor-legacy
[212.905267] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.286s)
[213.184971] [101/165] (853s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[213.635910] Starting subtest: basic-flip-before-cursor-varying-size
[214.893318] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.256s)
[215.178505] [102/165] (851s left) kms_dsc (dsc-basic)
[215.628496] Subtest dsc-basic: SKIP (0.000s)
[215.738772] [103/165] (851s left) kms_flip (basic-flip-vs-dpms)
[216.175214] Starting subtest: basic-flip-vs-dpms
[216.179734] Starting dynamic subtest: A-VGA1
[217.931929] Dynamic subtest A-VGA1: SUCCESS (1.753s)
[217.980078] Starting dynamic subtest: B-VGA1
[219.415200] Dynamic subtest B-VGA1: SUCCESS (1.482s)
[219.422296] Subtest basic-flip-vs-dpms: SUCCESS (3.243s)
[219.832980] [104/165] (847s left) kms_flip (basic-flip-vs-modeset)
[220.329910] Starting subtest: basic-flip-vs-modeset
[220.335767] Starting dynamic subtest: A-VGA1
[221.909434] Dynamic subtest A-VGA1: SUCCESS (1.574s)
[221.959240] Starting dynamic subtest: B-VGA1
[223.222227] Dynamic subtest B-VGA1: SUCCESS (1.311s)
[223.228571] Subtest basic-flip-vs-modeset: SUCCESS (2.891s)
[223.587888] [105/165] (843s left) kms_flip (basic-flip-vs-wf_vblank)
[224.053627] Starting subtest: basic-flip-vs-wf_vblank
[224.058593] Starting dynamic subtest: A-VGA1
[225.949609] Dynamic subtest A-VGA1: SUCCESS (1.889s)
[225.996498] Starting dynamic subtest: B-VGA1
[227.619696] Dynamic subtest B-VGA1: SUCCESS (1.670s)
[227.625243] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.566s)
[228.072919] [106/165] (839s left) kms_flip (basic-plain-flip)
[228.510709] Starting subtest: basic-plain-flip
[228.515600] Starting dynamic subtest: A-VGA1
[230.113346] Dynamic subtest A-VGA1: SUCCESS (1.594s)
[230.160297] Starting dynamic subtest: B-VGA1
[231.494831] Dynamic subtest B-VGA1: SUCCESS (1.383s)
[231.498900] Subtest basic-plain-flip: SUCCESS (2.982s)
[231.938745] [107/165] (835s left) kms_force_connector_basic (force-connector-state)
[232.423530] Starting subtest: force-connector-state
[232.686343] Subtest force-connector-state: SUCCESS (0.260s)
[233.115411] [108/165] (834s left) kms_force_connector_basic (force-edid)
[233.607243] Starting subtest: force-edid
[233.703935] Subtest force-edid: SUCCESS (0.099s)
[233.946618] [109/165] (833s left) kms_force_connector_basic (force-load-detect)
[234.418629] Starting subtest: force-load-detect
[234.771737] Subtest force-load-detect: SUCCESS (0.352s)
[235.156267] [110/165] (832s left) kms_force_connector_basic (prune-stale-modes)
[235.635955] Starting subtest: prune-stale-modes
[235.721968] Subtest prune-stale-modes: SUCCESS (0.085s)
[235.965849] [111/165] (831s left) kms_frontbuffer_tracking (basic)
[236.428449] Subtest basic: SKIP (0.000s)
[236.580365] [112/165] (831s left) kms_hdmi_inject (inject-audio)
[237.023841] Subtest inject-audio: SKIP (0.000s)
[237.131763] [113/165] (830s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[237.604736] Starting subtest: compare-crc-sanitycheck-xr24
[237.608474] Starting dynamic subtest: pipe-A-VGA-1
[237.988155] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.378s)
[238.037166] Starting dynamic subtest: pipe-B-VGA-1
[238.487734] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.490s)
[238.492636] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.880s)
[238.773742] [114/165] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[239.229615] Starting subtest: compare-crc-sanitycheck-nv12
[239.234767] Starting dynamic subtest: pipe-A-VGA-1
[239.245387] Dynamic subtest pipe-A-VGA-1: SKIP (0.002s)
[239.285249] Starting dynamic subtest: pipe-B-VGA-1
[239.290756] Dynamic subtest pipe-B-VGA-1: SKIP (0.001s)
[239.296800] Subtest compare-crc-sanitycheck-nv12: SKIP (0.013s)
[239.410228] [115/165] (828s left) kms_pipe_crc_basic (hang-read-crc)
[239.867634] Starting subtest: hang-read-crc
[239.871926] Starting dynamic subtest: pipe-A-VGA-1
[241.083803] Dynamic subtest pipe-A-VGA-1: SUCCESS (1.217s)
[241.142419] Starting dynamic subtest: pipe-B-VGA-1
[242.358686] Dynamic subtest pipe-B-VGA-1: SUCCESS (1.268s)
[242.362855] Subtest hang-read-crc: SUCCESS (2.495s)
[242.610651] [116/165] (825s left) kms_pipe_crc_basic (nonblocking-crc)
[243.067369] Starting subtest: nonblocking-crc
[243.071969] Starting dynamic subtest: pipe-A-VGA-1
[243.622339] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.551s)
[243.672610] Starting dynamic subtest: pipe-B-VGA-1
[244.279582] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.650s)
[244.284293] Subtest nonblocking-crc: SUCCESS (1.213s)
[244.539834] [117/165] (823s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[244.992389] Starting subtest: nonblocking-crc-frame-sequence
[245.001144] Starting dynamic subtest: pipe-A-VGA-1
[245.559956] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.561s)
[245.612160] Starting dynamic subtest: pipe-B-VGA-1
[246.229108] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.663s)
[246.233527] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.236s)
[246.489562] [118/165] (821s left) kms_pipe_crc_basic (read-crc)
[246.949427] Starting subtest: read-crc
[246.953515] Starting dynamic subtest: pipe-A-VGA-1
[247.428877] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.479s)
[247.481921] Starting dynamic subtest: pipe-B-VGA-1
[248.036184] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.601s)
[248.040746] Subtest read-crc: SUCCESS (1.091s)
[248.297870] [119/165] (820s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[248.753364] Starting subtest: read-crc-frame-sequence
[248.757881] Starting dynamic subtest: pipe-A-VGA-1
[249.240355] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.485s)
[249.282538] Starting dynamic subtest: pipe-B-VGA-1
[249.892944] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.644s)
[249.897519] Subtest read-crc-frame-sequence: SUCCESS (1.141s)
[250.156320] [120/165] (818s left) kms_pm_backlight (basic-brightness)
[250.613797] Subtest basic-brightness: SKIP (0.000s)
[250.723495] [121/165] (818s left) kms_pm_rpm (basic-pci-d3-state)
[262.614718] Subtest basic-pci-d3-state: SKIP (0.000s)
[262.720429] [122/165] (806s left) kms_pm_rpm (basic-rte)
[263.131671] Starting subtest: basic-rte
[274.722508] Subtest basic-rte: SKIP (11.569s)
[274.838613] [123/165] (794s left) kms_psr (primary_page_flip)
[275.249694] Subtest primary_page_flip: SKIP (0.000s)
[275.353535] [124/165] (793s left) kms_psr (cursor_plane_move)
[275.771911] Subtest cursor_plane_move: SKIP (0.000s)
[275.870715] [125/165] (793s left) kms_psr (sprite_plane_onoff)
[276.276337] Subtest sprite_plane_onoff: SKIP (0.000s)
[276.387686] [126/165] (792s left) kms_psr (primary_mmap_gtt)
[276.812947] Subtest primary_mmap_gtt: SKIP (0.000s)
[276.920515] [127/165] (792s left) kms_setmode (basic-clone-single-crtc)
[277.330655] Starting subtest: basic-clone-single-crtc
[277.342466] Subtest basic-clone-single-crtc: SKIP (0.001s)
[277.466716] [128/165] (791s left) i915_pm_rps (basic-api)
[277.882654] Subtest basic-api: SKIP (0.000s)
[277.988798] [129/165] (791s left) prime_self_import (basic-llseek-bad)
[278.376250] Starting subtest: basic-llseek-bad
[278.388466] Subtest basic-llseek-bad: SUCCESS (0.011s)
[278.533538] [130/165] (791s left) prime_self_import (basic-llseek-size)
[278.936403] Starting subtest: basic-llseek-size
[278.953688] Subtest basic-llseek-size: SUCCESS (0.018s)
[279.105577] [131/165] (790s left) prime_self_import (basic-with_fd_dup)
[279.498674] Starting subtest: basic-with_fd_dup
[279.514745] Subtest basic-with_fd_dup: SUCCESS (0.016s)
[279.655406] [132/165] (790s left) prime_self_import (basic-with_one_bo)
[280.067729] Starting subtest: basic-with_one_bo
[280.083253] Subtest basic-with_one_bo: SUCCESS (0.018s)
[280.206667] [133/165] (789s left) prime_self_import (basic-with_one_bo_two_files)
[280.615562] Starting subtest: basic-with_one_bo_two_files
[280.622615] Subtest basic-with_one_bo_two_files: SUCCESS (0.015s)
[280.771519] [134/165] (789s left) prime_self_import (basic-with_two_bos)
[281.162761] Starting subtest: basic-with_two_bos
[281.178925] Subtest basic-with_two_bos: SUCCESS (0.017s)
[281.334321] [135/165] (788s left) prime_vgem (basic-fence-flip)
[281.769462] Starting subtest: basic-fence-flip
[282.369490] Subtest basic-fence-flip: SUCCESS (0.596s)
[282.538705] [136/165] (787s left) prime_vgem (basic-fence-mmap)
[282.973522] Starting subtest: basic-fence-mmap
[283.163115] Subtest basic-fence-mmap: SUCCESS (0.192s)
[283.305781] [137/165] (786s left) prime_vgem (basic-fence-read)
[283.736818] Starting subtest: basic-fence-read
[284.039861] Subtest basic-fence-read: SUCCESS (0.303s)
[284.187485] [138/165] (786s left) prime_vgem (basic-gtt)
[284.623855] Starting subtest: basic-gtt
[284.696639] Subtest basic-gtt: SUCCESS (0.077s)
[284.852275] [139/165] (785s left) prime_vgem (basic-read)
[285.291625] Starting subtest: basic-read
[285.419245] Subtest basic-read: SUCCESS (0.131s)
[285.588749] [140/165] (784s left) prime_vgem (basic-write)
[286.021505] Starting subtest: basic-write
[286.175090] Subtest basic-write: SUCCESS (0.153s)
[286.320215] [141/165] (784s left) vgem_basic (setversion)
[286.738140] Starting subtest: setversion
[286.746319] Subtest setversion: SUCCESS (0.000s)
[286.852698] [142/165] (783s left) vgem_basic (create)
[287.255723] Starting subtest: create
[287.260259] Subtest create: SUCCESS (0.002s)
[287.391565] [143/165] (783s left) vgem_basic (debugfs)
[287.803197] Starting subtest: debugfs
[287.807404] Subtest debugfs: SUCCESS (0.003s)
[287.921678] [144/165] (782s left) vgem_basic (dmabuf-export)
[288.336337] Starting subtest: dmabuf-export
[288.349314] Subtest dmabuf-export: SUCCESS (0.012s)
[288.492777] [145/165] (782s left) vgem_basic (dmabuf-fence)
[288.921229] Starting subtest: dmabuf-fence
[288.925718] Subtest dmabuf-fence: SUCCESS (0.004s)
[289.037541] [146/165] (781s left) vgem_basic (dmabuf-fence-before)
[289.454951] Starting subtest: dmabuf-fence-before
[289.459586] Subtest dmabuf-fence-before: SUCCESS (0.004s)
[289.573712] [147/165] (781s left) vgem_basic (dmabuf-mmap)
[289.988526] Starting subtest: dmabuf-mmap
[290.069489] Subtest dmabuf-mmap: SUCCESS (0.082s)
[290.189385] [148/165] (780s left) vgem_basic (mmap)
[290.605231] Starting subtest: mmap
[290.660349] Subtest mmap: SUCCESS (0.058s)
[290.791804] [149/165] (780s left) vgem_basic (second-client)
[291.205657] Starting subtest: second-client
[291.239489] Subtest second-client: SUCCESS (0.033s)
[291.357635] [150/165] (779s left) vgem_basic (sysfs)
[291.772599] Starting subtest: sysfs
[291.777521] Subtest sysfs: SUCCESS (0.005s)
[291.894636] [151/165] (779s left) core_hotunplug (unbind-rebind)
[292.436714] Starting subtest: unbind-rebind
[296.900804] Subtest unbind-rebind: SUCCESS (4.461s)
[297.019847] [152/165] (774s left) vgem_basic (unload)
[297.432436] Starting subtest: unload
[326.819233] Subtest unload: SUCCESS (29.384s)
[327.075634] [153/165] (744s left) i915_module_load (reload)
[327.474729] Starting subtest: reload
[332.366822] Subtest reload: SUCCESS (4.889s)
[332.532674] [154/165] (739s left) gem_lmem_swapping (basic)
[333.683616] Subtest basic: SKIP (0.000s)
[333.791806] [155/165] (738s left) gem_lmem_swapping (parallel-random-engines)
[334.229870] Subtest parallel-random-engines: SKIP (0.000s)
[334.339553] [156/165] (737s left) gem_lmem_swapping (random-engines)
[334.757562] Subtest random-engines: SKIP (0.000s)
[334.855458] [157/165] (737s left) gem_lmem_swapping (verify-random)
[335.282710] Subtest verify-random: SKIP (0.000s)
[335.389790] [158/165] (736s left) i915_pm_rpm (module-reload)
[347.306392] Subtest module-reload: SKIP (0.000s)
[347.414657] [159/165] (724s left) i915_selftest (live)
[349.627705] Starting subtest: live
[349.632231] Starting dynamic subtest: sanitycheck
[352.245522] Dynamic subtest sanitycheck: SUCCESS (2.614s)
[352.283464] Starting dynamic subtest: uncore
[354.382380] Dynamic subtest uncore: SUCCESS (2.135s)
[354.417774] Starting dynamic subtest: workarounds
[356.492480] Dynamic subtest workarounds: SUCCESS (2.107s)
[356.523697] Starting dynamic subtest: gt_engines
[358.583745] Dynamic subtest gt_engines: SUCCESS (2.088s)
[358.619115] Starting dynamic subtest: gt_timelines
[369.749285] Dynamic subtest gt_timelines: SUCCESS (11.161s)
[369.781844] Starting dynamic subtest: gt_contexts
[371.873704] Dynamic subtest gt_contexts: SUCCESS (2.121s)
[371.906096] Starting dynamic subtest: gt_lrc
[373.991724] Dynamic subtest gt_lrc: SUCCESS (2.115s)
[374.029200] Starting dynamic subtest: gt_mocs
[376.120385] Dynamic subtest gt_mocs: SUCCESS (2.126s)
[376.154239] Starting dynamic subtest: gt_pm
[378.648611] Dynamic subtest gt_pm: SUCCESS (2.521s)
[378.682435] Starting dynamic subtest: gt_heartbeat
[380.791120] Dynamic subtest gt_heartbeat: SUCCESS (2.142s)
[380.823437] Starting dynamic subtest: gt_tlb
[382.893628] Dynamic subtest gt_tlb: SUCCESS (2.097s)
[382.932647] Starting dynamic subtest: requests
[387.602405] Dynamic subtest requests: SUCCESS (4.707s)
[387.638392] Starting dynamic subtest: migrate
[389.686296] Dynamic subtest migrate: SUCCESS (2.081s)
[389.718139] Starting dynamic subtest: active
[391.770902] Dynamic subtest active: SUCCESS (2.080s)
[391.808224] Starting dynamic subtest: objects
[394.902700] Dynamic subtest objects: SUCCESS (3.130s)
[394.933558] Starting dynamic subtest: mman
[406.740248] Dynamic subtest mman: SUCCESS (11.833s)
[406.777633] Starting dynamic subtest: dmabuf
[408.962856] Dynamic subtest dmabuf: SUCCESS (2.221s)
[408.995680] Starting dynamic subtest: vma
[411.090688] Dynamic subtest vma: SUCCESS (2.124s)
[411.127198] Starting dynamic subtest: coherency
[418.269440] Dynamic subtest coherency: SUCCESS (7.174s)
[418.300552] Starting dynamic subtest: gtt
[423.447471] Dynamic subtest gtt: SUCCESS (5.174s)
[423.485169] Starting dynamic subtest: gem
[425.591955] Dynamic subtest gem: SUCCESS (2.142s)
[425.624126] Starting dynamic subtest: evict
[427.689684] Dynamic subtest evict: SUCCESS (2.095s)
[427.721560] Starting dynamic subtest: hugepages
[429.794819] Dynamic subtest hugepages: SUCCESS (2.100s)
[429.826578] Starting dynamic subtest: gem_contexts
[431.836884] Dynamic subtest gem_contexts: SUCCESS (2.039s)
[431.869269] Starting dynamic subtest: client
[433.937182] Dynamic subtest client: SUCCESS (2.098s)
[433.970809] Starting dynamic subtest: gem_migrate
[436.062650] Dynamic subtest gem_migrate: SUCCESS (2.120s)
[436.101192] Starting dynamic subtest: reset
[438.721382] Dynamic subtest reset: SUCCESS (2.656s)
[438.756589] Starting dynamic subtest: memory_region
[440.856934] Dynamic subtest memory_region: SUCCESS (2.132s)
[440.889324] Starting dynamic subtest: hangcheck
[444.058314] Dynamic subtest hangcheck: SUCCESS (3.197s)
[444.096222] Starting dynamic subtest: execlists
[446.131162] Dynamic subtest execlists: SUCCESS (2.069s)
[446.164678] Starting dynamic subtest: ring_submission
[448.265753] Dynamic subtest ring_submission: SUCCESS (2.131s)
[448.300789] Starting dynamic subtest: perf
[450.396855] Dynamic subtest perf: SUCCESS (2.127s)
[450.429541] Starting dynamic subtest: slpc
[452.490490] Dynamic subtest slpc: SUCCESS (2.089s)
[452.526815] Starting dynamic subtest: guc
[454.583637] Dynamic subtest guc: SUCCESS (2.087s)
[454.622646] Starting dynamic subtest: guc_multi_lrc
[456.660967] Dynamic subtest guc_multi_lrc: SUCCESS (2.075s)
[456.692653] Starting dynamic subtest: guc_hang
[458.783168] Dynamic subtest guc_hang: SUCCESS (2.116s)
[458.818787] Starting dynamic subtest: late_gt_pm
[460.912611] Dynamic subtest late_gt_pm: SUCCESS (2.126s)
[460.916728] Subtest live: SUCCESS (111.287s)
[461.015217] [160/165] (611s left) dmabuf (all-tests)
[461.429423] Starting subtest: all-tests
[461.433672] Starting dynamic subtest: sanitycheck
[461.527507] Dynamic subtest sanitycheck: SUCCESS (0.097s)
[461.573736] Starting dynamic subtest: dma_fence
[461.754365] Dynamic subtest dma_fence: SUCCESS (0.221s)
[461.794081] Starting dynamic subtest: dma_fence_chain
[513.327938] Dynamic subtest dma_fence_chain: SUCCESS (51.569s)
[513.364382] Starting dynamic subtest: dma_fence_unwrap
[513.417781] Dynamic subtest dma_fence_unwrap: SUCCESS (0.087s)
[513.457469] Starting dynamic subtest: dma_resv
[513.536345] Dynamic subtest dma_resv: SUCCESS (0.114s)
[513.539789] Subtest all-tests: SUCCESS (52.111s)
[513.637282] [161/165] (558s left) i915_suspend (basic-s2idle-without-i915)
[514.025807] Starting subtest: basic-s2idle-without-i915
[535.538527] Subtest basic-s2idle-without-i915: SUCCESS (6.479s)
[537.778028] [162/165] (551s left) i915_suspend (basic-s3-without-i915)
[538.269479] Starting subtest: basic-s3-without-i915
[558.626662] Subtest basic-s3-without-i915: SUCCESS (4.485s)
[563.761649] [163/165] (546s left) gem_exec_suspend (basic-s0)
[564.268807] Starting subtest: basic-S0
[564.274380] Starting dynamic subtest: smem
[585.651315] Dynamic subtest smem: SUCCESS (6.126s)
[585.655188] Subtest basic-S0: SUCCESS (6.132s)
[587.872122] [164/165] (539s left) gem_exec_suspend (basic-s3)
[588.355519] Starting subtest: basic-S3
[588.360428] Starting dynamic subtest: smem
[610.820703] Dynamic subtest smem: SUCCESS (6.208s)
[610.824515] Subtest basic-S3: SUCCESS (6.212s)
[614.123539] [165/165] (533s left) kms_pipe_crc_basic (suspend-read-crc)
[614.623279] Starting subtest: suspend-read-crc
[614.629174] Starting dynamic subtest: pipe-A-VGA-1
[632.601578] Dynamic subtest pipe-A-VGA-1: SUCCESS (2.321s)
[632.664300] Starting dynamic subtest: pipe-B-VGA-1
[651.627426] Dynamic subtest pipe-B-VGA-1: SUCCESS (2.479s)
[651.631166] Subtest suspend-read-crc: SUCCESS (4.818s)
[654.903117] Closing watchdogs
Done.