[45.787049] Initializing watchdogs
[45.787193]   /dev/watchdog0
[45.797396] [001/165] (960s left) i915_module_load (load)
[45.897813] Starting subtest: load
[48.446552] Subtest load: SUCCESS (2.549s)
[48.473426] [002/165] (957s left) core_auth (basic-auth)
[48.570877] Starting subtest: basic-auth
[48.572231] Subtest basic-auth: SUCCESS (0.001s)
[48.652205] [003/165] (957s left) debugfs_test (read_all_entries)
[48.755251] Starting subtest: read_all_entries
[48.832587] Subtest read_all_entries: SUCCESS (0.077s)
[48.949499] [004/165] (957s left) debugfs_test (basic-hwmon)
[49.044560] Starting subtest: basic-hwmon
[49.046586] Subtest basic-hwmon: SKIP (0.000s)
[49.144638] [005/165] (957s left) fbdev (eof)
[49.252490] Starting subtest: eof
[49.253922] Subtest eof: SUCCESS (0.001s)
[49.318745] [006/165] (957s left) fbdev (info)
[49.433538] Starting subtest: info
[49.439046] Subtest info: SUCCESS (0.000s)
[49.512497] [007/165] (956s left) fbdev (nullptr)
[49.614665] Starting subtest: nullptr
[49.615846] Subtest nullptr: SUCCESS (0.000s)
[49.678900] [008/165] (956s left) fbdev (read)
[49.781967] Starting subtest: read
[56.888033] Subtest read: SUCCESS (7.071s)
[56.922902] [009/165] (949s left) fbdev (write)
[57.022146] Starting subtest: write
[60.805890] Subtest write: SUCCESS (3.748s)
[60.842372] [010/165] (945s left) gem_basic (bad-close)
[60.933383] Starting subtest: bad-close
[60.935512] Subtest bad-close: SUCCESS (0.000s)
[61.010087] [011/165] (945s left) gem_basic (create-close)
[61.102708] Starting subtest: create-close
[61.104724] Subtest create-close: SUCCESS (0.000s)
[61.213316] [012/165] (945s left) gem_basic (create-fd-close)
[61.316503] Starting subtest: create-fd-close
[61.318745] Subtest create-fd-close: SUCCESS (0.000s)
[61.412305] [013/165] (945s left) gem_busy (busy@all-engines)
[61.526582] Starting subtest: busy
[61.527856] Starting dynamic subtest: all-engines
[61.599343] Dynamic subtest all-engines: SUCCESS (0.073s)
[61.600513] Subtest busy: SUCCESS (0.073s)
[61.943732] [014/165] (944s left) gem_close_race (basic-process)
[62.061577] Starting subtest: basic-process
[62.135896] Subtest basic-process: SUCCESS (0.075s)
[62.350749] [015/165] (944s left) gem_close_race (basic-threads)
[62.461562] Starting subtest: basic-threads
[63.627047] Subtest basic-threads: SUCCESS (1.165s)
[63.868353] [016/165] (942s left) gem_ctx_create (basic)
[63.980600] Starting subtest: basic
[63.981806] Subtest basic: SUCCESS (0.000s)
[64.184100] [017/165] (942s left) gem_ctx_create (basic-files)
[64.289873] Starting subtest: basic-files
[66.317775] Subtest basic-files: SUCCESS (2.028s)
[66.570199] [018/165] (940s left) gem_ctx_exec (basic)
[66.667598] Starting subtest: basic
[66.672073] Subtest basic: SUCCESS (0.005s)
[66.787481] [019/165] (940s left) gem_exec_basic (basic)
[66.887877] Starting subtest: basic
[66.889094] Starting dynamic subtest: rcs0-smem
[66.890244] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[66.901156] Starting dynamic subtest: bcs0-smem
[66.902293] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[66.912756] Starting dynamic subtest: vcs0-smem
[66.913988] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[66.924442] Starting dynamic subtest: vecs0-smem
[66.925773] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[66.926982] Subtest basic: SUCCESS (0.005s)
[67.103314] [020/165] (939s left) gem_exec_create (basic)
[67.206332] Starting subtest: basic
[67.207563] Starting dynamic subtest: smem
[69.273264] Dynamic subtest smem: SUCCESS (2.044s)
[69.274637] Subtest basic: SUCCESS (2.045s)
[69.482975] [021/165] (937s left) gem_exec_fence (basic-busy)
[69.736718] Starting subtest: basic-busy
[69.738071] Starting dynamic subtest: rcs0
[69.767473] Dynamic subtest rcs0: SUCCESS (0.035s)
[69.782368] Starting dynamic subtest: bcs0
[69.786257] Dynamic subtest bcs0: SUCCESS (0.020s)
[69.797632] Starting dynamic subtest: vcs0
[69.815917] Dynamic subtest vcs0: SUCCESS (0.029s)
[69.826944] Starting dynamic subtest: vecs0
[69.841729] Dynamic subtest vecs0: SUCCESS (0.025s)
[69.842994] Subtest basic-busy: SUCCESS (0.111s)
[70.441632] [022/165] (936s left) gem_exec_fence (basic-wait)
[70.692802] Starting subtest: basic-wait
[70.694101] Starting dynamic subtest: rcs0
[70.726125] Dynamic subtest rcs0: SUCCESS (0.037s)
[70.741366] Starting dynamic subtest: bcs0
[70.748316] Dynamic subtest bcs0: SUCCESS (0.022s)
[70.760132] Starting dynamic subtest: vcs0
[70.795043] Dynamic subtest vcs0: SUCCESS (0.045s)
[70.812862] Starting dynamic subtest: vecs0
[70.819192] Dynamic subtest vecs0: SUCCESS (0.024s)
[70.820433] Subtest basic-wait: SUCCESS (0.132s)
[71.362830] [023/165] (935s left) gem_exec_fence (basic-await)
[71.612622] Starting subtest: basic-await
[71.613768] Starting dynamic subtest: rcs0
[71.717140] Dynamic subtest rcs0: SUCCESS (0.099s)
[71.731545] Starting dynamic subtest: bcs0
[71.815969] Dynamic subtest bcs0: SUCCESS (0.105s)
[71.843906] Starting dynamic subtest: vcs0
[71.949477] Dynamic subtest vcs0: SUCCESS (0.129s)
[71.977027] Starting dynamic subtest: vecs0
[72.084063] Dynamic subtest vecs0: SUCCESS (0.130s)
[72.085947] Subtest basic-await: SUCCESS (0.466s)
[72.661688] [024/165] (934s left) gem_exec_fence (nb-await)
[72.916764] Starting subtest: nb-await
[72.917994] Starting dynamic subtest: rcs0
[72.972625] Dynamic subtest rcs0: SUCCESS (0.063s)
[72.993615] Starting dynamic subtest: bcs0
[73.044723] Dynamic subtest bcs0: SUCCESS (0.071s)
[73.066914] Starting dynamic subtest: vcs0
[73.117141] Dynamic subtest vcs0: SUCCESS (0.072s)
[73.144278] Starting dynamic subtest: vecs0
[73.190871] Dynamic subtest vecs0: SUCCESS (0.073s)
[73.192964] Subtest nb-await: SUCCESS (0.282s)
[73.746869] [025/165] (933s left) gem_exec_gttfill (basic)
[73.853786] Starting subtest: basic
[79.738725] Subtest basic: SUCCESS (5.876s)
[79.937143] [026/165] (927s left) gem_exec_parallel (engines)
[80.113701] Starting subtest: engines
[80.115045] Starting dynamic subtest: basic
[82.428367] Dynamic subtest basic: SUCCESS (2.315s)
[82.442105] Starting dynamic subtest: contexts
[87.524874] Dynamic subtest contexts: SUCCESS (5.089s)
[87.543237] Starting dynamic subtest: fds
[98.202569] Dynamic subtest fds: SUCCESS (10.685s)
[98.236035] Starting dynamic subtest: userptr
[100.732200] Dynamic subtest userptr: SUCCESS (2.501s)
[100.734424] Subtest engines: SUCCESS (20.592s)
[100.963357] [027/165] (906s left) gem_exec_store (basic)
[101.069742] Starting subtest: basic
[101.132665] Subtest basic: SUCCESS (0.063s)
[101.349638] [028/165] (905s left) gem_flink_basic (bad-flink)
[101.440590] Starting subtest: bad-flink
[101.441768] Subtest bad-flink: SUCCESS (0.000s)
[101.525898] [029/165] (905s left) gem_flink_basic (bad-open)
[101.615601] Starting subtest: bad-open
[101.616802] Subtest bad-open: SUCCESS (0.000s)
[101.710106] [030/165] (905s left) gem_flink_basic (basic)
[101.799711] Starting subtest: basic
[101.800980] Subtest basic: SUCCESS (0.000s)
[101.909404] [031/165] (905s left) gem_flink_basic (double-flink)
[101.998934] Starting subtest: double-flink
[102.000336] Subtest double-flink: SUCCESS (0.000s)
[102.110228] [032/165] (905s left) gem_flink_basic (flink-lifetime)
[102.202763] Starting subtest: flink-lifetime
[102.204066] Subtest flink-lifetime: SUCCESS (0.002s)
[102.310901] [033/165] (904s left) gem_huc_copy (huc-copy)
[102.408830] Starting subtest: huc-copy
[102.411200] Subtest huc-copy: SKIP (0.000s)
[102.528200] [034/165] (904s left) gem_linear_blits (basic)
[102.775337] Starting subtest: basic
[102.789414] Subtest basic: SUCCESS (0.014s)
[102.929938] [035/165] (904s left) gem_mmap (basic)
[103.085205] Starting subtest: basic
[103.090335] Subtest basic: SUCCESS (0.001s)
[103.194496] [036/165] (904s left) gem_mmap_gtt (basic)
[103.286637] Starting subtest: basic
[103.287952] Subtest basic: SUCCESS (0.001s)
[103.370190] [037/165] (903s left) gem_render_linear_blits (basic)
[103.466921] Starting subtest: basic
[103.516445] Subtest basic: SUCCESS (0.049s)
[103.570404] [038/165] (903s left) gem_render_tiled_blits (basic)
[103.665181] Starting subtest: basic
[103.724175] Subtest basic: SUCCESS (0.059s)
[103.796213] [039/165] (903s left) gem_ringfill (basic-all)
[104.032492] Starting subtest: basic-all
[105.275762] Subtest basic-all: SUCCESS (1.234s)
[105.366620] [040/165] (902s left) gem_softpin (allocator-basic)
[105.462535] Starting subtest: allocator-basic
[105.636424] Subtest allocator-basic: SUCCESS (0.163s)
[105.764974] [041/165] (901s left) gem_softpin (allocator-basic-reserve)
[105.858819] Starting subtest: allocator-basic-reserve
[106.027759] Subtest allocator-basic-reserve: SUCCESS (0.160s)
[106.131275] [042/165] (901s left) gem_softpin (safe-alignment)
[106.228900] Starting subtest: safe-alignment
[106.248400] Subtest safe-alignment: SUCCESS (0.018s)
[106.376994] [043/165] (901s left) gem_sync (basic-all)
[106.485513] Starting subtest: basic-all
[108.706634] Subtest basic-all: SUCCESS (2.197s)
[108.882137] [044/165] (898s left) gem_sync (basic-each)
[108.989984] Starting subtest: basic-each
[111.194291] Subtest basic-each: SUCCESS (2.204s)
[111.453764] [045/165] (896s left) gem_tiled_blits (basic)
[111.679420] Starting subtest: basic
[111.707380] Subtest basic: SUCCESS (0.028s)
[111.839110] [046/165] (895s left) gem_tiled_fence_blits (basic)
[112.168417] Starting subtest: basic
[112.195370] Subtest basic: SUCCESS (0.028s)
[112.340846] [047/165] (895s left) gem_tiled_pread_basic
[112.854116] [048/165] (894s left) gem_wait (busy@all-engines)
[112.969095] Starting subtest: busy
[112.970337] Starting dynamic subtest: all-engines
[113.486687] Dynamic subtest all-engines: SUCCESS (0.515s)
[113.487685] Subtest busy: SUCCESS (0.516s)
[113.740422] [049/165] (893s left) gem_wait (wait@all-engines)
[113.855672] Starting subtest: wait
[113.856774] Starting dynamic subtest: all-engines
[114.909554] Dynamic subtest all-engines: SUCCESS (1.053s)
[114.911394] Subtest wait: SUCCESS (1.053s)
[115.174870] [050/165] (892s left) i915_getparams_basic (basic-eu-total)
[115.265238] Starting subtest: basic-eu-total
[115.268279] Subtest basic-eu-total: SUCCESS (0.000s)
[115.358223] [051/165] (892s left) i915_getparams_basic (basic-subslice-total)
[115.443157] Starting subtest: basic-subslice-total
[115.445249] Subtest basic-subslice-total: SUCCESS (0.000s)
[115.520409] [052/165] (892s left) i915_hangman (error-state-basic)
[115.626346] Starting subtest: error-state-basic
[115.636859] Subtest error-state-basic: SUCCESS (0.011s)
[115.719327] [053/165] (892s left) i915_pciid
[115.888524] [054/165] (891s left) kms_addfb_basic (addfb25-4-tiled)
[116.019254] Starting subtest: addfb25-4-tiled
[116.020354] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[116.122292] [055/165] (891s left) kms_addfb_basic (addfb25-bad-modifier)
[116.216113] Starting subtest: addfb25-bad-modifier
[116.217141] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[116.341679] [056/165] (891s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[116.434790] Starting subtest: addfb25-framebuffer-vs-set-tiling
[116.435852] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[116.559081] [057/165] (891s left) kms_addfb_basic (addfb25-modifier-no-flag)
[116.649731] Starting subtest: addfb25-modifier-no-flag
[116.650768] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[116.771275] [058/165] (891s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[116.861782] Starting subtest: addfb25-x-tiled-legacy
[116.863095] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[116.993963] [059/165] (890s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[117.086766] Starting subtest: addfb25-x-tiled-mismatch-legacy
[117.087915] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[117.209141] [060/165] (890s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[117.329042] Starting subtest: addfb25-yf-tiled-legacy
[117.330096] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[117.444912] [061/165] (890s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[117.564694] Starting subtest: addfb25-y-tiled-legacy
[117.565824] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[117.661028] [062/165] (890s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[117.781231] Starting subtest: addfb25-y-tiled-small-legacy
[117.782511] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[117.892490] [063/165] (890s left) kms_addfb_basic (bad-pitch-0)
[117.983761] Starting subtest: bad-pitch-0
[117.984982] Subtest bad-pitch-0: SUCCESS (0.000s)
[118.112851] [064/165] (889s left) kms_addfb_basic (bad-pitch-1024)
[118.203055] Starting subtest: bad-pitch-1024
[118.204181] Subtest bad-pitch-1024: SUCCESS (0.000s)
[118.326777] [065/165] (889s left) kms_addfb_basic (bad-pitch-128)
[118.419606] Starting subtest: bad-pitch-128
[118.420673] Subtest bad-pitch-128: SUCCESS (0.000s)
[118.561269] [066/165] (889s left) kms_addfb_basic (bad-pitch-256)
[118.651520] Starting subtest: bad-pitch-256
[118.652672] Subtest bad-pitch-256: SUCCESS (0.000s)
[118.777567] [067/165] (889s left) kms_addfb_basic (bad-pitch-32)
[118.869539] Starting subtest: bad-pitch-32
[118.870588] Subtest bad-pitch-32: SUCCESS (0.000s)
[118.994715] [068/165] (889s left) kms_addfb_basic (bad-pitch-63)
[119.084434] Starting subtest: bad-pitch-63
[119.085549] Subtest bad-pitch-63: SUCCESS (0.000s)
[119.210231] [069/165] (889s left) kms_addfb_basic (bad-pitch-65536)
[119.302632] Starting subtest: bad-pitch-65536
[119.303862] Subtest bad-pitch-65536: SUCCESS (0.000s)
[119.446079] [070/165] (888s left) kms_addfb_basic (bad-pitch-999)
[119.536575] Starting subtest: bad-pitch-999
[119.537630] Subtest bad-pitch-999: SUCCESS (0.000s)
[119.680810] [071/165] (888s left) kms_addfb_basic (basic)
[119.770526] Starting subtest: basic
[119.771679] Subtest basic: SUCCESS (0.000s)
[119.900203] [072/165] (888s left) kms_addfb_basic (basic-x-tiled-legacy)
[119.993114] Starting subtest: basic-x-tiled-legacy
[119.994163] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[120.129765] [073/165] (888s left) kms_addfb_basic (basic-y-tiled-legacy)
[120.223514] Starting subtest: basic-y-tiled-legacy
[120.224604] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[120.364207] [074/165] (888s left) kms_addfb_basic (bo-too-small)
[120.483928] Starting subtest: bo-too-small
[120.485101] Subtest bo-too-small: SUCCESS (0.000s)
[120.576504] [075/165] (887s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[120.696881] Starting subtest: bo-too-small-due-to-tiling
[120.698069] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[120.798286] [076/165] (887s left) kms_addfb_basic (clobberred-modifier)
[120.888627] Starting subtest: clobberred-modifier
[120.889719] Subtest clobberred-modifier: SUCCESS (0.000s)
[121.013150] [077/165] (887s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[121.104765] Starting subtest: framebuffer-vs-set-tiling
[121.105858] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[121.248718] [078/165] (887s left) kms_addfb_basic (invalid-get-prop)
[121.343154] Starting subtest: invalid-get-prop
[121.344186] Subtest invalid-get-prop: SUCCESS (0.000s)
[121.481630] [079/165] (887s left) kms_addfb_basic (invalid-get-prop-any)
[121.572349] Starting subtest: invalid-get-prop-any
[121.573443] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[121.695494] [080/165] (886s left) kms_addfb_basic (invalid-set-prop)
[121.787765] Starting subtest: invalid-set-prop
[121.788838] Subtest invalid-set-prop: SUCCESS (0.000s)
[121.914753] [081/165] (886s left) kms_addfb_basic (invalid-set-prop-any)
[122.006082] Starting subtest: invalid-set-prop-any
[122.007184] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[122.131999] [082/165] (886s left) kms_addfb_basic (no-handle)
[122.224646] Starting subtest: no-handle
[122.225663] Subtest no-handle: SUCCESS (0.000s)
[122.365801] [083/165] (886s left) kms_addfb_basic (size-max)
[122.485988] Starting subtest: size-max
[122.487104] Subtest size-max: SUCCESS (0.000s)
[122.579159] [084/165] (886s left) kms_addfb_basic (small-bo)
[122.698913] Starting subtest: small-bo
[122.700098] Subtest small-bo: SUCCESS (0.000s)
[122.796689] [085/165] (885s left) kms_addfb_basic (tile-pitch-mismatch)
[122.890115] Starting subtest: tile-pitch-mismatch
[122.891150] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[123.016609] [086/165] (885s left) kms_addfb_basic (too-high)
[123.135761] Starting subtest: too-high
[123.137039] Subtest too-high: SUCCESS (0.001s)
[123.233431] [087/165] (885s left) kms_addfb_basic (too-wide)
[123.352452] Starting subtest: too-wide
[123.353664] Subtest too-wide: SUCCESS (0.000s)
[123.449535] [088/165] (885s left) kms_addfb_basic (unused-handle)
[123.540470] Starting subtest: unused-handle
[123.541602] Subtest unused-handle: SUCCESS (0.000s)
[123.662078] [089/165] (885s left) kms_addfb_basic (unused-modifier)
[123.752400] Starting subtest: unused-modifier
[123.753469] Subtest unused-modifier: SUCCESS (0.000s)
[123.884194] [090/165] (884s left) kms_addfb_basic (unused-offsets)
[123.975884] Starting subtest: unused-offsets
[123.976960] Subtest unused-offsets: SUCCESS (0.000s)
[124.117211] [091/165] (884s left) kms_addfb_basic (unused-pitches)
[124.213651] Starting subtest: unused-pitches
[124.214777] Subtest unused-pitches: SUCCESS (0.000s)
[124.350322] [092/165] (884s left) kms_busy (basic)
[124.476396] Starting subtest: basic
[124.477529] Starting dynamic subtest: flip
[125.496619] Dynamic subtest flip: SUCCESS (1.004s)
[125.510817] Starting dynamic subtest: modeset
[129.346729] Dynamic subtest modeset: SUCCESS (3.863s)
[129.347988] Subtest basic: SUCCESS (4.870s)
[130.301561] [093/165] (878s left) kms_prop_blob (basic)
[130.395270] Starting subtest: basic
[130.396354] Subtest basic: SUCCESS (0.000s)
[130.484333] [094/165] (878s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[130.604276] Starting subtest: basic-busy-flip-before-cursor-atomic
[130.606436] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[130.678479] [095/165] (878s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[130.794431] Starting subtest: basic-busy-flip-before-cursor-legacy
[130.796570] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[130.885088] [096/165] (878s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[131.004820] Starting subtest: basic-flip-after-cursor-atomic
[132.313176] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.309s)
[133.242781] [097/165] (875s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[133.361697] Starting subtest: basic-flip-after-cursor-legacy
[134.669646] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.308s)
[135.608128] [098/165] (873s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[135.726824] Starting subtest: basic-flip-after-cursor-varying-size
[137.005588] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.278s)
[137.977292] [099/165] (871s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[138.096231] Starting subtest: basic-flip-before-cursor-atomic
[139.406211] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.309s)
[140.347067] [100/165] (868s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[140.466447] Starting subtest: basic-flip-before-cursor-legacy
[141.776490] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.308s)
[142.714282] [101/165] (866s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[142.833368] Starting subtest: basic-flip-before-cursor-varying-size
[144.144385] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.311s)
[145.082114] [102/165] (864s left) kms_dsc (dsc-basic)
[145.201692] Starting subtest: dsc-basic
[145.204033] Subtest dsc-basic: SKIP (0.001s)
[145.279133] [103/165] (863s left) kms_flip (basic-flip-vs-dpms)
[145.378342] Starting subtest: basic-flip-vs-dpms
[145.381505] Starting dynamic subtest: A-eDP1
[148.208762] Dynamic subtest A-eDP1: SUCCESS (2.827s)
[148.226780] Starting dynamic subtest: B-eDP1
[150.581127] Dynamic subtest B-eDP1: SUCCESS (2.369s)
[150.614063] Starting dynamic subtest: C-eDP1
[152.945924] Dynamic subtest C-eDP1: SUCCESS (2.365s)
[152.951596] Subtest basic-flip-vs-dpms: SUCCESS (7.572s)
[153.859309] [104/165] (855s left) kms_flip (basic-flip-vs-modeset)
[153.958920] Starting subtest: basic-flip-vs-modeset
[153.962758] Starting dynamic subtest: A-eDP1
[156.845048] Dynamic subtest A-eDP1: SUCCESS (2.880s)
[156.861352] Starting dynamic subtest: B-eDP1
[159.218245] Dynamic subtest B-eDP1: SUCCESS (2.375s)
[159.236101] Starting dynamic subtest: C-eDP1
[161.580313] Dynamic subtest C-eDP1: SUCCESS (2.362s)
[161.585179] Subtest basic-flip-vs-modeset: SUCCESS (7.625s)
[162.553812] [105/165] (846s left) kms_flip (basic-flip-vs-wf_vblank)
[162.653076] Starting subtest: basic-flip-vs-wf_vblank
[162.656775] Starting dynamic subtest: A-eDP1
[164.951563] Dynamic subtest A-eDP1: SUCCESS (2.293s)
[164.970508] Starting dynamic subtest: B-eDP1
[167.240122] Dynamic subtest B-eDP1: SUCCESS (2.286s)
[167.258680] Starting dynamic subtest: C-eDP1
[169.477515] Dynamic subtest C-eDP1: SUCCESS (2.235s)
[169.481178] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.828s)
[170.807549] [106/165] (838s left) kms_flip (basic-plain-flip)
[170.908329] Starting subtest: basic-plain-flip
[170.911407] Starting dynamic subtest: A-eDP1
[172.924536] Dynamic subtest A-eDP1: SUCCESS (2.012s)
[172.946731] Starting dynamic subtest: B-eDP1
[174.889781] Dynamic subtest B-eDP1: SUCCESS (1.963s)
[174.908698] Starting dynamic subtest: C-eDP1
[176.874935] Dynamic subtest C-eDP1: SUCCESS (1.983s)
[176.879699] Subtest basic-plain-flip: SUCCESS (5.972s)
[178.171043] [107/165] (831s left) kms_force_connector_basic (force-connector-state)
[178.267018] Starting subtest: force-connector-state
[178.898220] Subtest force-connector-state: SUCCESS (0.630s)
[179.983234] [108/165] (829s left) kms_force_connector_basic (force-edid)
[180.077386] Starting subtest: force-edid
[180.337504] Subtest force-edid: SUCCESS (0.260s)
[180.460807] [109/165] (828s left) kms_force_connector_basic (force-load-detect)
[180.554945] Starting subtest: force-load-detect
[180.557308] Subtest force-load-detect: SKIP (0.000s)
[180.660576] [110/165] (828s left) kms_force_connector_basic (prune-stale-modes)
[180.754477] Starting subtest: prune-stale-modes
[181.072689] Subtest prune-stale-modes: SUCCESS (0.318s)
[181.217413] [111/165] (828s left) kms_frontbuffer_tracking (basic)
[181.343817] Starting subtest: basic
[184.646370] Subtest basic: SUCCESS (3.303s)
[184.770636] [112/165] (824s left) kms_hdmi_inject (inject-audio)
[185.186600] Starting subtest: inject-audio
[185.516239] Subtest inject-audio: SUCCESS (0.328s)
[186.121751] [113/165] (823s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[186.241209] Starting subtest: compare-crc-sanitycheck-xr24
[186.243965] Starting dynamic subtest: pipe-A-eDP-1
[186.932383] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[186.952187] Starting dynamic subtest: pipe-B-eDP-1
[188.374462] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.439s)
[188.395248] Starting dynamic subtest: pipe-C-eDP-1
[189.779573] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.399s)
[189.780881] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.535s)
[190.714854] [114/165] (818s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[190.836153] Starting subtest: compare-crc-sanitycheck-nv12
[190.838955] Starting dynamic subtest: pipe-A-eDP-1
[191.774194] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.936s)
[191.794621] Starting dynamic subtest: pipe-B-eDP-1
[193.169171] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.390s)
[193.189637] Starting dynamic subtest: pipe-C-eDP-1
[194.567166] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.396s)
[194.568718] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.733s)
[195.514773] [115/165] (814s left) kms_pipe_crc_basic (hang-read-crc)
[195.635230] Starting subtest: hang-read-crc
[195.636335] Starting dynamic subtest: pipe-A-eDP-1
[198.000307] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.362s)
[198.020764] Starting dynamic subtest: pipe-B-eDP-1
[201.098856] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.095s)
[201.117847] Starting dynamic subtest: pipe-C-eDP-1
[204.096041] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.994s)
[204.097767] Subtest hang-read-crc: SUCCESS (8.463s)
[205.051134] [116/165] (804s left) kms_pipe_crc_basic (nonblocking-crc)
[205.168128] Starting subtest: nonblocking-crc
[205.170902] Starting dynamic subtest: pipe-A-eDP-1
[206.044779] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.873s)
[206.065332] Starting dynamic subtest: pipe-B-eDP-1
[207.631953] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.583s)
[207.651402] Starting dynamic subtest: pipe-C-eDP-1
[209.214765] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.579s)
[209.215929] Subtest nonblocking-crc: SUCCESS (4.048s)
[210.171820] [117/165] (799s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[210.293382] Starting subtest: nonblocking-crc-frame-sequence
[210.296162] Starting dynamic subtest: pipe-A-eDP-1
[211.182884] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.886s)
[211.219794] Starting dynamic subtest: pipe-B-eDP-1
[212.741248] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.549s)
[212.759878] Starting dynamic subtest: pipe-C-eDP-1
[214.303905] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.559s)
[214.305364] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.010s)
[215.226270] [118/165] (794s left) kms_pipe_crc_basic (read-crc)
[215.345740] Starting subtest: read-crc
[215.348549] Starting dynamic subtest: pipe-A-eDP-1
[216.170610] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.821s)
[216.188856] Starting dynamic subtest: pipe-B-eDP-1
[217.740697] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.567s)
[217.763542] Starting dynamic subtest: pipe-C-eDP-1
[219.246724] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.500s)
[219.248180] Subtest read-crc: SUCCESS (3.900s)
[220.219421] [119/165] (789s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[220.339785] Starting subtest: read-crc-frame-sequence
[220.342786] Starting dynamic subtest: pipe-A-eDP-1
[221.164965] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.822s)
[221.183694] Starting dynamic subtest: pipe-B-eDP-1
[222.734635] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.567s)
[222.757329] Starting dynamic subtest: pipe-C-eDP-1
[224.217628] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.477s)
[224.219566] Subtest read-crc-frame-sequence: SUCCESS (3.880s)
[225.146424] [120/165] (784s left) kms_pm_backlight (basic-brightness)
[225.267936] Starting subtest: basic-brightness
[225.382198] Starting dynamic subtest: eDP-1
[225.956408] Dynamic subtest eDP-1: SUCCESS (0.582s)
[225.957574] Subtest basic-brightness: SUCCESS (0.690s)
[226.879530] [121/165] (782s left) kms_psr (primary_page_flip)
[227.005595] Starting subtest: primary_page_flip
[230.803731] Subtest primary_page_flip: SUCCESS (3.798s)
[231.738246] [122/165] (778s left) kms_psr (cursor_plane_move)
[231.864653] Starting subtest: cursor_plane_move
[235.674377] Subtest cursor_plane_move: SUCCESS (3.811s)
[236.600944] [123/165] (773s left) kms_psr (sprite_plane_onoff)
[236.723856] Starting subtest: sprite_plane_onoff
[240.603075] Subtest sprite_plane_onoff: SUCCESS (3.877s)
[241.523308] [124/165] (768s left) kms_psr (primary_mmap_gtt)
[241.645963] Starting subtest: primary_mmap_gtt
[245.461630] Subtest primary_mmap_gtt: SUCCESS (3.814s)
[246.395321] [125/165] (763s left) kms_setmode (basic-clone-single-crtc)
[246.489402] Starting subtest: basic-clone-single-crtc
[246.492863] Subtest basic-clone-single-crtc: SKIP (0.000s)
[246.580718] [126/165] (763s left) i915_pm_rpm (basic-pci-d3-state)
[248.414061] Starting subtest: basic-pci-d3-state
[252.818079] Subtest basic-pci-d3-state: SUCCESS (4.404s)
[253.026840] [127/165] (756s left) i915_pm_rpm (basic-rte)
[253.119341] Starting subtest: basic-rte
[259.281710] Subtest basic-rte: SUCCESS (6.164s)
[264.607898] [128/165] (745s left) i915_pm_rps (basic-api)
[264.704763] Starting subtest: basic-api
[264.708914] Subtest basic-api: SUCCESS (0.004s)
[264.828028] [129/165] (745s left) prime_self_import (basic-llseek-bad)
[264.917063] Starting subtest: basic-llseek-bad
[264.921382] Subtest basic-llseek-bad: SUCCESS (0.003s)
[265.041412] [130/165] (744s left) prime_self_import (basic-llseek-size)
[265.128332] Starting subtest: basic-llseek-size
[265.132901] Subtest basic-llseek-size: SUCCESS (0.004s)
[265.241667] [131/165] (744s left) prime_self_import (basic-with_fd_dup)
[265.327812] Starting subtest: basic-with_fd_dup
[265.332353] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[265.442097] [132/165] (744s left) prime_self_import (basic-with_one_bo)
[265.528808] Starting subtest: basic-with_one_bo
[265.533404] Subtest basic-with_one_bo: SUCCESS (0.005s)
[265.621443] [133/165] (744s left) prime_self_import (basic-with_one_bo_two_files)
[265.709630] Starting subtest: basic-with_one_bo_two_files
[265.714119] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[265.825100] [134/165] (744s left) prime_self_import (basic-with_two_bos)
[265.913201] Starting subtest: basic-with_two_bos
[265.917846] Subtest basic-with_two_bos: SUCCESS (0.005s)
[266.025927] [135/165] (744s left) prime_vgem (basic-fence-flip)
[266.141128] Starting subtest: basic-fence-flip
[266.682346] Subtest basic-fence-flip: SUCCESS (0.541s)
[266.810390] [136/165] (743s left) prime_vgem (basic-fence-mmap)
[266.938312] Starting subtest: basic-fence-mmap
[266.989169] Subtest basic-fence-mmap: SUCCESS (0.051s)
[267.076438] [137/165] (743s left) prime_vgem (basic-fence-read)
[267.175506] Starting subtest: basic-fence-read
[267.251893] Subtest basic-fence-read: SUCCESS (0.076s)
[267.344711] [138/165] (742s left) prime_vgem (basic-gtt)
[267.482033] Starting subtest: basic-gtt
[267.507896] Subtest basic-gtt: SUCCESS (0.026s)
[267.628792] [139/165] (742s left) prime_vgem (basic-read)
[267.742494] Starting subtest: basic-read
[267.780951] Subtest basic-read: SUCCESS (0.038s)
[267.895663] [140/165] (742s left) prime_vgem (basic-write)
[268.009595] Starting subtest: basic-write
[268.054227] Subtest basic-write: SUCCESS (0.044s)
[268.142668] [141/165] (742s left) vgem_basic (setversion)
[268.235279] Starting subtest: setversion
[268.237403] Subtest setversion: SUCCESS (0.000s)
[268.309474] [142/165] (742s left) vgem_basic (create)
[268.401752] Starting subtest: create
[268.403049] Subtest create: SUCCESS (0.000s)
[268.474964] [143/165] (741s left) vgem_basic (debugfs)
[268.568665] Starting subtest: debugfs
[268.569789] Subtest debugfs: SUCCESS (0.001s)
[268.640933] [144/165] (741s left) vgem_basic (dmabuf-export)
[268.735564] Starting subtest: dmabuf-export
[268.761437] Subtest dmabuf-export: SUCCESS (0.025s)
[268.862097] [145/165] (741s left) vgem_basic (dmabuf-fence)
[268.952808] Starting subtest: dmabuf-fence
[268.954191] Subtest dmabuf-fence: SUCCESS (0.001s)
[269.030582] [146/165] (741s left) vgem_basic (dmabuf-fence-before)
[269.136900] Starting subtest: dmabuf-fence-before
[269.138246] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[269.210476] [147/165] (741s left) vgem_basic (dmabuf-mmap)
[269.303170] Starting subtest: dmabuf-mmap
[269.328149] Subtest dmabuf-mmap: SUCCESS (0.025s)
[269.392264] [148/165] (741s left) vgem_basic (mmap)
[269.487371] Starting subtest: mmap
[269.505484] Subtest mmap: SUCCESS (0.017s)
[269.576476] [149/165] (741s left) vgem_basic (second-client)
[269.687542] Starting subtest: second-client
[269.722595] Subtest second-client: SUCCESS (0.033s)
[269.795364] [150/165] (740s left) vgem_basic (sysfs)
[269.904157] Starting subtest: sysfs
[269.905420] Subtest sysfs: SUCCESS (0.001s)
[269.978228] [151/165] (740s left) core_hotunplug (unbind-rebind)
[270.120293] Starting subtest: unbind-rebind
[274.593447] Subtest unbind-rebind: SUCCESS (4.473s)
[274.629930] [152/165] (736s left) vgem_basic (unload)
[274.721937] Starting subtest: unload
[303.777410] Subtest unload: SUCCESS (29.020s)
[303.885085] [153/165] (706s left) i915_module_load (reload)
[303.975117] Starting subtest: reload
[307.343899] Subtest reload: SUCCESS (3.369s)
[307.373949] [154/165] (703s left) gem_lmem_swapping (basic)
[307.486359] Subtest basic: SKIP (0.000s)
[307.528785] [155/165] (703s left) gem_lmem_swapping (parallel-random-engines)
[307.624857] Subtest parallel-random-engines: SKIP (0.000s)
[307.679195] [156/165] (703s left) gem_lmem_swapping (random-engines)
[307.772274] Subtest random-engines: SKIP (0.000s)
[307.812184] [157/165] (703s left) gem_lmem_swapping (verify-random)
[307.908862] Subtest verify-random: SKIP (0.000s)
[307.967166] [158/165] (702s left) i915_pm_rpm (module-reload)
[320.437999] Starting subtest: module-reload
[354.857865] Subtest module-reload: SUCCESS (34.411s)
[354.880518] [159/165] (656s left) i915_selftest (live)
[354.981788] Starting subtest: live
[354.983070] Starting dynamic subtest: sanitycheck
[356.899535] Dynamic subtest sanitycheck: SUCCESS (1.908s)
[356.914198] Starting dynamic subtest: uncore
[358.794441] Dynamic subtest uncore: SUCCESS (1.891s)
[358.809263] Starting dynamic subtest: workarounds
[360.924368] Dynamic subtest workarounds: SUCCESS (2.127s)
[360.939102] Starting dynamic subtest: gt_engines
[363.216318] Dynamic subtest gt_engines: SUCCESS (2.288s)
[363.231362] Starting dynamic subtest: gt_timelines
[375.987727] Dynamic subtest gt_timelines: SUCCESS (12.768s)
[376.001926] Starting dynamic subtest: gt_contexts
[377.936388] Dynamic subtest gt_contexts: SUCCESS (1.945s)
[377.951158] Starting dynamic subtest: gt_lrc
[382.217548] Dynamic subtest gt_lrc: SUCCESS (4.278s)
[382.231956] Starting dynamic subtest: gt_mocs
[384.182834] Dynamic subtest gt_mocs: SUCCESS (1.960s)
[384.198293] Starting dynamic subtest: gt_pm
[389.294397] Dynamic subtest gt_pm: SUCCESS (5.108s)
[389.309566] Starting dynamic subtest: gt_heartbeat
[391.243438] Dynamic subtest gt_heartbeat: SUCCESS (1.954s)
[391.261017] Starting dynamic subtest: gt_tlb
[394.391561] Dynamic subtest gt_tlb: SUCCESS (3.136s)
[394.406288] Starting dynamic subtest: requests
[402.044772] Dynamic subtest requests: SUCCESS (7.650s)
[402.059556] Starting dynamic subtest: migrate
[406.673463] Dynamic subtest migrate: SUCCESS (4.625s)
[406.688364] Starting dynamic subtest: active
[408.541474] Dynamic subtest active: SUCCESS (1.864s)
[408.556142] Starting dynamic subtest: objects
[411.918656] Dynamic subtest objects: SUCCESS (3.373s)
[411.933217] Starting dynamic subtest: mman
[418.851585] Dynamic subtest mman: SUCCESS (6.929s)
[418.866105] Starting dynamic subtest: dmabuf
[420.782461] Dynamic subtest dmabuf: SUCCESS (1.927s)
[420.797253] Starting dynamic subtest: vma
[422.601662] Dynamic subtest vma: SUCCESS (1.815s)
[422.616420] Starting dynamic subtest: coherency
[427.223508] Dynamic subtest coherency: SUCCESS (4.617s)
[427.238528] Starting dynamic subtest: gtt
[436.665410] Dynamic subtest gtt: SUCCESS (9.439s)
[436.679850] Starting dynamic subtest: gem
[438.686611] Dynamic subtest gem: SUCCESS (2.016s)
[438.701492] Starting dynamic subtest: evict
[442.534807] Dynamic subtest evict: SUCCESS (3.844s)
[442.549213] Starting dynamic subtest: hugepages
[450.308579] Dynamic subtest hugepages: SUCCESS (7.769s)
[450.323619] Starting dynamic subtest: gem_contexts
[465.677560] Dynamic subtest gem_contexts: SUCCESS (15.365s)
[465.692945] Starting dynamic subtest: client
[467.564547] Dynamic subtest client: SUCCESS (1.883s)
[467.579971] Starting dynamic subtest: gem_migrate
[469.392520] Dynamic subtest gem_migrate: SUCCESS (1.824s)
[469.408127] Starting dynamic subtest: reset
[474.807517] Dynamic subtest reset: SUCCESS (5.412s)
[474.822788] Starting dynamic subtest: memory_region
[476.646514] Dynamic subtest memory_region: SUCCESS (1.835s)
[476.661827] Starting dynamic subtest: hangcheck
[501.126468] Dynamic subtest hangcheck: SUCCESS (24.476s)
[501.141773] Starting dynamic subtest: execlists
[511.210630] Dynamic subtest execlists: SUCCESS (10.081s)
[511.226097] Starting dynamic subtest: ring_submission
[513.076461] Dynamic subtest ring_submission: SUCCESS (1.862s)
[513.091726] Starting dynamic subtest: perf
[515.039785] Dynamic subtest perf: SUCCESS (1.963s)
[515.054619] Starting dynamic subtest: slpc
[516.880497] Dynamic subtest slpc: SUCCESS (1.833s)
[516.895917] Starting dynamic subtest: guc
[518.751531] Dynamic subtest guc: SUCCESS (1.867s)
[518.766864] Starting dynamic subtest: guc_multi_lrc
[520.661449] Dynamic subtest guc_multi_lrc: SUCCESS (1.906s)
[520.677148] Starting dynamic subtest: guc_hang
[522.512499] Dynamic subtest guc_hang: SUCCESS (1.848s)
[522.528269] Starting dynamic subtest: late_gt_pm
[524.381727] Dynamic subtest late_gt_pm: SUCCESS (1.866s)
[524.383020] Subtest live: SUCCESS (169.393s)
[524.408713] [160/165] (486s left) dmabuf (all-tests)
[524.508637] Starting subtest: all-tests
[524.509939] Starting dynamic subtest: sanitycheck
[524.578580] Dynamic subtest sanitycheck: SUCCESS (0.070s)
[524.599619] Starting dynamic subtest: dma_fence
[524.771847] Dynamic subtest dma_fence: SUCCESS (0.189s)
[524.795716] Starting dynamic subtest: dma_fence_chain
[542.331700] Dynamic subtest dma_fence_chain: SUCCESS (17.556s)
[542.351162] Starting dynamic subtest: dma_fence_unwrap
[542.398778] Dynamic subtest dma_fence_unwrap: SUCCESS (0.063s)
[542.417960] Starting dynamic subtest: dma_resv
[542.486602] Dynamic subtest dma_resv: SUCCESS (0.084s)
[542.488855] Subtest all-tests: SUCCESS (17.981s)
[542.519933] [161/165] (468s left) i915_suspend (basic-s2idle-without-i915)
[542.607442] Starting subtest: basic-s2idle-without-i915
[565.712240] Subtest basic-s2idle-without-i915: SUCCESS (8.052s)
[566.059554] [162/165] (459s left) i915_suspend (basic-s3-without-i915)
[566.151666] Starting subtest: basic-s3-without-i915
[588.796408] Subtest basic-s3-without-i915: SUCCESS (6.862s)
[589.141917] [163/165] (452s left) gem_exec_suspend (basic-s0)
[589.257774] Starting subtest: basic-S0
[589.258950] Starting dynamic subtest: smem
[608.948434] Dynamic subtest smem: SUCCESS (3.803s)
[608.949621] Subtest basic-S0: SUCCESS (3.805s)
[609.140573] [164/165] (448s left) gem_exec_suspend (basic-s3)
[609.247278] Starting subtest: basic-S3
[609.248533] Starting dynamic subtest: smem
[627.875921] Dynamic subtest smem: SUCCESS (3.568s)
[627.877074] Subtest basic-S3: SUCCESS (3.570s)
[628.083761] [165/165] (444s left) kms_pipe_crc_basic (suspend-read-crc)
[628.211644] Starting subtest: suspend-read-crc
[628.212808] Starting dynamic subtest: pipe-A-eDP-1
[645.891934] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.558s)
[645.926049] Starting dynamic subtest: pipe-B-eDP-1
[663.826285] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.124s)
[663.851326] Starting dynamic subtest: pipe-C-eDP-1
[681.847057] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.133s)
[681.849187] Subtest suspend-read-crc: SUCCESS (8.832s)
[682.731811] Closing watchdogs
Done.