[40.444343] Initializing watchdogs
[40.444589]   /dev/watchdog0
[40.479482] [001/165] (960s left) i915_module_load (load)
[40.650436] Starting subtest: load
[42.195596] Subtest load: SUCCESS (1.531s)
[42.322940] [002/165] (958s left) core_auth (basic-auth)
[42.566070] Starting subtest: basic-auth
[42.586922] Subtest basic-auth: SUCCESS (0.001s)
[42.657434] [003/165] (958s left) debugfs_test (read_all_entries)
[42.823279] Starting subtest: read_all_entries
[42.841796] Subtest read_all_entries: SUCCESS (0.045s)
[42.962193] [004/165] (958s left) debugfs_test (basic-hwmon)
[43.139246] Starting subtest: basic-hwmon
[43.181944] Subtest basic-hwmon: SKIP (0.000s)
[43.265611] [005/165] (958s left) fbdev (eof)
[43.434194] Starting subtest: eof
[43.454533] Subtest eof: SUCCESS (0.001s)
[43.583104] [006/165] (957s left) fbdev (info)
[43.749596] Starting subtest: info
[43.775479] Subtest info: SUCCESS (0.000s)
[43.900041] [007/165] (957s left) fbdev (nullptr)
[44.054709] Starting subtest: nullptr
[44.082047] Subtest nullptr: SUCCESS (0.000s)
[44.214552] [008/165] (957s left) fbdev (read)
[44.363947] Starting subtest: read
[44.634663] Subtest read: SUCCESS (0.275s)
[44.763461] [009/165] (956s left) fbdev (write)
[44.945307] Starting subtest: write
[45.068047] Subtest write: SUCCESS (0.142s)
[45.186082] [010/165] (956s left) gem_basic (bad-close)
[45.406339] Starting subtest: bad-close
[45.463670] Subtest bad-close: SUCCESS (0.000s)
[45.561379] [011/165] (956s left) gem_basic (create-close)
[45.768349] Starting subtest: create-close
[45.819645] Subtest create-close: SUCCESS (0.000s)
[45.904909] [012/165] (956s left) gem_basic (create-fd-close)
[46.078914] Starting subtest: create-fd-close
[46.119675] Subtest create-fd-close: SUCCESS (0.000s)
[46.201981] [013/165] (955s left) gem_busy (busy@all-engines)
[46.394578] Starting subtest: busy
[46.416499] Starting dynamic subtest: all-engines
[46.438328] Dynamic subtest all-engines: SUCCESS (0.029s)
[46.460330] Subtest busy: SUCCESS (0.030s)
[46.778464] [014/165] (955s left) gem_close_race (basic-process)
[46.982363] Starting subtest: basic-process
[47.045750] Subtest basic-process: SUCCESS (0.057s)
[47.226894] [015/165] (954s left) gem_close_race (basic-threads)
[47.414177] Starting subtest: basic-threads
[48.571098] Subtest basic-threads: SUCCESS (1.173s)
[48.796805] [016/165] (953s left) gem_ctx_create (basic)
[48.998552] Starting subtest: basic
[49.021349] Subtest basic: SUCCESS (0.000s)
[49.184328] [017/165] (953s left) gem_ctx_create (basic-files)
[49.354876] Starting subtest: basic-files
[51.380361] Subtest basic-files: SUCCESS (2.021s)
[51.617657] [018/165] (950s left) gem_ctx_exec (basic)
[51.801584] Starting subtest: basic
[51.821432] Subtest basic: SUCCESS (0.001s)
[51.907444] [019/165] (950s left) gem_exec_basic (basic)
[52.104279] Starting subtest: basic
[52.126500] Starting dynamic subtest: rcs0-smem
[52.148188] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[52.177109] Starting dynamic subtest: vcs0-smem
[52.197165] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[52.217378] Subtest basic: SUCCESS (0.002s)
[52.299554] [020/165] (950s left) gem_exec_create (basic)
[52.478999] Starting subtest: basic
[52.498306] Starting dynamic subtest: smem
[54.539793] Dynamic subtest smem: SUCCESS (2.067s)
[54.561406] Subtest basic: SUCCESS (2.067s)
[54.733429] [021/165] (947s left) gem_exec_fence (basic-busy)
[55.032234] Starting subtest: basic-busy
[55.053622] Starting dynamic subtest: rcs0
[55.095602] Dynamic subtest rcs0: SUCCESS (0.021s)
[55.125094] Starting dynamic subtest: vcs0
[55.166038] Dynamic subtest vcs0: SUCCESS (0.022s)
[55.188425] Subtest basic-busy: SUCCESS (0.044s)
[55.668519] [022/165] (946s left) gem_exec_fence (basic-wait)
[55.959643] Starting subtest: basic-wait
[55.979543] Starting dynamic subtest: rcs0
[55.999641] Dynamic subtest rcs0: SUCCESS (0.027s)
[56.032193] Starting dynamic subtest: vcs0
[56.052264] Dynamic subtest vcs0: SUCCESS (0.021s)
[56.080491] Subtest basic-wait: SUCCESS (0.048s)
[56.524565] [023/165] (946s left) gem_exec_fence (basic-await)
[56.810767] Starting subtest: basic-await
[56.830513] Starting dynamic subtest: rcs0
[56.878035] Dynamic subtest rcs0: SUCCESS (0.070s)
[56.904123] Starting dynamic subtest: vcs0
[56.950163] Dynamic subtest vcs0: SUCCESS (0.071s)
[56.971934] Subtest basic-await: SUCCESS (0.142s)
[57.454535] [024/165] (945s left) gem_exec_fence (nb-await)
[57.738496] Starting subtest: nb-await
[57.758361] Starting dynamic subtest: rcs0
[57.800561] Dynamic subtest rcs0: SUCCESS (0.053s)
[57.835528] Starting dynamic subtest: vcs0
[57.857846] Dynamic subtest vcs0: SUCCESS (0.052s)
[57.879764] Subtest nb-await: SUCCESS (0.106s)
[58.364611] [025/165] (944s left) gem_exec_gttfill (basic)
[58.546056] Starting subtest: basic
[68.361041] Subtest basic: SUCCESS (9.842s)
[68.575697] [026/165] (934s left) gem_exec_parallel (engines)
[68.886074] Starting subtest: engines
[68.904840] Starting dynamic subtest: basic
[70.453072] Dynamic subtest basic: SUCCESS (1.570s)
[70.482799] Starting dynamic subtest: contexts
[72.316659] Dynamic subtest contexts: SUCCESS (1.876s)
[72.345029] Starting dynamic subtest: fds
[72.385859] Dynamic subtest fds: SKIP (0.001s)
[72.414186] Starting dynamic subtest: userptr
[73.963730] Dynamic subtest userptr: SUCCESS (1.642s)
[73.983206] Subtest engines: SUCCESS (5.090s)
[74.183678] [027/165] (928s left) gem_exec_store (basic)
[74.431306] Starting subtest: basic
[74.450815] Subtest basic: SUCCESS (0.043s)
[74.608439] [028/165] (928s left) gem_flink_basic (bad-flink)
[74.794451] Starting subtest: bad-flink
[74.815573] Subtest bad-flink: SUCCESS (0.000s)
[74.907869] [029/165] (928s left) gem_flink_basic (bad-open)
[75.099967] Starting subtest: bad-open
[75.119197] Subtest bad-open: SUCCESS (0.000s)
[75.199946] [030/165] (928s left) gem_flink_basic (basic)
[75.381749] Starting subtest: basic
[75.400839] Subtest basic: SUCCESS (0.000s)
[75.485527] [031/165] (927s left) gem_flink_basic (double-flink)
[75.665384] Starting subtest: double-flink
[75.684857] Subtest double-flink: SUCCESS (0.000s)
[75.766815] [032/165] (927s left) gem_flink_basic (flink-lifetime)
[75.962853] Starting subtest: flink-lifetime
[75.985811] Subtest flink-lifetime: SUCCESS (0.002s)
[76.085160] [033/165] (927s left) gem_huc_copy (huc-copy)
[76.310984] Subtest huc-copy: SKIP (0.000s)
[76.397336] [034/165] (927s left) gem_linear_blits (basic)
[76.660852] Starting subtest: basic
[76.684766] Subtest basic: SUCCESS (0.013s)
[76.881773] [035/165] (926s left) gem_mmap (basic)
[77.191909] Starting subtest: basic
[77.290284] Subtest basic: SUCCESS (0.001s)
[77.374292] [036/165] (926s left) gem_mmap_gtt (basic)
[77.559430] Starting subtest: basic
[77.581117] Subtest basic: SUCCESS (0.001s)
[77.663106] [037/165] (926s left) gem_render_linear_blits (basic)
[77.853201] Starting subtest: basic
[77.951374] Subtest basic: SUCCESS (0.035s)
[78.036404] [038/165] (925s left) gem_render_tiled_blits (basic)
[78.199786] Starting subtest: basic
[78.325112] Subtest basic: SUCCESS (0.039s)
[78.403275] [039/165] (925s left) gem_ringfill (basic-all)
[78.620137] Starting subtest: basic-all
[79.717317] Subtest basic-all: SUCCESS (1.119s)
[79.815325] [040/165] (924s left) gem_softpin (allocator-basic)
[80.018454] Subtest allocator-basic: SKIP (0.000s)
[80.120750] [041/165] (924s left) gem_softpin (allocator-basic-reserve)
[80.320699] Subtest allocator-basic-reserve: SKIP (0.000s)
[80.424381] [042/165] (923s left) gem_softpin (safe-alignment)
[80.653405] Starting subtest: safe-alignment
[80.672871] Subtest safe-alignment: SUCCESS (0.007s)
[80.763377] [043/165] (923s left) gem_sync (basic-all)
[80.986141] Starting subtest: basic-all
[82.992101] Subtest basic-all: SUCCESS (2.052s)
[83.197971] [044/165] (921s left) gem_sync (basic-each)
[83.402636] Starting subtest: basic-each
[85.406096] Subtest basic-each: SUCCESS (2.053s)
[85.614286] [045/165] (918s left) gem_tiled_blits (basic)
[85.855605] Starting subtest: basic
[85.895263] Subtest basic: SUCCESS (0.030s)
[86.041550] [046/165] (918s left) gem_tiled_fence_blits (basic)
[86.442509] Starting subtest: basic
[86.464630] Subtest basic: SUCCESS (0.014s)
[86.614290] [047/165] (918s left) gem_tiled_pread_basic
[87.150744] [048/165] (917s left) gem_wait (busy@all-engines)
[87.334728] Starting subtest: busy
[87.356138] Starting dynamic subtest: all-engines
[87.857510] Dynamic subtest all-engines: SUCCESS (0.518s)
[87.877056] Subtest busy: SUCCESS (0.518s)
[88.101274] [049/165] (916s left) gem_wait (wait@all-engines)
[88.281979] Starting subtest: wait
[88.303353] Starting dynamic subtest: all-engines
[89.303989] Dynamic subtest all-engines: SUCCESS (1.023s)
[89.323340] Subtest wait: SUCCESS (1.024s)
[89.539052] [050/165] (915s left) i915_getparams_basic (basic-eu-total)
[89.719313] Starting subtest: basic-eu-total
[89.762048] Subtest basic-eu-total: SUCCESS (0.000s)
[89.843989] [051/165] (915s left) i915_getparams_basic (basic-subslice-total)
[90.005341] Starting subtest: basic-subslice-total
[90.044617] Subtest basic-subslice-total: SUCCESS (0.000s)
[90.124931] [052/165] (914s left) i915_hangman (error-state-basic)
[90.307280] Starting subtest: error-state-basic
[90.326174] Subtest error-state-basic: SUCCESS (0.004s)
[90.436243] [053/165] (914s left) i915_pciid
[90.732420] [054/165] (914s left) kms_addfb_basic (addfb25-4-tiled)
[90.965060] Starting subtest: addfb25-4-tiled
[90.984526] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[91.074627] [055/165] (914s left) kms_addfb_basic (addfb25-bad-modifier)
[91.252542] Starting subtest: addfb25-bad-modifier
[91.272079] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[91.362825] [056/165] (913s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[91.529535] Starting subtest: addfb25-framebuffer-vs-set-tiling
[91.551230] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[91.641749] [057/165] (913s left) kms_addfb_basic (addfb25-modifier-no-flag)
[91.825571] Starting subtest: addfb25-modifier-no-flag
[91.847376] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[91.926289] [058/165] (913s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[92.108363] Starting subtest: addfb25-x-tiled-legacy
[92.127983] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[92.208328] [059/165] (913s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[92.393119] Starting subtest: addfb25-x-tiled-mismatch-legacy
[92.411695] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[92.499712] [060/165] (913s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[92.668263] Starting subtest: addfb25-yf-tiled-legacy
[92.690051] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[92.773176] [061/165] (912s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[92.955873] Starting subtest: addfb25-y-tiled-legacy
[92.976706] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[93.065199] [062/165] (912s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[93.252096] Starting subtest: addfb25-y-tiled-small-legacy
[93.290591] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[93.372419] [063/165] (912s left) kms_addfb_basic (bad-pitch-0)
[93.537114] Starting subtest: bad-pitch-0
[93.558605] Subtest bad-pitch-0: SUCCESS (0.000s)
[93.650159] [064/165] (912s left) kms_addfb_basic (bad-pitch-1024)
[93.840446] Starting subtest: bad-pitch-1024
[93.861339] Subtest bad-pitch-1024: SUCCESS (0.000s)
[93.944034] [065/165] (912s left) kms_addfb_basic (bad-pitch-128)
[94.138657] Starting subtest: bad-pitch-128
[94.157550] Subtest bad-pitch-128: SUCCESS (0.000s)
[94.239709] [066/165] (911s left) kms_addfb_basic (bad-pitch-256)
[94.422245] Starting subtest: bad-pitch-256
[94.443935] Subtest bad-pitch-256: SUCCESS (0.000s)
[94.534079] [067/165] (911s left) kms_addfb_basic (bad-pitch-32)
[94.716390] Starting subtest: bad-pitch-32
[94.737391] Subtest bad-pitch-32: SUCCESS (0.000s)
[94.819772] [068/165] (911s left) kms_addfb_basic (bad-pitch-63)
[94.999154] Starting subtest: bad-pitch-63
[95.020425] Subtest bad-pitch-63: SUCCESS (0.000s)
[95.104504] [069/165] (911s left) kms_addfb_basic (bad-pitch-65536)
[95.292898] Starting subtest: bad-pitch-65536
[95.311823] Subtest bad-pitch-65536: SUCCESS (0.000s)
[95.395294] [070/165] (910s left) kms_addfb_basic (bad-pitch-999)
[95.574831] Starting subtest: bad-pitch-999
[95.596756] Subtest bad-pitch-999: SUCCESS (0.000s)
[95.691621] [071/165] (910s left) kms_addfb_basic (basic)
[95.866413] Starting subtest: basic
[95.886901] Subtest basic: SUCCESS (0.000s)
[95.970241] [072/165] (910s left) kms_addfb_basic (basic-x-tiled-legacy)
[96.151969] Starting subtest: basic-x-tiled-legacy
[96.171410] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[96.254688] [073/165] (910s left) kms_addfb_basic (basic-y-tiled-legacy)
[96.431087] Starting subtest: basic-y-tiled-legacy
[96.449970] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[96.542202] [074/165] (910s left) kms_addfb_basic (bo-too-small)
[96.725909] Starting subtest: bo-too-small
[96.747667] Subtest bo-too-small: SUCCESS (0.000s)
[96.833625] [075/165] (909s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[97.015261] Starting subtest: bo-too-small-due-to-tiling
[97.035828] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[97.120470] [076/165] (909s left) kms_addfb_basic (clobberred-modifier)
[97.307408] Starting subtest: clobberred-modifier
[97.338398] Subtest clobberred-modifier: SUCCESS (0.000s)
[97.439524] [077/165] (909s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[97.649102] Starting subtest: framebuffer-vs-set-tiling
[97.674041] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[97.783464] [078/165] (909s left) kms_addfb_basic (invalid-get-prop)
[97.984925] Starting subtest: invalid-get-prop
[98.009082] Subtest invalid-get-prop: SUCCESS (0.000s)
[98.104061] [079/165] (908s left) kms_addfb_basic (invalid-get-prop-any)
[98.318717] Starting subtest: invalid-get-prop-any
[98.344935] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[98.445709] [080/165] (908s left) kms_addfb_basic (invalid-set-prop)
[98.655721] Starting subtest: invalid-set-prop
[98.681628] Subtest invalid-set-prop: SUCCESS (0.000s)
[98.781453] [081/165] (908s left) kms_addfb_basic (invalid-set-prop-any)
[98.997124] Starting subtest: invalid-set-prop-any
[99.021265] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[99.122891] [082/165] (908s left) kms_addfb_basic (no-handle)
[99.360719] Starting subtest: no-handle
[99.386088] Subtest no-handle: SUCCESS (0.000s)
[99.482610] [083/165] (907s left) kms_addfb_basic (size-max)
[99.676125] Starting subtest: size-max
[99.701923] Subtest size-max: SUCCESS (0.000s)
[99.806565] [084/165] (907s left) kms_addfb_basic (small-bo)
[100.002365] Starting subtest: small-bo
[100.028115] Subtest small-bo: SUCCESS (0.000s)
[100.133458] [085/165] (907s left) kms_addfb_basic (tile-pitch-mismatch)
[100.336825] Starting subtest: tile-pitch-mismatch
[100.364056] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[100.469377] [086/165] (907s left) kms_addfb_basic (too-high)
[100.688872] Starting subtest: too-high
[100.713890] Subtest too-high: SUCCESS (0.001s)
[100.819200] [087/165] (906s left) kms_addfb_basic (too-wide)
[101.019192] Starting subtest: too-wide
[101.043357] Subtest too-wide: SUCCESS (0.000s)
[101.136952] [088/165] (906s left) kms_addfb_basic (unused-handle)
[101.329602] Starting subtest: unused-handle
[101.357169] Subtest unused-handle: SUCCESS (0.000s)
[101.449596] [089/165] (906s left) kms_addfb_basic (unused-modifier)
[101.636708] Starting subtest: unused-modifier
[101.660953] Subtest unused-modifier: SUCCESS (0.000s)
[101.754107] [090/165] (906s left) kms_addfb_basic (unused-offsets)
[101.949609] Starting subtest: unused-offsets
[101.971684] Subtest unused-offsets: SUCCESS (0.000s)
[102.071320] [091/165] (906s left) kms_addfb_basic (unused-pitches)
[102.245455] Starting subtest: unused-pitches
[102.267906] Subtest unused-pitches: SUCCESS (0.000s)
[102.357230] [092/165] (905s left) kms_busy (basic)
[102.567122] Starting subtest: basic
[102.587863] Starting dynamic subtest: flip
[103.319178] Dynamic subtest flip: SUCCESS (0.760s)
[103.348065] Starting dynamic subtest: modeset
[103.920082] Dynamic subtest modeset: SUCCESS (0.609s)
[103.938881] Subtest basic: SUCCESS (1.370s)
[104.115216] [093/165] (904s left) kms_prop_blob (basic)
[104.315975] Starting subtest: basic
[104.337464] Subtest basic: SUCCESS (0.000s)
[104.420277] [094/165] (903s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[104.619540] Starting subtest: basic-busy-flip-before-cursor-atomic
[105.609126] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.991s)
[105.780056] [095/165] (902s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[105.962561] Starting subtest: basic-busy-flip-before-cursor-legacy
[106.926758] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.969s)
[107.108881] [096/165] (901s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[107.300082] Starting subtest: basic-flip-after-cursor-atomic
[108.249384] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.960s)
[108.420531] [097/165] (900s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[108.612581] Starting subtest: basic-flip-after-cursor-legacy
[109.567933] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.955s)
[109.736466] [098/165] (898s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[109.909664] Starting subtest: basic-flip-after-cursor-varying-size
[110.899304] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.990s)
[111.061977] [099/165] (897s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[111.246081] Starting subtest: basic-flip-before-cursor-atomic
[112.206845] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.966s)
[112.387782] [100/165] (896s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[112.578867] Starting subtest: basic-flip-before-cursor-legacy
[113.548449] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.976s)
[113.715639] [101/165] (895s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[113.901948] Starting subtest: basic-flip-before-cursor-varying-size
[114.874076] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.973s)
[115.039443] [102/165] (893s left) kms_dsc (dsc-basic)
[115.258192] Subtest dsc-basic: SKIP (0.000s)
[115.345410] [103/165] (893s left) kms_flip (basic-flip-vs-dpms)
[115.570517] Starting subtest: basic-flip-vs-dpms
[115.592335] Starting dynamic subtest: A-VGA1
[116.855889] Dynamic subtest A-VGA1: SUCCESS (1.310s)
[116.890411] Starting dynamic subtest: B-VGA1
[118.072159] Dynamic subtest B-VGA1: SUCCESS (1.215s)
[118.090681] Subtest basic-flip-vs-dpms: SUCCESS (2.528s)
[118.246904] [104/165] (890s left) kms_flip (basic-flip-vs-modeset)
[118.468742] Starting subtest: basic-flip-vs-modeset
[118.489970] Starting dynamic subtest: A-VGA1
[119.777245] Dynamic subtest A-VGA1: SUCCESS (1.335s)
[119.813075] Starting dynamic subtest: B-VGA1
[121.055836] Dynamic subtest B-VGA1: SUCCESS (1.276s)
[121.078434] Subtest basic-flip-vs-modeset: SUCCESS (2.614s)
[121.228678] [105/165] (887s left) kms_flip (basic-flip-vs-wf_vblank)
[121.451715] Starting subtest: basic-flip-vs-wf_vblank
[121.474555] Starting dynamic subtest: A-VGA1
[122.947109] Dynamic subtest A-VGA1: SUCCESS (1.541s)
[122.976601] Starting dynamic subtest: B-VGA1
[124.442094] Dynamic subtest B-VGA1: SUCCESS (1.498s)
[124.462432] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.043s)
[124.669967] [106/165] (884s left) kms_flip (basic-plain-flip)
[124.882495] Starting subtest: basic-plain-flip
[124.903894] Starting dynamic subtest: A-VGA1
[126.079783] Dynamic subtest A-VGA1: SUCCESS (1.245s)
[126.113663] Starting dynamic subtest: B-VGA1
[127.295071] Dynamic subtest B-VGA1: SUCCESS (1.216s)
[127.314165] Subtest basic-plain-flip: SUCCESS (2.465s)
[127.524523] [107/165] (881s left) kms_force_connector_basic (force-connector-state)
[127.725594] Starting subtest: force-connector-state
[127.855130] Subtest force-connector-state: SUCCESS (0.127s)
[128.071083] [108/165] (881s left) kms_force_connector_basic (force-edid)
[128.257303] Starting subtest: force-edid
[128.279149] Subtest force-edid: SUCCESS (0.024s)
[128.386580] [109/165] (881s left) kms_force_connector_basic (force-load-detect)
[128.571309] Starting subtest: force-load-detect
[128.714751] Subtest force-load-detect: SUCCESS (0.163s)
[128.948217] [110/165] (880s left) kms_force_connector_basic (prune-stale-modes)
[129.127323] Starting subtest: prune-stale-modes
[129.149586] Subtest prune-stale-modes: SUCCESS (0.020s)
[129.274728] [111/165] (880s left) kms_frontbuffer_tracking (basic)
[129.524303] Starting subtest: basic
[131.700430] Subtest basic: SUCCESS (2.253s)
[131.848332] [112/165] (877s left) kms_hdmi_inject (inject-audio)
[132.071276] Starting subtest: inject-audio
[132.270780] Subtest inject-audio: SUCCESS (0.167s)
[132.533588] [113/165] (877s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[132.704482] Starting subtest: compare-crc-sanitycheck-xr24
[132.727500] Starting dynamic subtest: pipe-A-VGA-1
[133.036164] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.330s)
[133.065990] Starting dynamic subtest: pipe-B-VGA-1
[133.396985] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.340s)
[133.416462] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.673s)
[133.562317] [114/165] (876s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[133.760938] Starting subtest: compare-crc-sanitycheck-nv12
[133.783127] Starting dynamic subtest: pipe-A-VGA-1
[133.825354] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[133.857648] Starting dynamic subtest: pipe-B-VGA-1
[133.897173] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[133.941256] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s)
[134.029819] [115/165] (876s left) kms_pipe_crc_basic (hang-read-crc)
[134.221212] Starting subtest: hang-read-crc
[134.248072] Starting dynamic subtest: pipe-A-VGA-1
[135.087866] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.862s)
[135.116863] Starting dynamic subtest: pipe-B-VGA-1
[135.948104] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.870s)
[135.969752] Subtest hang-read-crc: SUCCESS (1.735s)
[136.103770] [116/165] (874s left) kms_pipe_crc_basic (nonblocking-crc)
[136.287596] Starting subtest: nonblocking-crc
[136.307464] Starting dynamic subtest: pipe-A-VGA-1
[136.809957] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.510s)
[136.839687] Starting dynamic subtest: pipe-B-VGA-1
[137.309139] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.511s)
[137.330164] Subtest nonblocking-crc: SUCCESS (1.026s)
[137.479371] [117/165] (872s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[137.671996] Starting subtest: nonblocking-crc-frame-sequence
[137.691913] Starting dynamic subtest: pipe-A-VGA-1
[138.137449] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.481s)
[138.169874] Starting dynamic subtest: pipe-B-VGA-1
[138.644508] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.499s)
[138.663996] Subtest nonblocking-crc-frame-sequence: SUCCESS (0.983s)
[138.808482] [118/165] (871s left) kms_pipe_crc_basic (read-crc)
[138.995040] Starting subtest: read-crc
[139.017241] Starting dynamic subtest: pipe-A-VGA-1
[139.414418] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.413s)
[139.441217] Starting dynamic subtest: pipe-B-VGA-1
[139.890173] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.472s)
[139.911175] Subtest read-crc: SUCCESS (0.888s)
[140.044845] [119/165] (870s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[140.268719] Starting subtest: read-crc-frame-sequence
[140.293954] Starting dynamic subtest: pipe-A-VGA-1
[140.651577] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.422s)
[140.688850] Starting dynamic subtest: pipe-B-VGA-1
[141.079292] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.433s)
[141.107298] Subtest read-crc-frame-sequence: SUCCESS (0.858s)
[141.266714] [120/165] (869s left) kms_pm_backlight (basic-brightness)
[141.517278] Subtest basic-brightness: SKIP (0.000s)
[141.631860] [121/165] (868s left) kms_pm_rpm (basic-pci-d3-state)
[143.120888] Subtest basic-pci-d3-state: SKIP (0.000s)
[143.213771] [122/165] (867s left) kms_pm_rpm (basic-rte)
[143.379034] Starting subtest: basic-rte
[145.010781] Subtest basic-rte: SKIP (1.573s)
[145.141591] [123/165] (865s left) kms_psr (primary_page_flip)
[145.360433] Subtest primary_page_flip: SKIP (0.000s)
[145.442413] [124/165] (865s left) kms_psr (cursor_plane_move)
[145.665614] Subtest cursor_plane_move: SKIP (0.000s)
[145.750214] [125/165] (865s left) kms_psr (sprite_plane_onoff)
[145.947083] Subtest sprite_plane_onoff: SKIP (0.000s)
[146.033303] [126/165] (864s left) kms_psr (primary_mmap_gtt)
[146.249530] Subtest primary_mmap_gtt: SKIP (0.000s)
[146.333687] [127/165] (864s left) kms_setmode (basic-clone-single-crtc)
[146.535835] Starting subtest: basic-clone-single-crtc
[146.595546] Subtest basic-clone-single-crtc: SKIP (0.000s)
[146.689550] [128/165] (864s left) i915_pm_rps (basic-api)
[146.890569] Subtest basic-api: SKIP (0.000s)
[146.978426] [129/165] (864s left) prime_self_import (basic-llseek-bad)
[147.125979] Starting subtest: basic-llseek-bad
[147.171542] Subtest basic-llseek-bad: SUCCESS (0.003s)
[147.258219] [130/165] (864s left) prime_self_import (basic-llseek-size)
[147.421200] Starting subtest: basic-llseek-size
[147.464981] Subtest basic-llseek-size: SUCCESS (0.005s)
[147.546725] [131/165] (863s left) prime_self_import (basic-with_fd_dup)
[147.722047] Starting subtest: basic-with_fd_dup
[147.761748] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[147.849250] [132/165] (863s left) prime_self_import (basic-with_one_bo)
[148.016286] Starting subtest: basic-with_one_bo
[148.060345] Subtest basic-with_one_bo: SUCCESS (0.005s)
[148.146047] [133/165] (863s left) prime_self_import (basic-with_one_bo_two_files)
[148.313504] Starting subtest: basic-with_one_bo_two_files
[148.356849] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[148.437349] [134/165] (863s left) prime_self_import (basic-with_two_bos)
[148.608048] Starting subtest: basic-with_two_bos
[148.647896] Subtest basic-with_two_bos: SUCCESS (0.007s)
[148.736206] [135/165] (863s left) prime_vgem (basic-fence-flip)
[148.951841] Starting subtest: basic-fence-flip
[149.322392] Subtest basic-fence-flip: SUCCESS (0.408s)
[149.450725] [136/165] (862s left) prime_vgem (basic-fence-mmap)
[149.651252] Starting subtest: basic-fence-mmap
[149.679444] Subtest basic-fence-mmap: SUCCESS (0.047s)
[149.792178] [137/165] (862s left) prime_vgem (basic-fence-read)
[149.997134] Starting subtest: basic-fence-read
[150.036693] Subtest basic-fence-read: SUCCESS (0.081s)
[150.141888] [138/165] (861s left) prime_vgem (basic-gtt)
[150.342199] Starting subtest: basic-gtt
[150.364821] Subtest basic-gtt: SUCCESS (0.027s)
[150.475433] [139/165] (861s left) prime_vgem (basic-read)
[150.668356] Starting subtest: basic-read
[150.688516] Subtest basic-read: SUCCESS (0.037s)
[150.792828] [140/165] (861s left) prime_vgem (basic-write)
[151.002128] Starting subtest: basic-write
[151.024329] Subtest basic-write: SUCCESS (0.034s)
[151.132478] [141/165] (861s left) vgem_basic (setversion)
[151.349442] Starting subtest: setversion
[151.390349] Subtest setversion: SUCCESS (0.000s)
[151.473660] [142/165] (860s left) vgem_basic (create)
[151.643361] Starting subtest: create
[151.664092] Subtest create: SUCCESS (0.000s)
[151.750278] [143/165] (860s left) vgem_basic (debugfs)
[151.919949] Starting subtest: debugfs
[151.939990] Subtest debugfs: SUCCESS (0.000s)
[152.035270] [144/165] (860s left) vgem_basic (dmabuf-export)
[152.201249] Starting subtest: dmabuf-export
[152.246119] Subtest dmabuf-export: SUCCESS (0.002s)
[152.331084] [145/165] (860s left) vgem_basic (dmabuf-fence)
[152.520540] Starting subtest: dmabuf-fence
[152.542619] Subtest dmabuf-fence: SUCCESS (0.001s)
[152.626516] [146/165] (860s left) vgem_basic (dmabuf-fence-before)
[152.826933] Starting subtest: dmabuf-fence-before
[152.846613] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[152.929033] [147/165] (859s left) vgem_basic (dmabuf-mmap)
[153.114057] Starting subtest: dmabuf-mmap
[153.133950] Subtest dmabuf-mmap: SUCCESS (0.031s)
[153.222183] [148/165] (859s left) vgem_basic (mmap)
[153.389680] Starting subtest: mmap
[153.412280] Subtest mmap: SUCCESS (0.020s)
[153.498819] [149/165] (859s left) vgem_basic (second-client)
[153.677993] Starting subtest: second-client
[153.721467] Subtest second-client: SUCCESS (0.033s)
[153.803982] [150/165] (859s left) vgem_basic (sysfs)
[153.993052] Starting subtest: sysfs
[154.013371] Subtest sysfs: SUCCESS (0.003s)
[154.100330] [151/165] (858s left) core_hotunplug (unbind-rebind)
[154.327519] Starting subtest: unbind-rebind
[157.191736] Subtest unbind-rebind: SUCCESS (2.849s)
[157.273756] [152/165] (855s left) vgem_basic (unload)
[157.425573] Starting subtest: unload
[186.558877] Subtest unload: SUCCESS (28.965s)
[186.642368] [153/165] (826s left) i915_module_load (reload)
[186.788709] Starting subtest: reload
[188.918322] Subtest reload: SUCCESS (2.125s)
[189.007868] [154/165] (824s left) gem_lmem_swapping (basic)
[189.207923] Subtest basic: SKIP (0.000s)
[189.296374] [155/165] (824s left) gem_lmem_swapping (parallel-random-engines)
[189.478809] Subtest parallel-random-engines: SKIP (0.000s)
[189.563029] [156/165] (823s left) gem_lmem_swapping (random-engines)
[189.820240] Subtest random-engines: SKIP (0.000s)
[189.919274] [157/165] (823s left) gem_lmem_swapping (verify-random)
[190.136205] Subtest verify-random: SKIP (0.000s)
[190.233745] [158/165] (823s left) i915_pm_rpm (module-reload)
[191.769125] Subtest module-reload: SKIP (0.000s)
[191.849120] [159/165] (821s left) i915_selftest (live)
[192.756241] Starting subtest: live
[192.778100] Starting dynamic subtest: sanitycheck
[193.478879] Dynamic subtest sanitycheck: SUCCESS (0.724s)
[193.508858] Starting dynamic subtest: uncore
[194.139723] Dynamic subtest uncore: SUCCESS (0.656s)
[194.170207] Starting dynamic subtest: workarounds
[194.847680] Dynamic subtest workarounds: SUCCESS (0.708s)
[194.877276] Starting dynamic subtest: gt_engines
[195.561739] Dynamic subtest gt_engines: SUCCESS (0.709s)
[195.589103] Starting dynamic subtest: gt_timelines
[200.372041] Dynamic subtest gt_timelines: SUCCESS (4.812s)
[200.401931] Starting dynamic subtest: gt_contexts
[201.094173] Dynamic subtest gt_contexts: SUCCESS (0.715s)
[201.122068] Starting dynamic subtest: gt_lrc
[201.743490] Dynamic subtest gt_lrc: SUCCESS (0.652s)
[201.769325] Starting dynamic subtest: gt_mocs
[202.474148] Dynamic subtest gt_mocs: SUCCESS (0.724s)
[202.505964] Starting dynamic subtest: gt_pm
[203.694317] Dynamic subtest gt_pm: SUCCESS (1.222s)
[203.721307] Starting dynamic subtest: gt_heartbeat
[204.447607] Dynamic subtest gt_heartbeat: SUCCESS (0.751s)
[204.474238] Starting dynamic subtest: gt_tlb
[205.078046] Dynamic subtest gt_tlb: SUCCESS (0.626s)
[205.105803] Starting dynamic subtest: requests
[209.404196] Dynamic subtest requests: SUCCESS (4.328s)
[209.432099] Starting dynamic subtest: migrate
[210.050171] Dynamic subtest migrate: SUCCESS (0.641s)
[210.077865] Starting dynamic subtest: active
[210.716591] Dynamic subtest active: SUCCESS (0.668s)
[210.750719] Starting dynamic subtest: objects
[212.968473] Dynamic subtest objects: SUCCESS (2.248s)
[212.996655] Starting dynamic subtest: mman
[218.886492] Dynamic subtest mman: SUCCESS (5.916s)
[218.913336] Starting dynamic subtest: dmabuf
[219.657538] Dynamic subtest dmabuf: SUCCESS (0.771s)
[219.686216] Starting dynamic subtest: vma
[220.434341] Dynamic subtest vma: SUCCESS (0.771s)
[220.462264] Starting dynamic subtest: coherency
[222.138456] Dynamic subtest coherency: SUCCESS (1.705s)
[222.166430] Starting dynamic subtest: gtt
[226.307253] Dynamic subtest gtt: SUCCESS (4.157s)
[226.335170] Starting dynamic subtest: gem
[227.076147] Dynamic subtest gem: SUCCESS (0.778s)
[227.104435] Starting dynamic subtest: evict
[227.786170] Dynamic subtest evict: SUCCESS (0.705s)
[227.812936] Starting dynamic subtest: hugepages
[228.447368] Dynamic subtest hugepages: SUCCESS (0.660s)
[228.478390] Starting dynamic subtest: gem_contexts
[232.184472] Dynamic subtest gem_contexts: SUCCESS (3.736s)
[232.210296] Starting dynamic subtest: client
[232.852160] Dynamic subtest client: SUCCESS (0.660s)
[232.878168] Starting dynamic subtest: gem_migrate
[233.512499] Dynamic subtest gem_migrate: SUCCESS (0.662s)
[233.540776] Starting dynamic subtest: reset
[234.347119] Dynamic subtest reset: SUCCESS (0.831s)
[234.372858] Starting dynamic subtest: memory_region
[235.072568] Dynamic subtest memory_region: SUCCESS (0.722s)
[235.104421] Starting dynamic subtest: hangcheck
[237.364176] Dynamic subtest hangcheck: SUCCESS (2.292s)
[237.391114] Starting dynamic subtest: execlists
[238.081870] Dynamic subtest execlists: SUCCESS (0.713s)
[238.110465] Starting dynamic subtest: ring_submission
[238.827129] Dynamic subtest ring_submission: SUCCESS (0.743s)
[238.854027] Starting dynamic subtest: perf
[239.468688] Dynamic subtest perf: SUCCESS (0.634s)
[239.498873] Starting dynamic subtest: slpc
[240.122794] Dynamic subtest slpc: SUCCESS (0.648s)
[240.151282] Starting dynamic subtest: guc
[240.837117] Dynamic subtest guc: SUCCESS (0.715s)
[240.871091] Starting dynamic subtest: guc_multi_lrc
[241.484801] Dynamic subtest guc_multi_lrc: SUCCESS (0.642s)
[241.521408] Starting dynamic subtest: guc_hang
[242.141714] Dynamic subtest guc_hang: SUCCESS (0.654s)
[242.176425] Starting dynamic subtest: late_gt_pm
[242.846259] Dynamic subtest late_gt_pm: SUCCESS (0.704s)
[242.871044] Subtest live: SUCCESS (50.113s)
[242.969305] [160/165] (770s left) dmabuf (all-tests)
[243.154091] Starting subtest: all-tests
[243.173154] Starting dynamic subtest: sanitycheck
[243.239733] Dynamic subtest sanitycheck: SUCCESS (0.086s)
[243.268862] Starting dynamic subtest: dma_fence
[243.414204] Dynamic subtest dma_fence: SUCCESS (0.174s)
[243.438730] Starting dynamic subtest: dma_fence_chain
[258.783949] Dynamic subtest dma_fence_chain: SUCCESS (15.374s)
[258.812552] Starting dynamic subtest: dma_fence_unwrap
[258.877164] Dynamic subtest dma_fence_unwrap: SUCCESS (0.082s)
[258.910396] Starting dynamic subtest: dma_resv
[258.955929] Dynamic subtest dma_resv: SUCCESS (0.084s)
[258.975428] Subtest all-tests: SUCCESS (15.813s)
[259.053853] [161/165] (754s left) i915_suspend (basic-s2idle-without-i915)
[259.222446] Starting subtest: basic-s2idle-without-i915
[276.536919] Subtest basic-s2idle-without-i915: SUCCESS (2.279s)
[282.617407] [162/165] (752s left) i915_suspend (basic-s3-without-i915)
[282.754405] Starting subtest: basic-s3-without-i915
[301.446614] Subtest basic-s3-without-i915: SUCCESS (3.684s)
[302.555473] [163/165] (748s left) gem_exec_suspend (basic-s0)
[302.738734] Starting subtest: basic-S0
[302.757213] Starting dynamic subtest: smem
[322.295757] Dynamic subtest smem: SUCCESS (4.760s)
[322.316828] Subtest basic-S0: SUCCESS (4.761s)
[323.351218] [164/165] (743s left) gem_exec_suspend (basic-s3)
[323.529616] Starting subtest: basic-S3
[323.547844] Starting dynamic subtest: smem
[340.735409] Dynamic subtest smem: SUCCESS (3.437s)
[340.756319] Subtest basic-S3: SUCCESS (3.439s)
[344.125880] [165/165] (739s left) kms_pipe_crc_basic (suspend-read-crc)
[344.324017] Starting subtest: suspend-read-crc
[344.343713] Starting dynamic subtest: pipe-A-VGA-1
[363.415848] Dynamic subtest pipe-A-VGA-1: SUCCESS (3.629s)
[363.444075] Starting dynamic subtest: pipe-B-VGA-1
[380.805577] Dynamic subtest pipe-B-VGA-1: SUCCESS (2.575s)
[380.827241] Subtest suspend-read-crc: SUCCESS (6.208s)
[383.943328] Closing watchdogs
Done.