[37.714124] Initializing watchdogs
[37.714297]   /dev/watchdog0
[37.766667] [001/165] (960s left) i915_module_load (load)
[37.965741] Starting subtest: load
[39.518909] Subtest load: SUCCESS (1.564s)
[39.622874] [002/165] (958s left) core_auth (basic-auth)
[39.853272] Starting subtest: basic-auth
[39.872183] Subtest basic-auth: SUCCESS (0.001s)
[39.951082] [003/165] (958s left) debugfs_test (read_all_entries)
[40.121501] Starting subtest: read_all_entries
[40.172795] Subtest read_all_entries: SUCCESS (0.051s)
[40.260762] [004/165] (958s left) debugfs_test (basic-hwmon)
[40.437068] Starting subtest: basic-hwmon
[40.478064] Subtest basic-hwmon: SKIP (0.000s)
[40.553647] [005/165] (958s left) fbdev (eof)
[40.742263] Starting subtest: eof
[40.761737] Subtest eof: SUCCESS (0.001s)
[40.863532] [006/165] (957s left) fbdev (info)
[40.999312] Starting subtest: info
[41.018445] Subtest info: SUCCESS (0.000s)
[41.123571] [007/165] (957s left) fbdev (nullptr)
[41.299586] Starting subtest: nullptr
[41.321497] Subtest nullptr: SUCCESS (0.000s)
[41.415500] [008/165] (957s left) fbdev (read)
[41.566201] Starting subtest: read
[41.831753] Subtest read: SUCCESS (0.269s)
[41.927766] [009/165] (956s left) fbdev (write)
[42.081270] Starting subtest: write
[42.218431] Subtest write: SUCCESS (0.138s)
[42.321707] [010/165] (956s left) gem_basic (bad-close)
[42.516067] Starting subtest: bad-close
[42.557386] Subtest bad-close: SUCCESS (0.000s)
[42.632090] [011/165] (956s left) gem_basic (create-close)
[42.800146] Starting subtest: create-close
[42.838315] Subtest create-close: SUCCESS (0.000s)
[42.917976] [012/165] (956s left) gem_basic (create-fd-close)
[43.094976] Starting subtest: create-fd-close
[43.139076] Subtest create-fd-close: SUCCESS (0.000s)
[43.220112] [013/165] (955s left) gem_busy (busy@all-engines)
[43.406389] Starting subtest: busy
[43.427893] Starting dynamic subtest: all-engines
[43.447322] Dynamic subtest all-engines: SUCCESS (0.030s)
[43.466711] Subtest busy: SUCCESS (0.030s)
[43.769886] [014/165] (955s left) gem_close_race (basic-process)
[43.987873] Starting subtest: basic-process
[44.007631] Subtest basic-process: SUCCESS (0.057s)
[44.217036] [015/165] (955s left) gem_close_race (basic-threads)
[44.402283] Starting subtest: basic-threads
[45.579741] Subtest basic-threads: SUCCESS (1.194s)
[45.798547] [016/165] (953s left) gem_ctx_create (basic)
[45.993742] Starting subtest: basic
[46.014142] Subtest basic: SUCCESS (0.000s)
[46.174925] [017/165] (953s left) gem_ctx_create (basic-files)
[46.339227] Starting subtest: basic-files
[48.365516] Subtest basic-files: SUCCESS (2.022s)
[48.596833] [018/165] (950s left) gem_ctx_exec (basic)
[48.791231] Starting subtest: basic
[48.813563] Subtest basic: SUCCESS (0.001s)
[48.901710] [019/165] (950s left) gem_exec_basic (basic)
[49.096069] Starting subtest: basic
[49.117677] Starting dynamic subtest: rcs0-smem
[49.141975] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[49.178672] Starting dynamic subtest: vcs0-smem
[49.203342] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[49.228097] Subtest basic: SUCCESS (0.001s)
[49.310793] [020/165] (950s left) gem_exec_create (basic)
[49.519116] Starting subtest: basic
[49.541887] Starting dynamic subtest: smem
[51.540068] Dynamic subtest smem: SUCCESS (2.042s)
[51.564180] Subtest basic: SUCCESS (2.042s)
[51.756209] [021/165] (948s left) gem_exec_fence (basic-busy)
[52.063230] Starting subtest: basic-busy
[52.084426] Starting dynamic subtest: rcs0
[52.125447] Dynamic subtest rcs0: SUCCESS (0.020s)
[52.159532] Starting dynamic subtest: vcs0
[52.198254] Dynamic subtest vcs0: SUCCESS (0.035s)
[52.218744] Subtest basic-busy: SUCCESS (0.055s)
[52.716906] [022/165] (947s left) gem_exec_fence (basic-wait)
[53.006260] Starting subtest: basic-wait
[53.025749] Starting dynamic subtest: rcs0
[53.044973] Dynamic subtest rcs0: SUCCESS (0.027s)
[53.077554] Starting dynamic subtest: vcs0
[53.099124] Dynamic subtest vcs0: SUCCESS (0.025s)
[53.121203] Subtest basic-wait: SUCCESS (0.053s)
[53.586652] [023/165] (946s left) gem_exec_fence (basic-await)
[53.885857] Starting subtest: basic-await
[53.905061] Starting dynamic subtest: rcs0
[53.955873] Dynamic subtest rcs0: SUCCESS (0.071s)
[53.983372] Starting dynamic subtest: vcs0
[54.034163] Dynamic subtest vcs0: SUCCESS (0.075s)
[54.055665] Subtest basic-await: SUCCESS (0.147s)
[54.549351] [024/165] (945s left) gem_exec_fence (nb-await)
[54.855823] Starting subtest: nb-await
[54.875527] Starting dynamic subtest: rcs0
[54.895075] Dynamic subtest rcs0: SUCCESS (0.053s)
[54.930259] Starting dynamic subtest: vcs0
[54.952126] Dynamic subtest vcs0: SUCCESS (0.053s)
[54.973520] Subtest nb-await: SUCCESS (0.107s)
[55.451345] [025/165] (944s left) gem_exec_gttfill (basic)
[55.653033] Starting subtest: basic
[65.254463] Subtest basic: SUCCESS (9.630s)
[65.469144] [026/165] (934s left) gem_exec_parallel (engines)
[65.768363] Starting subtest: engines
[65.787299] Starting dynamic subtest: basic
[67.404387] Dynamic subtest basic: SUCCESS (1.644s)
[67.432088] Starting dynamic subtest: contexts
[69.281166] Dynamic subtest contexts: SUCCESS (1.890s)
[69.308748] Starting dynamic subtest: fds
[69.347600] Dynamic subtest fds: SKIP (0.001s)
[69.375514] Starting dynamic subtest: userptr
[71.190597] Dynamic subtest userptr: SUCCESS (1.900s)
[71.210133] Subtest engines: SUCCESS (5.436s)
[71.396663] [027/165] (928s left) gem_exec_store (basic)
[71.639871] Starting subtest: basic
[71.659412] Subtest basic: SUCCESS (0.047s)
[71.816297] [028/165] (928s left) gem_flink_basic (bad-flink)
[71.978234] Starting subtest: bad-flink
[71.999608] Subtest bad-flink: SUCCESS (0.000s)
[72.083216] [029/165] (928s left) gem_flink_basic (bad-open)
[72.265742] Starting subtest: bad-open
[72.287037] Subtest bad-open: SUCCESS (0.000s)
[72.366139] [030/165] (928s left) gem_flink_basic (basic)
[72.545831] Starting subtest: basic
[72.565283] Subtest basic: SUCCESS (0.000s)
[72.641404] [031/165] (927s left) gem_flink_basic (double-flink)
[72.819891] Starting subtest: double-flink
[72.839070] Subtest double-flink: SUCCESS (0.000s)
[72.923889] [032/165] (927s left) gem_flink_basic (flink-lifetime)
[73.088139] Starting subtest: flink-lifetime
[73.109809] Subtest flink-lifetime: SUCCESS (0.002s)
[73.191960] [033/165] (927s left) gem_huc_copy (huc-copy)
[73.397941] Subtest huc-copy: SKIP (0.000s)
[73.477075] [034/165] (927s left) gem_linear_blits (basic)
[73.739756] Starting subtest: basic
[73.759549] Subtest basic: SUCCESS (0.013s)
[73.930408] [035/165] (926s left) gem_mmap (basic)
[74.240633] Starting subtest: basic
[74.340937] Subtest basic: SUCCESS (0.001s)
[74.430672] [036/165] (926s left) gem_mmap_gtt (basic)
[74.622751] Starting subtest: basic
[74.644902] Subtest basic: SUCCESS (0.001s)
[74.729574] [037/165] (926s left) gem_render_linear_blits (basic)
[74.956171] Starting subtest: basic
[75.054317] Subtest basic: SUCCESS (0.033s)
[75.138921] [038/165] (925s left) gem_render_tiled_blits (basic)
[75.321551] Starting subtest: basic
[75.450301] Subtest basic: SUCCESS (0.043s)
[75.532193] [039/165] (925s left) gem_ringfill (basic-all)
[75.778412] Starting subtest: basic-all
[76.851650] Subtest basic-all: SUCCESS (1.109s)
[76.955291] [040/165] (924s left) gem_softpin (allocator-basic)
[77.149888] Subtest allocator-basic: SKIP (0.000s)
[77.257162] [041/165] (924s left) gem_softpin (allocator-basic-reserve)
[77.465245] Subtest allocator-basic-reserve: SKIP (0.000s)
[77.562459] [042/165] (923s left) gem_softpin (safe-alignment)
[77.787387] Starting subtest: safe-alignment
[77.807918] Subtest safe-alignment: SUCCESS (0.006s)
[77.894309] [043/165] (923s left) gem_sync (basic-all)
[78.112219] Starting subtest: basic-all
[80.202547] Subtest basic-all: SUCCESS (2.147s)
[80.415163] [044/165] (921s left) gem_sync (basic-each)
[80.631333] Starting subtest: basic-each
[82.695679] Subtest basic-each: SUCCESS (2.111s)
[82.899632] [045/165] (918s left) gem_tiled_blits (basic)
[83.171434] Starting subtest: basic
[83.212526] Subtest basic: SUCCESS (0.033s)
[83.371795] [046/165] (918s left) gem_tiled_fence_blits (basic)
[83.760405] Starting subtest: basic
[83.782117] Subtest basic: SUCCESS (0.016s)
[83.933507] [047/165] (917s left) gem_tiled_pread_basic
[84.491390] [048/165] (917s left) gem_wait (busy@all-engines)
[84.702224] Starting subtest: busy
[84.723291] Starting dynamic subtest: all-engines
[85.202054] Dynamic subtest all-engines: SUCCESS (0.520s)
[85.221822] Subtest busy: SUCCESS (0.520s)
[85.445894] [049/165] (916s left) gem_wait (wait@all-engines)
[85.635135] Starting subtest: wait
[85.656517] Starting dynamic subtest: all-engines
[86.634939] Dynamic subtest all-engines: SUCCESS (1.022s)
[86.654325] Subtest wait: SUCCESS (1.023s)
[86.886451] [050/165] (915s left) i915_getparams_basic (basic-eu-total)
[87.058219] Starting subtest: basic-eu-total
[87.101423] Subtest basic-eu-total: SUCCESS (0.000s)
[87.179280] [051/165] (914s left) i915_getparams_basic (basic-subslice-total)
[87.387836] Starting subtest: basic-subslice-total
[87.445175] Subtest basic-subslice-total: SUCCESS (0.000s)
[87.544982] [052/165] (914s left) i915_hangman (error-state-basic)
[87.762277] Starting subtest: error-state-basic
[87.794425] Subtest error-state-basic: SUCCESS (0.004s)
[87.921413] [053/165] (914s left) i915_pciid
[88.247644] [054/165] (914s left) kms_addfb_basic (addfb25-4-tiled)
[88.540946] Starting subtest: addfb25-4-tiled
[88.562413] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[88.647408] [055/165] (913s left) kms_addfb_basic (addfb25-bad-modifier)
[88.857907] Starting subtest: addfb25-bad-modifier
[88.878232] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[88.958732] [056/165] (913s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[89.147834] Starting subtest: addfb25-framebuffer-vs-set-tiling
[89.166893] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[89.248617] [057/165] (913s left) kms_addfb_basic (addfb25-modifier-no-flag)
[89.446373] Starting subtest: addfb25-modifier-no-flag
[89.467304] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[89.552503] [058/165] (913s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[89.744534] Starting subtest: addfb25-x-tiled-legacy
[89.766095] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[89.846997] [059/165] (912s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[90.051265] Starting subtest: addfb25-x-tiled-mismatch-legacy
[90.073212] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[90.151867] [060/165] (912s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[90.349513] Starting subtest: addfb25-yf-tiled-legacy
[90.368910] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[90.450469] [061/165] (912s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[90.635867] Starting subtest: addfb25-y-tiled-legacy
[90.657651] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[90.748251] [062/165] (912s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[90.927195] Starting subtest: addfb25-y-tiled-small-legacy
[90.968475] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[91.046273] [063/165] (911s left) kms_addfb_basic (bad-pitch-0)
[91.236105] Starting subtest: bad-pitch-0
[91.255616] Subtest bad-pitch-0: SUCCESS (0.000s)
[91.338080] [064/165] (911s left) kms_addfb_basic (bad-pitch-1024)
[91.513763] Starting subtest: bad-pitch-1024
[91.533728] Subtest bad-pitch-1024: SUCCESS (0.000s)
[91.620615] [065/165] (911s left) kms_addfb_basic (bad-pitch-128)
[91.806740] Starting subtest: bad-pitch-128
[91.828831] Subtest bad-pitch-128: SUCCESS (0.000s)
[91.910472] [066/165] (911s left) kms_addfb_basic (bad-pitch-256)
[92.087767] Starting subtest: bad-pitch-256
[92.108222] Subtest bad-pitch-256: SUCCESS (0.000s)
[92.186653] [067/165] (911s left) kms_addfb_basic (bad-pitch-32)
[92.376532] Starting subtest: bad-pitch-32
[92.396082] Subtest bad-pitch-32: SUCCESS (0.000s)
[92.478224] [068/165] (910s left) kms_addfb_basic (bad-pitch-63)
[92.653207] Starting subtest: bad-pitch-63
[92.673589] Subtest bad-pitch-63: SUCCESS (0.000s)
[92.756611] [069/165] (910s left) kms_addfb_basic (bad-pitch-65536)
[92.948836] Starting subtest: bad-pitch-65536
[92.970623] Subtest bad-pitch-65536: SUCCESS (0.000s)
[93.052614] [070/165] (910s left) kms_addfb_basic (bad-pitch-999)
[93.238969] Starting subtest: bad-pitch-999
[93.260046] Subtest bad-pitch-999: SUCCESS (0.000s)
[93.340573] [071/165] (910s left) kms_addfb_basic (basic)
[93.533656] Starting subtest: basic
[93.553218] Subtest basic: SUCCESS (0.000s)
[93.645323] [072/165] (910s left) kms_addfb_basic (basic-x-tiled-legacy)
[93.831759] Starting subtest: basic-x-tiled-legacy
[93.852236] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[93.938267] [073/165] (909s left) kms_addfb_basic (basic-y-tiled-legacy)
[94.110322] Starting subtest: basic-y-tiled-legacy
[94.132161] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[94.216709] [074/165] (909s left) kms_addfb_basic (bo-too-small)
[94.397438] Starting subtest: bo-too-small
[94.418247] Subtest bo-too-small: SUCCESS (0.000s)
[94.497356] [075/165] (909s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[94.693928] Starting subtest: bo-too-small-due-to-tiling
[94.713398] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[94.797623] [076/165] (909s left) kms_addfb_basic (clobberred-modifier)
[94.981406] Starting subtest: clobberred-modifier
[95.003462] Subtest clobberred-modifier: SUCCESS (0.000s)
[95.087944] [077/165] (908s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[95.258915] Starting subtest: framebuffer-vs-set-tiling
[95.279834] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[95.358514] [078/165] (908s left) kms_addfb_basic (invalid-get-prop)
[95.538399] Starting subtest: invalid-get-prop
[95.557980] Subtest invalid-get-prop: SUCCESS (0.000s)
[95.647376] [079/165] (908s left) kms_addfb_basic (invalid-get-prop-any)
[95.828618] Starting subtest: invalid-get-prop-any
[95.847576] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[95.930928] [080/165] (908s left) kms_addfb_basic (invalid-set-prop)
[96.095866] Starting subtest: invalid-set-prop
[96.118034] Subtest invalid-set-prop: SUCCESS (0.000s)
[96.202215] [081/165] (908s left) kms_addfb_basic (invalid-set-prop-any)
[96.390748] Starting subtest: invalid-set-prop-any
[96.412116] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[96.491905] [082/165] (907s left) kms_addfb_basic (no-handle)
[96.680208] Starting subtest: no-handle
[96.699691] Subtest no-handle: SUCCESS (0.000s)
[96.779595] [083/165] (907s left) kms_addfb_basic (size-max)
[96.953670] Starting subtest: size-max
[96.974354] Subtest size-max: SUCCESS (0.000s)
[97.060893] [084/165] (907s left) kms_addfb_basic (small-bo)
[97.231203] Starting subtest: small-bo
[97.252367] Subtest small-bo: SUCCESS (0.000s)
[97.340890] [085/165] (907s left) kms_addfb_basic (tile-pitch-mismatch)
[97.512417] Starting subtest: tile-pitch-mismatch
[97.533664] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[97.615185] [086/165] (907s left) kms_addfb_basic (too-high)
[97.792623] Starting subtest: too-high
[97.812103] Subtest too-high: SUCCESS (0.001s)
[97.891449] [087/165] (906s left) kms_addfb_basic (too-wide)
[98.076024] Starting subtest: too-wide
[98.094832] Subtest too-wide: SUCCESS (0.000s)
[98.180920] [088/165] (906s left) kms_addfb_basic (unused-handle)
[98.348998] Starting subtest: unused-handle
[98.371121] Subtest unused-handle: SUCCESS (0.000s)
[98.452418] [089/165] (906s left) kms_addfb_basic (unused-modifier)
[98.634190] Starting subtest: unused-modifier
[98.654108] Subtest unused-modifier: SUCCESS (0.000s)
[98.731705] [090/165] (906s left) kms_addfb_basic (unused-offsets)
[98.912245] Starting subtest: unused-offsets
[98.931457] Subtest unused-offsets: SUCCESS (0.000s)
[99.013714] [091/165] (906s left) kms_addfb_basic (unused-pitches)
[99.182660] Starting subtest: unused-pitches
[99.202532] Subtest unused-pitches: SUCCESS (0.000s)
[99.290585] [092/165] (905s left) kms_busy (basic)
[99.484857] Starting subtest: basic
[99.506871] Starting dynamic subtest: flip
[100.267722] Dynamic subtest flip: SUCCESS (0.783s)
[100.297970] Starting dynamic subtest: modeset
[100.850957] Dynamic subtest modeset: SUCCESS (0.598s)
[100.872242] Subtest basic: SUCCESS (1.382s)
[101.047979] [093/165] (904s left) kms_prop_blob (basic)
[101.255128] Starting subtest: basic
[101.274678] Subtest basic: SUCCESS (0.000s)
[101.361405] [094/165] (903s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[101.530085] Starting subtest: basic-busy-flip-before-cursor-atomic
[102.500362] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.970s)
[102.672592] [095/165] (902s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[102.867154] Starting subtest: basic-busy-flip-before-cursor-legacy
[103.815546] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.960s)
[103.982840] [096/165] (901s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[104.182226] Starting subtest: basic-flip-after-cursor-atomic
[105.150794] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.977s)
[105.308657] [097/165] (900s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[105.485641] Starting subtest: basic-flip-after-cursor-legacy
[106.453563] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.970s)
[106.623660] [098/165] (898s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[106.807790] Starting subtest: basic-flip-after-cursor-varying-size
[107.782079] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.972s)
[107.934920] [099/165] (897s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[108.130056] Starting subtest: basic-flip-before-cursor-atomic
[109.105329] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.974s)
[109.288265] [100/165] (896s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[109.476480] Starting subtest: basic-flip-before-cursor-legacy
[110.460716] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.996s)
[110.657888] [101/165] (895s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[110.857984] Starting subtest: basic-flip-before-cursor-varying-size
[111.827153] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.979s)
[111.982648] [102/165] (893s left) kms_dsc (dsc-basic)
[112.186062] Subtest dsc-basic: SKIP (0.000s)
[112.265671] [103/165] (893s left) kms_flip (basic-flip-vs-dpms)
[112.502141] Starting subtest: basic-flip-vs-dpms
[112.522350] Starting dynamic subtest: A-VGA1
[113.778705] Dynamic subtest A-VGA1: SUCCESS (1.290s)
[113.814578] Starting dynamic subtest: B-VGA1
[115.024205] Dynamic subtest B-VGA1: SUCCESS (1.249s)
[115.045091] Subtest basic-flip-vs-dpms: SUCCESS (2.542s)
[115.209723] [104/165] (890s left) kms_flip (basic-flip-vs-modeset)
[115.435870] Starting subtest: basic-flip-vs-modeset
[115.456388] Starting dynamic subtest: A-VGA1
[116.745285] Dynamic subtest A-VGA1: SUCCESS (1.344s)
[116.778930] Starting dynamic subtest: B-VGA1
[117.892831] Dynamic subtest B-VGA1: SUCCESS (1.152s)
[117.911284] Subtest basic-flip-vs-modeset: SUCCESS (2.499s)
[118.067904] [105/165] (888s left) kms_flip (basic-flip-vs-wf_vblank)
[118.293273] Starting subtest: basic-flip-vs-wf_vblank
[118.312757] Starting dynamic subtest: A-VGA1
[119.793352] Dynamic subtest A-VGA1: SUCCESS (1.555s)
[119.822540] Starting dynamic subtest: B-VGA1
[121.275889] Dynamic subtest B-VGA1: SUCCESS (1.481s)
[121.296030] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.038s)
[121.498785] [106/165] (884s left) kms_flip (basic-plain-flip)
[121.707183] Starting subtest: basic-plain-flip
[121.727977] Starting dynamic subtest: A-VGA1
[122.915448] Dynamic subtest A-VGA1: SUCCESS (1.238s)
[122.942038] Starting dynamic subtest: B-VGA1
[124.127763] Dynamic subtest B-VGA1: SUCCESS (1.214s)
[124.149177] Subtest basic-plain-flip: SUCCESS (2.455s)
[124.370028] [107/165] (881s left) kms_force_connector_basic (force-connector-state)
[124.572048] Starting subtest: force-connector-state
[124.681270] Subtest force-connector-state: SUCCESS (0.127s)
[124.888629] [108/165] (881s left) kms_force_connector_basic (force-edid)
[125.063877] Starting subtest: force-edid
[125.085081] Subtest force-edid: SUCCESS (0.026s)
[125.192936] [109/165] (881s left) kms_force_connector_basic (force-load-detect)
[125.391632] Starting subtest: force-load-detect
[125.529507] Subtest force-load-detect: SUCCESS (0.180s)
[125.759026] [110/165] (880s left) kms_force_connector_basic (prune-stale-modes)
[125.928297] Starting subtest: prune-stale-modes
[125.950220] Subtest prune-stale-modes: SUCCESS (0.016s)
[126.055482] [111/165] (880s left) kms_frontbuffer_tracking (basic)
[126.346153] Starting subtest: basic
[128.526163] Subtest basic: SUCCESS (2.254s)
[128.657403] [112/165] (878s left) kms_hdmi_inject (inject-audio)
[128.868495] Starting subtest: inject-audio
[129.061290] Subtest inject-audio: SUCCESS (0.154s)
[129.318211] [113/165] (877s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[129.540039] Starting subtest: compare-crc-sanitycheck-xr24
[129.566546] Starting dynamic subtest: pipe-A-VGA-1
[129.858765] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.322s)
[129.893449] Starting dynamic subtest: pipe-B-VGA-1
[130.184244] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.347s)
[130.210158] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.671s)
[130.389686] [114/165] (876s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[130.611128] Starting subtest: compare-crc-sanitycheck-nv12
[130.635571] Starting dynamic subtest: pipe-A-VGA-1
[130.682574] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[130.722420] Starting dynamic subtest: pipe-B-VGA-1
[130.775663] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[130.817360] Subtest compare-crc-sanitycheck-nv12: SKIP (0.003s)
[130.899350] [115/165] (875s left) kms_pipe_crc_basic (hang-read-crc)
[131.087420] Starting subtest: hang-read-crc
[131.106996] Starting dynamic subtest: pipe-A-VGA-1
[131.926354] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.843s)
[131.954750] Starting dynamic subtest: pipe-B-VGA-1
[132.825858] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.894s)
[132.844639] Subtest hang-read-crc: SUCCESS (1.740s)
[132.982682] [116/165] (873s left) kms_pipe_crc_basic (nonblocking-crc)
[133.166053] Starting subtest: nonblocking-crc
[133.187093] Starting dynamic subtest: pipe-A-VGA-1
[133.655096] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.498s)
[133.683037] Starting dynamic subtest: pipe-B-VGA-1
[134.160472] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.494s)
[134.181523] Subtest nonblocking-crc: SUCCESS (0.995s)
[134.306943] [117/165] (872s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[134.481506] Starting subtest: nonblocking-crc-frame-sequence
[134.500554] Starting dynamic subtest: pipe-A-VGA-1
[134.972959] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.485s)
[135.002270] Starting dynamic subtest: pipe-B-VGA-1
[135.458051] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.492s)
[135.478120] Subtest nonblocking-crc-frame-sequence: SUCCESS (0.980s)
[135.616680] [118/165] (871s left) kms_pipe_crc_basic (read-crc)
[135.803354] Starting subtest: read-crc
[135.823953] Starting dynamic subtest: pipe-A-VGA-1
[136.218283] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.418s)
[136.247857] Starting dynamic subtest: pipe-B-VGA-1
[136.639130] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.427s)
[136.660894] Subtest read-crc: SUCCESS (0.847s)
[136.790531] [119/165] (870s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[136.975053] Starting subtest: read-crc-frame-sequence
[136.993861] Starting dynamic subtest: pipe-A-VGA-1
[137.388724] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.411s)
[137.419648] Starting dynamic subtest: pipe-B-VGA-1
[137.844118] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.451s)
[137.864095] Subtest read-crc-frame-sequence: SUCCESS (0.867s)
[137.994387] [120/165] (869s left) kms_pm_backlight (basic-brightness)
[138.224697] Subtest basic-brightness: SKIP (0.000s)
[138.311953] [121/165] (869s left) kms_pm_rpm (basic-pci-d3-state)
[140.123089] Subtest basic-pci-d3-state: SKIP (0.000s)
[140.206030] [122/165] (867s left) kms_pm_rpm (basic-rte)
[140.368100] Starting subtest: basic-rte
[142.010250] Subtest basic-rte: SKIP (1.586s)
[142.132709] [123/165] (865s left) kms_psr (psr-primary-page-flip)
[142.362077] Subtest psr-primary-page-flip: SKIP (0.000s)
[142.447120] [124/165] (865s left) kms_psr (psr-cursor-plane-move)
[142.662546] Subtest psr-cursor-plane-move: SKIP (0.000s)
[142.749593] [125/165] (864s left) kms_psr (psr-sprite-plane-onoff)
[142.947979] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[143.031057] [126/165] (864s left) kms_psr (psr-primary-mmap-gtt)
[143.253428] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[143.339046] [127/165] (864s left) kms_setmode (basic-clone-single-crtc)
[143.521491] Starting subtest: basic-clone-single-crtc
[143.584198] Subtest basic-clone-single-crtc: SKIP (0.000s)
[143.668053] [128/165] (864s left) i915_pm_rps (basic-api)
[143.902450] Subtest basic-api: SKIP (0.000s)
[143.981050] [129/165] (864s left) prime_self_import (basic-llseek-bad)
[144.147527] Starting subtest: basic-llseek-bad
[144.186640] Subtest basic-llseek-bad: SUCCESS (0.004s)
[144.271704] [130/165] (863s left) prime_self_import (basic-llseek-size)
[144.421310] Starting subtest: basic-llseek-size
[144.462498] Subtest basic-llseek-size: SUCCESS (0.007s)
[144.544760] [131/165] (863s left) prime_self_import (basic-with_fd_dup)
[144.719097] Starting subtest: basic-with_fd_dup
[144.760555] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[144.840631] [132/165] (863s left) prime_self_import (basic-with_one_bo)
[145.014294] Starting subtest: basic-with_one_bo
[145.052829] Subtest basic-with_one_bo: SUCCESS (0.007s)
[145.136076] [133/165] (863s left) prime_self_import (basic-with_one_bo_two_files)
[145.291747] Starting subtest: basic-with_one_bo_two_files
[145.331256] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[145.417471] [134/165] (863s left) prime_self_import (basic-with_two_bos)
[145.585193] Starting subtest: basic-with_two_bos
[145.628605] Subtest basic-with_two_bos: SUCCESS (0.007s)
[145.709256] [135/165] (862s left) prime_vgem (basic-fence-flip)
[145.925600] Starting subtest: basic-fence-flip
[146.266839] Subtest basic-fence-flip: SUCCESS (0.379s)
[146.401423] [136/165] (862s left) prime_vgem (basic-fence-mmap)
[146.609345] Starting subtest: basic-fence-mmap
[146.628850] Subtest basic-fence-mmap: SUCCESS (0.049s)
[146.739106] [137/165] (861s left) prime_vgem (basic-fence-read)
[146.936540] Starting subtest: basic-fence-read
[146.958097] Subtest basic-fence-read: SUCCESS (0.065s)
[147.060600] [138/165] (861s left) prime_vgem (basic-gtt)
[147.293940] Starting subtest: basic-gtt
[147.313457] Subtest basic-gtt: SUCCESS (0.024s)
[147.420380] [139/165] (861s left) prime_vgem (basic-read)
[147.606905] Starting subtest: basic-read
[147.626781] Subtest basic-read: SUCCESS (0.034s)
[147.762075] [140/165] (861s left) prime_vgem (basic-write)
[148.015222] Starting subtest: basic-write
[148.044662] Subtest basic-write: SUCCESS (0.036s)
[148.174337] [141/165] (860s left) vgem_basic (setversion)
[148.405880] Starting subtest: setversion
[148.459185] Subtest setversion: SUCCESS (0.000s)
[148.557818] [142/165] (860s left) vgem_basic (create)
[148.810430] Starting subtest: create
[148.834275] Subtest create: SUCCESS (0.000s)
[148.933281] [143/165] (860s left) vgem_basic (debugfs)
[149.136737] Starting subtest: debugfs
[149.161345] Subtest debugfs: SUCCESS (0.001s)
[149.255741] [144/165] (860s left) vgem_basic (dmabuf-export)
[149.469163] Starting subtest: dmabuf-export
[149.518788] Subtest dmabuf-export: SUCCESS (0.002s)
[149.625988] [145/165] (859s left) vgem_basic (dmabuf-fence)
[149.877558] Starting subtest: dmabuf-fence
[149.903946] Subtest dmabuf-fence: SUCCESS (0.002s)
[150.008481] [146/165] (859s left) vgem_basic (dmabuf-fence-before)
[150.213720] Starting subtest: dmabuf-fence-before
[150.239244] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[150.340831] [147/165] (859s left) vgem_basic (dmabuf-mmap)
[150.536956] Starting subtest: dmabuf-mmap
[150.558993] Subtest dmabuf-mmap: SUCCESS (0.030s)
[150.662831] [148/165] (858s left) vgem_basic (mmap)
[150.882347] Starting subtest: mmap
[150.909875] Subtest mmap: SUCCESS (0.022s)
[151.014409] [149/165] (858s left) vgem_basic (second-client)
[151.218594] Starting subtest: second-client
[151.270924] Subtest second-client: SUCCESS (0.033s)
[151.369184] [150/165] (858s left) vgem_basic (sysfs)
[151.575745] Starting subtest: sysfs
[151.605671] Subtest sysfs: SUCCESS (0.001s)
[151.706922] [151/165] (858s left) core_hotunplug (unbind-rebind)
[151.956330] Starting subtest: unbind-rebind
[154.757885] Subtest unbind-rebind: SUCCESS (2.820s)
[154.838676] [152/165] (855s left) vgem_basic (unload)
[155.008826] Starting subtest: unload
[184.108971] Subtest unload: SUCCESS (28.941s)
[184.188747] [153/165] (825s left) i915_module_load (reload)
[184.363601] Starting subtest: reload
[186.289879] Subtest reload: SUCCESS (1.955s)
[186.398436] [154/165] (823s left) gem_lmem_swapping (basic)
[186.643087] Subtest basic: SKIP (0.000s)
[186.723542] [155/165] (823s left) gem_lmem_swapping (parallel-random-engines)
[186.938305] Subtest parallel-random-engines: SKIP (0.000s)
[187.032933] [156/165] (823s left) gem_lmem_swapping (random-engines)
[187.234951] Subtest random-engines: SKIP (0.000s)
[187.316545] [157/165] (823s left) gem_lmem_swapping (verify-random)
[187.525364] Subtest verify-random: SKIP (0.000s)
[187.610433] [158/165] (822s left) i915_pm_rpm (module-reload)
[189.092325] Subtest module-reload: SKIP (0.000s)
[189.178189] [159/165] (821s left) i915_selftest (live)
[189.956304] Starting subtest: live
[189.975560] Starting dynamic subtest: sanitycheck
[190.659598] Dynamic subtest sanitycheck: SUCCESS (0.699s)
[190.687831] Starting dynamic subtest: uncore
[191.305842] Dynamic subtest uncore: SUCCESS (0.649s)
[191.335885] Starting dynamic subtest: workarounds
[191.979343] Dynamic subtest workarounds: SUCCESS (0.669s)
[192.007240] Starting dynamic subtest: gt_engines
[192.635407] Dynamic subtest gt_engines: SUCCESS (0.658s)
[192.661246] Starting dynamic subtest: gt_timelines
[197.454439] Dynamic subtest gt_timelines: SUCCESS (4.816s)
[197.486624] Starting dynamic subtest: gt_contexts
[198.135514] Dynamic subtest gt_contexts: SUCCESS (0.681s)
[198.166729] Starting dynamic subtest: gt_lrc
[198.812376] Dynamic subtest gt_lrc: SUCCESS (0.673s)
[198.844311] Starting dynamic subtest: gt_mocs
[199.477379] Dynamic subtest gt_mocs: SUCCESS (0.665s)
[199.506896] Starting dynamic subtest: gt_pm
[200.699309] Dynamic subtest gt_pm: SUCCESS (1.217s)
[200.727116] Starting dynamic subtest: gt_heartbeat
[201.393257] Dynamic subtest gt_heartbeat: SUCCESS (0.695s)
[201.421409] Starting dynamic subtest: gt_tlb
[202.093987] Dynamic subtest gt_tlb: SUCCESS (0.695s)
[202.119693] Starting dynamic subtest: requests
[206.389216] Dynamic subtest requests: SUCCESS (4.296s)
[206.421154] Starting dynamic subtest: migrate
[207.073621] Dynamic subtest migrate: SUCCESS (0.684s)
[207.103212] Starting dynamic subtest: active
[207.810385] Dynamic subtest active: SUCCESS (0.733s)
[207.840540] Starting dynamic subtest: objects
[209.917077] Dynamic subtest objects: SUCCESS (2.106s)
[209.967171] Starting dynamic subtest: mman
[216.190666] Dynamic subtest mman: SUCCESS (6.265s)
[216.220746] Starting dynamic subtest: dmabuf
[216.926616] Dynamic subtest dmabuf: SUCCESS (0.730s)
[216.956602] Starting dynamic subtest: vma
[217.598068] Dynamic subtest vma: SUCCESS (0.680s)
[217.627126] Starting dynamic subtest: coherency
[219.417494] Dynamic subtest coherency: SUCCESS (1.817s)
[219.452266] Starting dynamic subtest: gtt
[223.503897] Dynamic subtest gtt: SUCCESS (4.085s)
[223.537242] Starting dynamic subtest: gem
[224.291764] Dynamic subtest gem: SUCCESS (0.787s)
[224.322604] Starting dynamic subtest: evict
[224.993402] Dynamic subtest evict: SUCCESS (0.697s)
[225.026296] Starting dynamic subtest: hugepages
[225.665328] Dynamic subtest hugepages: SUCCESS (0.673s)
[225.691476] Starting dynamic subtest: gem_contexts
[229.438981] Dynamic subtest gem_contexts: SUCCESS (3.775s)
[229.467612] Starting dynamic subtest: client
[230.079414] Dynamic subtest client: SUCCESS (0.635s)
[230.107215] Starting dynamic subtest: gem_migrate
[230.710299] Dynamic subtest gem_migrate: SUCCESS (0.632s)
[230.737313] Starting dynamic subtest: reset
[231.513105] Dynamic subtest reset: SUCCESS (0.796s)
[231.541152] Starting dynamic subtest: memory_region
[232.163387] Dynamic subtest memory_region: SUCCESS (0.650s)
[232.190185] Starting dynamic subtest: hangcheck
[234.429104] Dynamic subtest hangcheck: SUCCESS (2.265s)
[234.460252] Starting dynamic subtest: execlists
[235.074122] Dynamic subtest execlists: SUCCESS (0.644s)
[235.104369] Starting dynamic subtest: ring_submission
[235.721462] Dynamic subtest ring_submission: SUCCESS (0.643s)
[235.749443] Starting dynamic subtest: perf
[236.394229] Dynamic subtest perf: SUCCESS (0.672s)
[236.421766] Starting dynamic subtest: slpc
[237.053518] Dynamic subtest slpc: SUCCESS (0.656s)
[237.082785] Starting dynamic subtest: guc
[237.687458] Dynamic subtest guc: SUCCESS (0.632s)
[237.715125] Starting dynamic subtest: guc_multi_lrc
[238.340377] Dynamic subtest guc_multi_lrc: SUCCESS (0.647s)
[238.368929] Starting dynamic subtest: guc_hang
[238.992292] Dynamic subtest guc_hang: SUCCESS (0.650s)
[239.019163] Starting dynamic subtest: late_gt_pm
[239.651396] Dynamic subtest late_gt_pm: SUCCESS (0.657s)
[239.673966] Subtest live: SUCCESS (49.720s)
[239.759609] [160/165] (771s left) dmabuf (all-tests)
[239.957925] Starting subtest: all-tests
[239.978165] Starting dynamic subtest: sanitycheck
[240.034273] Dynamic subtest sanitycheck: SUCCESS (0.080s)
[240.061392] Starting dynamic subtest: dma_fence
[240.216118] Dynamic subtest dma_fence: SUCCESS (0.178s)
[240.242140] Starting dynamic subtest: dma_fence_chain
[255.655361] Dynamic subtest dma_fence_chain: SUCCESS (15.444s)
[255.691669] Starting dynamic subtest: dma_fence_unwrap
[255.732247] Dynamic subtest dma_fence_unwrap: SUCCESS (0.077s)
[255.758398] Starting dynamic subtest: dma_resv
[255.803995] Dynamic subtest dma_resv: SUCCESS (0.068s)
[255.825871] Subtest all-tests: SUCCESS (15.857s)
[255.907041] [161/165] (754s left) i915_suspend (basic-s2idle-without-i915)
[256.071978] Starting subtest: basic-s2idle-without-i915
[273.516331] Subtest basic-s2idle-without-i915: SUCCESS (2.347s)
[279.627004] [162/165] (752s left) i915_suspend (basic-s3-without-i915)
[279.823422] Starting subtest: basic-s3-without-i915
[298.464717] Subtest basic-s3-without-i915: SUCCESS (3.749s)
[299.591030] [163/165] (748s left) gem_exec_suspend (basic-s0)
[299.772274] Starting subtest: basic-S0
[299.791691] Starting dynamic subtest: smem
[319.276578] Dynamic subtest smem: SUCCESS (4.708s)
[319.296217] Subtest basic-S0: SUCCESS (4.710s)
[320.499609] [164/165] (743s left) gem_exec_suspend (basic-s3)
[320.703903] Starting subtest: basic-S3
[320.739836] Starting dynamic subtest: smem
[337.717884] Dynamic subtest smem: SUCCESS (3.261s)
[337.738801] Subtest basic-S3: SUCCESS (3.262s)
[342.937452] [165/165] (739s left) kms_pipe_crc_basic (suspend-read-crc)
[343.152590] Starting subtest: suspend-read-crc
[343.172245] Starting dynamic subtest: pipe-A-VGA-1
[362.356969] Dynamic subtest pipe-A-VGA-1: SUCCESS (3.533s)
[362.386615] Starting dynamic subtest: pipe-B-VGA-1
[379.796367] Dynamic subtest pipe-B-VGA-1: SUCCESS (2.605s)
[379.817366] Subtest suspend-read-crc: SUCCESS (6.144s)
[382.960716] Closing watchdogs
Done.