[45.201170] Initializing watchdogs
[45.201255]   /dev/watchdog0
[45.206327] [001/162] (960s left) i915_module_load (load)
[45.271976] Starting subtest: load
[46.942398] Subtest load: SUCCESS (1.669s)
[46.957296] [002/162] (958s left) core_auth (basic-auth)
[47.063308] Starting subtest: basic-auth
[47.063878] Subtest basic-auth: SUCCESS (0.001s)
[47.118960] [003/162] (958s left) debugfs_test (read_all_entries)
[47.184256] Starting subtest: read_all_entries
[47.241653] Subtest read_all_entries: SUCCESS (0.057s)
[47.503140] [004/162] (958s left) debugfs_test (basic-hwmon)
[47.564520] Starting subtest: basic-hwmon
[47.565556] Subtest basic-hwmon: SKIP (0.000s)
[47.819862] [005/162] (957s left) fbdev (eof)
[47.891829] Starting subtest: eof
[47.892569] Subtest eof: SUCCESS (0.001s)
[47.937869] [006/162] (957s left) fbdev (info)
[48.001922] Starting subtest: info
[48.002476] Subtest info: SUCCESS (0.000s)
[48.052766] [007/162] (957s left) fbdev (nullptr)
[48.124774] Starting subtest: nullptr
[48.125491] Subtest nullptr: SUCCESS (0.000s)
[48.170979] [008/162] (957s left) fbdev (read)
[48.241191] Starting subtest: read
[48.474020] Subtest read: SUCCESS (0.233s)
[48.519927] [009/162] (957s left) fbdev (write)
[48.591443] Starting subtest: write
[48.712598] Subtest write: SUCCESS (0.121s)
[48.753061] [010/162] (957s left) gem_basic (bad-close)
[48.811873] Starting subtest: bad-close
[48.813097] Subtest bad-close: SUCCESS (0.000s)
[48.871123] [011/162] (956s left) gem_basic (create-close)
[48.934378] Starting subtest: create-close
[48.935451] Subtest create-close: SUCCESS (0.000s)
[49.008201] [012/162] (956s left) gem_basic (create-fd-close)
[49.079779] Starting subtest: create-fd-close
[49.080585] Subtest create-fd-close: SUCCESS (0.000s)
[49.162157] [013/162] (956s left) gem_busy (busy@all-engines)
[49.252306] Starting subtest: busy
[49.252891] Starting dynamic subtest: all-engines
[49.292090] Dynamic subtest all-engines: SUCCESS (0.040s)
[49.292565] Subtest busy: SUCCESS (0.040s)
[49.662053] [014/162] (956s left) gem_close_race (basic-process)
[49.761814] Starting subtest: basic-process
[49.809047] Subtest basic-process: SUCCESS (0.048s)
[49.995220] [015/162] (955s left) gem_close_race (basic-threads)
[50.094823] Starting subtest: basic-threads
[51.283563] Subtest basic-threads: SUCCESS (1.189s)
[51.482225] [016/162] (954s left) gem_ctx_create (basic)
[51.572909] Starting subtest: basic
[51.573416] Subtest basic: SUCCESS (0.000s)
[51.764831] [017/162] (954s left) gem_ctx_create (basic-files)
[51.860532] Starting subtest: basic-files
[53.872994] Subtest basic-files: SUCCESS (2.012s)
[54.098300] [018/162] (951s left) gem_ctx_exec (basic)
[54.195812] Starting subtest: basic
[54.198068] Subtest basic: SUCCESS (0.002s)
[54.307392] [019/162] (951s left) gem_exec_basic (basic)
[54.404248] Starting subtest: basic
[54.404903] Starting dynamic subtest: rcs0-smem
[54.405501] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[54.411205] Starting dynamic subtest: bcs0-smem
[54.411736] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[54.416684] Starting dynamic subtest: vcs0-smem
[54.417263] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[54.421973] Starting dynamic subtest: vcs1-smem
[54.422528] Dynamic subtest vcs1-smem: SUCCESS (0.000s)
[54.427386] Starting dynamic subtest: vecs0-smem
[54.428022] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[54.428551] Subtest basic: SUCCESS (0.004s)
[54.627983] [020/162] (951s left) gem_exec_create (basic)
[54.697805] Starting subtest: basic
[54.698287] Starting dynamic subtest: smem
[56.737935] Dynamic subtest smem: SUCCESS (2.040s)
[56.738397] Subtest basic: SUCCESS (2.040s)
[56.929545] [021/162] (949s left) gem_exec_fence (basic-busy)
[57.138039] Starting subtest: basic-busy
[57.139488] Starting dynamic subtest: rcs0
[57.168711] Dynamic subtest rcs0: SUCCESS (0.030s)
[57.183456] Starting dynamic subtest: bcs0
[57.197334] Dynamic subtest bcs0: SUCCESS (0.028s)
[57.211606] Starting dynamic subtest: vcs0
[57.218904] Dynamic subtest vcs0: SUCCESS (0.022s)
[57.226988] Starting dynamic subtest: vcs1
[57.242873] Dynamic subtest vcs1: SUCCESS (0.024s)
[57.249636] Starting dynamic subtest: vecs0
[57.268761] Dynamic subtest vecs0: SUCCESS (0.026s)
[57.269511] Subtest basic-busy: SUCCESS (0.131s)
[57.866563] [022/162] (948s left) gem_exec_fence (basic-wait)
[58.089867] Starting subtest: basic-wait
[58.091419] Starting dynamic subtest: rcs0
[58.115599] Dynamic subtest rcs0: SUCCESS (0.026s)
[58.131955] Starting dynamic subtest: bcs0
[58.143530] Dynamic subtest bcs0: SUCCESS (0.028s)
[58.153620] Starting dynamic subtest: vcs0
[58.164025] Dynamic subtest vcs0: SUCCESS (0.021s)
[58.173558] Starting dynamic subtest: vcs1
[58.184210] Dynamic subtest vcs1: SUCCESS (0.020s)
[58.194802] Starting dynamic subtest: vecs0
[58.207060] Dynamic subtest vecs0: SUCCESS (0.023s)
[58.207955] Subtest basic-wait: SUCCESS (0.118s)
[58.786106] [023/162] (947s left) gem_exec_fence (basic-await)
[58.992411] Starting subtest: basic-await
[58.993237] Starting dynamic subtest: rcs0
[59.092117] Dynamic subtest rcs0: SUCCESS (0.100s)
[59.099535] Starting dynamic subtest: bcs0
[59.184188] Dynamic subtest bcs0: SUCCESS (0.092s)
[59.192369] Starting dynamic subtest: vcs0
[59.279101] Dynamic subtest vcs0: SUCCESS (0.095s)
[59.287046] Starting dynamic subtest: vcs1
[59.378078] Dynamic subtest vcs1: SUCCESS (0.099s)
[59.391078] Starting dynamic subtest: vecs0
[59.504078] Dynamic subtest vecs0: SUCCESS (0.126s)
[59.505036] Subtest basic-await: SUCCESS (0.512s)
[60.132563] [024/162] (946s left) gem_exec_fence (nb-await)
[60.339226] Starting subtest: nb-await
[60.340422] Starting dynamic subtest: rcs0
[60.405496] Dynamic subtest rcs0: SUCCESS (0.065s)
[60.424729] Starting dynamic subtest: bcs0
[60.477814] Dynamic subtest bcs0: SUCCESS (0.072s)
[60.499054] Starting dynamic subtest: vcs0
[60.557806] Dynamic subtest vcs0: SUCCESS (0.079s)
[60.578880] Starting dynamic subtest: vcs1
[60.634289] Dynamic subtest vcs1: SUCCESS (0.077s)
[60.655358] Starting dynamic subtest: vecs0
[60.711200] Dynamic subtest vecs0: SUCCESS (0.076s)
[60.712414] Subtest nb-await: SUCCESS (0.372s)
[61.304105] [025/162] (944s left) gem_exec_gttfill (basic)
[61.396376] Starting subtest: basic
[64.968039] Subtest basic: SUCCESS (3.572s)
[65.141990] [026/162] (941s left) gem_exec_parallel (engines)
[65.273125] Starting subtest: engines
[65.273970] Starting dynamic subtest: basic
[66.947747] Dynamic subtest basic: SUCCESS (1.674s)
[66.953817] Starting dynamic subtest: contexts
[69.283376] Dynamic subtest contexts: SUCCESS (2.336s)
[69.289597] Starting dynamic subtest: fds
[71.710133] Dynamic subtest fds: SUCCESS (2.426s)
[71.716305] Starting dynamic subtest: userptr
[73.478186] Dynamic subtest userptr: SUCCESS (1.769s)
[73.478724] Subtest engines: SUCCESS (8.205s)
[73.672370] [027/162] (932s left) gem_exec_store (basic)
[73.741890] Starting subtest: basic
[73.787980] Subtest basic: SUCCESS (0.046s)
[73.977724] [028/162] (932s left) gem_flink_basic (bad-flink)
[74.060647] Starting subtest: bad-flink
[74.061175] Subtest bad-flink: SUCCESS (0.000s)
[74.122470] [029/162] (932s left) gem_flink_basic (bad-open)
[74.184806] Starting subtest: bad-open
[74.185265] Subtest bad-open: SUCCESS (0.000s)
[74.240890] [030/162] (932s left) gem_flink_basic (basic)
[74.308890] Starting subtest: basic
[74.309490] Subtest basic: SUCCESS (0.000s)
[74.404339] [031/162] (931s left) gem_flink_basic (double-flink)
[74.476113] Starting subtest: double-flink
[74.476632] Subtest double-flink: SUCCESS (0.000s)
[74.567611] [032/162] (931s left) gem_flink_basic (flink-lifetime)
[74.646586] Starting subtest: flink-lifetime
[74.647301] Subtest flink-lifetime: SUCCESS (0.001s)
[74.737560] [033/162] (931s left) gem_huc_copy (huc-copy)
[74.816038] Starting subtest: huc-copy
[74.817142] Subtest huc-copy: SKIP (0.000s)
[74.890835] [034/162] (931s left) gem_linear_blits (basic)
[75.023785] Starting subtest: basic
[75.029124] Subtest basic: SUCCESS (0.006s)
[75.151908] [035/162] (931s left) gem_mmap (basic)
[75.301026] Starting subtest: basic
[75.303324] Subtest basic: SUCCESS (0.000s)
[75.376891] [036/162] (931s left) gem_mmap_gtt (basic)
[75.447308] Starting subtest: basic
[75.448034] Subtest basic: SUCCESS (0.000s)
[75.498518] [037/162] (930s left) gem_render_linear_blits (basic)
[75.563100] Starting subtest: basic
[75.587557] Subtest basic: SUCCESS (0.024s)
[75.614802] [038/162] (930s left) gem_render_tiled_blits (basic)
[75.674096] Starting subtest: basic
[75.700477] Subtest basic: SUCCESS (0.026s)
[75.732455] [039/162] (930s left) gem_ringfill (basic-all)
[76.005341] Starting subtest: basic-all
[77.180751] Subtest basic-all: SUCCESS (1.176s)
[77.301455] [040/162] (929s left) gem_softpin (allocator-basic)
[77.388534] Starting subtest: allocator-basic
[77.468909] Subtest allocator-basic: SUCCESS (0.080s)
[77.565382] [041/162] (929s left) gem_softpin (allocator-basic-reserve)
[77.650970] Starting subtest: allocator-basic-reserve
[77.730322] Subtest allocator-basic-reserve: SUCCESS (0.079s)
[77.827092] [042/162] (928s left) gem_softpin (safe-alignment)
[77.901743] Starting subtest: safe-alignment
[77.912184] Subtest safe-alignment: SUCCESS (0.010s)
[78.032146] [043/162] (928s left) gem_sync (basic-all)
[78.124625] Starting subtest: basic-all
[80.204979] Subtest basic-all: SUCCESS (2.080s)
[80.400912] [044/162] (926s left) gem_sync (basic-each)
[80.489781] Starting subtest: basic-each
[82.630971] Subtest basic-each: SUCCESS (2.141s)
[82.813191] [045/162] (923s left) gem_tiled_blits (basic)
[83.007442] Starting subtest: basic
[83.020736] Subtest basic: SUCCESS (0.014s)
[83.187237] [046/162] (923s left) gem_tiled_fence_blits (basic)
[83.437338] Starting subtest: basic
[83.445550] Subtest basic: SUCCESS (0.009s)
[83.582975] [047/162] (923s left) gem_tiled_pread_basic
[83.890183] [048/162] (922s left) gem_wait (busy@all-engines)
[83.967249] Starting subtest: busy
[83.967724] Starting dynamic subtest: all-engines
[84.488938] Dynamic subtest all-engines: SUCCESS (0.521s)
[84.489714] Subtest busy: SUCCESS (0.522s)
[84.698919] [049/162] (922s left) gem_wait (wait@all-engines)
[84.778181] Starting subtest: wait
[84.778759] Starting dynamic subtest: all-engines
[85.805621] Dynamic subtest all-engines: SUCCESS (1.027s)
[85.807230] Subtest wait: SUCCESS (1.027s)
[86.018029] [050/162] (920s left) i915_getparams_basic (basic-eu-total)
[86.090318] Starting subtest: basic-eu-total
[86.091382] Subtest basic-eu-total: SUCCESS (0.000s)
[86.158474] [051/162] (920s left) i915_getparams_basic (basic-subslice-total)
[86.226748] Starting subtest: basic-subslice-total
[86.228214] Subtest basic-subslice-total: SUCCESS (0.000s)
[86.295176] [052/162] (920s left) i915_hangman (error-state-basic)
[86.382196] Starting subtest: error-state-basic
[86.388296] Subtest error-state-basic: SUCCESS (0.005s)
[86.478998] [053/162] (920s left) i915_pciid
[86.604795] [054/162] (920s left) kms_addfb_basic (addfb25-bad-modifier)
[86.665029] Starting subtest: addfb25-bad-modifier
[86.667184] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[86.937825] [055/162] (920s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[86.996229] Starting subtest: addfb25-framebuffer-vs-set-tiling
[86.996704] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[87.272500] [056/162] (919s left) kms_addfb_basic (addfb25-modifier-no-flag)
[87.334571] Starting subtest: addfb25-modifier-no-flag
[87.335122] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[87.605489] [057/162] (919s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[87.670671] Starting subtest: addfb25-x-tiled-legacy
[87.671131] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[87.921077] [058/162] (919s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[87.979344] Starting subtest: addfb25-x-tiled-mismatch-legacy
[87.979922] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[88.238665] [059/162] (918s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[88.493133] Starting subtest: addfb25-yf-tiled-legacy
[88.493724] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[88.575398] [060/162] (918s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[88.841014] Starting subtest: addfb25-y-tiled-legacy
[88.841531] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[88.922454] [061/162] (918s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[89.176956] Starting subtest: addfb25-y-tiled-small-legacy
[89.177586] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[89.256949] [062/162] (917s left) kms_addfb_basic (bad-pitch-0)
[89.321355] Starting subtest: bad-pitch-0
[89.322514] Subtest bad-pitch-0: SUCCESS (0.000s)
[89.587712] [063/162] (917s left) kms_addfb_basic (bad-pitch-1024)
[89.645993] Starting subtest: bad-pitch-1024
[89.646518] Subtest bad-pitch-1024: SUCCESS (0.000s)
[89.927815] [064/162] (917s left) kms_addfb_basic (bad-pitch-128)
[90.006789] Starting subtest: bad-pitch-128
[90.007292] Subtest bad-pitch-128: SUCCESS (0.000s)
[90.272700] [065/162] (916s left) kms_addfb_basic (bad-pitch-256)
[90.337301] Starting subtest: bad-pitch-256
[90.337881] Subtest bad-pitch-256: SUCCESS (0.000s)
[90.605302] [066/162] (916s left) kms_addfb_basic (bad-pitch-32)
[90.664256] Starting subtest: bad-pitch-32
[90.664839] Subtest bad-pitch-32: SUCCESS (0.000s)
[90.941063] [067/162] (916s left) kms_addfb_basic (bad-pitch-63)
[91.010278] Starting subtest: bad-pitch-63
[91.010937] Subtest bad-pitch-63: SUCCESS (0.000s)
[91.272377] [068/162] (915s left) kms_addfb_basic (bad-pitch-65536)
[91.334798] Starting subtest: bad-pitch-65536
[91.335238] Subtest bad-pitch-65536: SUCCESS (0.000s)
[91.609217] [069/162] (915s left) kms_addfb_basic (bad-pitch-999)
[91.682094] Starting subtest: bad-pitch-999
[91.682721] Subtest bad-pitch-999: SUCCESS (0.000s)
[91.938731] [070/162] (915s left) kms_addfb_basic (basic)
[91.998457] Starting subtest: basic
[91.999122] Subtest basic: SUCCESS (0.000s)
[92.274942] [071/162] (914s left) kms_addfb_basic (basic-x-tiled-legacy)
[92.344257] Starting subtest: basic-x-tiled-legacy
[92.344830] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[92.604323] [072/162] (914s left) kms_addfb_basic (basic-y-tiled-legacy)
[92.663367] Starting subtest: basic-y-tiled-legacy
[92.663938] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[92.922808] [073/162] (914s left) kms_addfb_basic (bo-too-small)
[93.180138] Starting subtest: bo-too-small
[93.180821] Subtest bo-too-small: SUCCESS (0.000s)
[93.279484] [074/162] (913s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[93.553621] Starting subtest: bo-too-small-due-to-tiling
[93.554148] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[93.638625] [075/162] (913s left) kms_addfb_basic (clobberred-modifier)
[93.698919] Starting subtest: clobberred-modifier
[93.699406] Subtest clobberred-modifier: SUCCESS (0.000s)
[93.973569] [076/162] (913s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[94.040779] Starting subtest: framebuffer-vs-set-tiling
[94.041331] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[94.306630] [077/162] (912s left) kms_addfb_basic (invalid-get-prop)
[94.371894] Starting subtest: invalid-get-prop
[94.372564] Subtest invalid-get-prop: SUCCESS (0.000s)
[94.637962] [078/162] (912s left) kms_addfb_basic (invalid-get-prop-any)
[94.697756] Starting subtest: invalid-get-prop-any
[94.698323] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[94.980409] [079/162] (912s left) kms_addfb_basic (invalid-set-prop)
[95.061750] Starting subtest: invalid-set-prop
[95.062261] Subtest invalid-set-prop: SUCCESS (0.000s)
[95.340865] [080/162] (911s left) kms_addfb_basic (invalid-set-prop-any)
[95.406634] Starting subtest: invalid-set-prop-any
[95.407183] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[95.671166] [081/162] (911s left) kms_addfb_basic (no-handle)
[95.729401] Starting subtest: no-handle
[95.729969] Subtest no-handle: SUCCESS (0.000s)
[95.988256] [082/162] (911s left) kms_addfb_basic (size-max)
[96.243336] Starting subtest: size-max
[96.243787] Subtest size-max: SUCCESS (0.000s)
[96.320974] [083/162] (911s left) kms_addfb_basic (small-bo)
[96.574840] Starting subtest: small-bo
[96.575425] Subtest small-bo: SUCCESS (0.000s)
[96.657619] [084/162] (910s left) kms_addfb_basic (tile-pitch-mismatch)
[96.726217] Starting subtest: tile-pitch-mismatch
[96.726818] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[97.010847] [085/162] (910s left) kms_addfb_basic (too-high)
[97.281403] Starting subtest: too-high
[97.281962] Subtest too-high: SUCCESS (0.001s)
[97.355983] [086/162] (910s left) kms_addfb_basic (too-wide)
[97.612864] Starting subtest: too-wide
[97.613472] Subtest too-wide: SUCCESS (0.000s)
[97.690453] [087/162] (909s left) kms_addfb_basic (unused-handle)
[97.755345] Starting subtest: unused-handle
[97.755907] Subtest unused-handle: SUCCESS (0.000s)
[98.046961] [088/162] (909s left) kms_addfb_basic (unused-modifier)
[98.123768] Starting subtest: unused-modifier
[98.124812] Subtest unused-modifier: SUCCESS (0.000s)
[98.388138] [089/162] (909s left) kms_addfb_basic (unused-offsets)
[98.447318] Starting subtest: unused-offsets
[98.447865] Subtest unused-offsets: SUCCESS (0.000s)
[98.705826] [090/162] (908s left) kms_addfb_basic (unused-pitches)
[98.765300] Starting subtest: unused-pitches
[98.765894] Subtest unused-pitches: SUCCESS (0.000s)
[99.040405] [091/162] (908s left) kms_busy (basic)
[99.304874] Starting subtest: basic
[99.306135] Starting dynamic subtest: flip
[99.946201] Dynamic subtest flip: SUCCESS (0.639s)
[99.954102] Starting dynamic subtest: modeset
[100.920755] Dynamic subtest modeset: SUCCESS (0.974s)
[100.924106] Subtest basic: SUCCESS (1.615s)
[101.180486] [092/162] (906s left) kms_prop_blob (basic)
[101.244365] Starting subtest: basic
[101.244987] Subtest basic: SUCCESS (0.000s)
[101.310027] [093/162] (906s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[101.568144] Starting subtest: basic-busy-flip-before-cursor-atomic
[101.569252] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[101.625031] [094/162] (905s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[101.878119] Starting subtest: basic-busy-flip-before-cursor-legacy
[101.879201] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[101.941994] [095/162] (905s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[102.196504] Starting subtest: basic-flip-after-cursor-atomic
[103.150943] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.954s)
[103.417119] [096/162] (904s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[103.669175] Starting subtest: basic-flip-after-cursor-legacy
[104.606224] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.937s)
[104.858122] [097/162] (902s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[105.116769] Starting subtest: basic-flip-after-cursor-varying-size
[106.066438] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.950s)
[106.323474] [098/162] (901s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[106.576314] Starting subtest: basic-flip-before-cursor-atomic
[107.530744] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.954s)
[107.776928] [099/162] (899s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[108.032270] Starting subtest: basic-flip-before-cursor-legacy
[108.984101] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.952s)
[109.234324] [100/162] (898s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[109.486125] Starting subtest: basic-flip-before-cursor-varying-size
[110.424680] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.938s)
[110.671448] [101/162] (896s left) kms_flip (basic-flip-vs-dpms)
[110.861975] Starting subtest: basic-flip-vs-dpms
[110.864586] Starting dynamic subtest: A-DP1
[112.034395] Dynamic subtest A-DP1: SUCCESS (1.170s)
[112.047080] Starting dynamic subtest: B-DP1
[113.144500] Dynamic subtest B-DP1: SUCCESS (1.109s)
[113.152859] Starting dynamic subtest: C-DP1
[114.229976] Dynamic subtest C-DP1: SUCCESS (1.085s)
[114.230559] Subtest basic-flip-vs-dpms: SUCCESS (3.369s)
[114.500977] [102/162] (893s left) kms_flip (basic-flip-vs-modeset)
[114.692218] Starting subtest: basic-flip-vs-modeset
[114.693141] Starting dynamic subtest: A-DP1
[115.861454] Dynamic subtest A-DP1: SUCCESS (1.168s)
[115.873728] Starting dynamic subtest: B-DP1
[116.992847] Dynamic subtest B-DP1: SUCCESS (1.130s)
[117.002334] Starting dynamic subtest: C-DP1
[118.119653] Dynamic subtest C-DP1: SUCCESS (1.125s)
[118.120270] Subtest basic-flip-vs-modeset: SUCCESS (3.429s)
[118.374286] [103/162] (889s left) kms_flip (basic-flip-vs-wf_vblank)
[118.565455] Starting subtest: basic-flip-vs-wf_vblank
[118.566788] Starting dynamic subtest: A-DP1
[119.853242] Dynamic subtest A-DP1: SUCCESS (1.286s)
[119.862054] Starting dynamic subtest: B-DP1
[121.094196] Dynamic subtest B-DP1: SUCCESS (1.240s)
[121.101963] Starting dynamic subtest: C-DP1
[122.354974] Dynamic subtest C-DP1: SUCCESS (1.260s)
[122.356228] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.792s)
[122.662724] [104/162] (884s left) kms_flip (basic-plain-flip)
[122.850785] Starting subtest: basic-plain-flip
[122.852229] Starting dynamic subtest: A-DP1
[123.855295] Dynamic subtest A-DP1: SUCCESS (1.003s)
[123.863754] Starting dynamic subtest: B-DP1
[124.835851] Dynamic subtest B-DP1: SUCCESS (0.979s)
[124.844638] Starting dynamic subtest: C-DP1
[125.811524] Dynamic subtest C-DP1: SUCCESS (0.974s)
[125.813311] Subtest basic-plain-flip: SUCCESS (2.963s)
[126.116657] [105/162] (881s left) kms_force_connector_basic (force-connector-state)
[126.363615] Starting subtest: force-connector-state
[127.133895] Subtest force-connector-state: SUCCESS (0.771s)
[127.947664] [106/162] (879s left) kms_force_connector_basic (force-edid)
[128.190896] Starting subtest: force-edid
[128.551080] Subtest force-edid: SUCCESS (0.363s)
[129.064945] [107/162] (878s left) kms_force_connector_basic (force-load-detect)
[129.305347] Starting subtest: force-load-detect
[129.307135] Subtest force-load-detect: SKIP (0.000s)
[129.712383] [108/162] (878s left) kms_force_connector_basic (prune-stale-modes)
[129.974860] Starting subtest: prune-stale-modes
[130.378500] Subtest prune-stale-modes: SUCCESS (0.407s)
[130.898450] [109/162] (876s left) kms_frontbuffer_tracking (basic)
[131.154937] Starting subtest: basic
[133.701998] Subtest basic: SUCCESS (2.547s)
[133.797325] [110/162] (873s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[134.051350] Starting subtest: compare-crc-sanitycheck-xr24
[134.052974] Starting dynamic subtest: pipe-A-DP-1
[134.456976] Dynamic subtest pipe-A-DP-1: SUCCESS (0.403s)
[134.474041] Starting dynamic subtest: pipe-B-DP-1
[135.017247] Dynamic subtest pipe-B-DP-1: SUCCESS (0.558s)
[135.032714] Starting dynamic subtest: pipe-C-DP-1
[135.620687] Dynamic subtest pipe-C-DP-1: SUCCESS (0.602s)
[135.622181] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.569s)
[135.884553] [111/162] (871s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[136.140752] Starting subtest: compare-crc-sanitycheck-nv12
[136.142812] Starting dynamic subtest: pipe-A-DP-1
[136.590890] Dynamic subtest pipe-A-DP-1: SUCCESS (0.448s)
[136.600761] Starting dynamic subtest: pipe-B-DP-1
[137.186846] Dynamic subtest pipe-B-DP-1: SUCCESS (0.593s)
[137.196931] Starting dynamic subtest: pipe-C-DP-1
[137.197844] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[137.198440] Subtest compare-crc-sanitycheck-nv12: SUCCESS (1.048s)
[137.449988] [112/162] (870s left) kms_pipe_crc_basic (hang-read-crc)
[137.702247] Starting subtest: hang-read-crc
[137.703964] Starting dynamic subtest: pipe-A-DP-1
[138.841138] Dynamic subtest pipe-A-DP-1: SUCCESS (1.136s)
[138.851482] Starting dynamic subtest: pipe-B-DP-1
[140.127187] Dynamic subtest pipe-B-DP-1: SUCCESS (1.285s)
[140.138181] Starting dynamic subtest: pipe-C-DP-1
[141.497215] Dynamic subtest pipe-C-DP-1: SUCCESS (1.367s)
[141.497741] Subtest hang-read-crc: SUCCESS (3.795s)
[141.738600] [113/162] (866s left) kms_pipe_crc_basic (nonblocking-crc)
[141.993271] Starting subtest: nonblocking-crc
[141.994976] Starting dynamic subtest: pipe-A-DP-1
[142.579946] Dynamic subtest pipe-A-DP-1: SUCCESS (0.585s)
[142.589890] Starting dynamic subtest: pipe-B-DP-1
[143.304114] Dynamic subtest pipe-B-DP-1: SUCCESS (0.722s)
[143.312673] Starting dynamic subtest: pipe-C-DP-1
[144.060534] Dynamic subtest pipe-C-DP-1: SUCCESS (0.754s)
[144.061121] Subtest nonblocking-crc: SUCCESS (2.067s)
[144.300283] [114/162] (863s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[144.552391] Starting subtest: nonblocking-crc-frame-sequence
[144.553728] Starting dynamic subtest: pipe-A-DP-1
[145.122965] Dynamic subtest pipe-A-DP-1: SUCCESS (0.569s)
[145.133733] Starting dynamic subtest: pipe-B-DP-1
[145.830159] Dynamic subtest pipe-B-DP-1: SUCCESS (0.705s)
[145.840280] Starting dynamic subtest: pipe-C-DP-1
[146.598101] Dynamic subtest pipe-C-DP-1: SUCCESS (0.766s)
[146.598566] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.046s)
[146.838370] [115/162] (860s left) kms_pipe_crc_basic (read-crc)
[147.092347] Starting subtest: read-crc
[147.094168] Starting dynamic subtest: pipe-A-DP-1
[147.614210] Dynamic subtest pipe-A-DP-1: SUCCESS (0.519s)
[147.629981] Starting dynamic subtest: pipe-B-DP-1
[148.258067] Dynamic subtest pipe-B-DP-1: SUCCESS (0.643s)
[148.266645] Starting dynamic subtest: pipe-C-DP-1
[148.941466] Dynamic subtest pipe-C-DP-1: SUCCESS (0.681s)
[148.942706] Subtest read-crc: SUCCESS (1.850s)
[149.173283] [116/162] (858s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[149.432328] Starting subtest: read-crc-frame-sequence
[149.433552] Starting dynamic subtest: pipe-A-DP-1
[149.940988] Dynamic subtest pipe-A-DP-1: SUCCESS (0.507s)
[149.956469] Starting dynamic subtest: pipe-B-DP-1
[150.585102] Dynamic subtest pipe-B-DP-1: SUCCESS (0.641s)
[150.598820] Starting dynamic subtest: pipe-C-DP-1
[151.270992] Dynamic subtest pipe-C-DP-1: SUCCESS (0.685s)
[151.272302] Subtest read-crc-frame-sequence: SUCCESS (1.840s)
[151.516872] [117/162] (856s left) kms_psr (primary_page_flip)
[151.578574] Subtest primary_page_flip: SKIP (0.000s)
[151.610409] [118/162] (856s left) kms_psr (cursor_plane_move)
[151.669872] Subtest cursor_plane_move: SKIP (0.000s)
[151.694267] [119/162] (856s left) kms_psr (sprite_plane_onoff)
[151.753584] Subtest sprite_plane_onoff: SKIP (0.000s)
[151.778152] [120/162] (856s left) kms_psr (primary_mmap_gtt)
[151.835863] Subtest primary_mmap_gtt: SKIP (0.000s)
[151.860433] [121/162] (856s left) kms_setmode (basic-clone-single-crtc)
[151.919041] Starting subtest: basic-clone-single-crtc
[151.920237] Subtest basic-clone-single-crtc: SKIP (0.000s)
[151.988658] [122/162] (855s left) i915_pm_backlight (basic-brightness)
[152.255288] Subtest basic-brightness: SKIP (0.000s)
[152.278230] [123/162] (855s left) i915_pm_rpm (basic-pci-d3-state)
[154.062962] Starting subtest: basic-pci-d3-state
[164.836638] Subtest basic-pci-d3-state: FAIL (10.767s)
[165.363286] [124/162] (842s left) i915_pm_rpm (basic-rte)
[165.426455] Starting subtest: basic-rte
[177.897351] Subtest basic-rte: FAIL (12.441s)
[189.102790] [125/162] (818s left) i915_pm_rps (basic-api)
[189.176289] Starting subtest: basic-api
[189.178537] Subtest basic-api: SUCCESS (0.002s)
[189.240321] [126/162] (818s left) prime_self_import (basic-llseek-bad)
[189.300267] Starting subtest: basic-llseek-bad
[189.301288] Subtest basic-llseek-bad: SUCCESS (0.001s)
[189.381823] [127/162] (818s left) prime_self_import (basic-llseek-size)
[189.451687] Starting subtest: basic-llseek-size
[189.454431] Subtest basic-llseek-size: SUCCESS (0.003s)
[189.531376] [128/162] (818s left) prime_self_import (basic-with_fd_dup)
[189.604772] Starting subtest: basic-with_fd_dup
[189.607327] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[189.706769] [129/162] (818s left) prime_self_import (basic-with_one_bo)
[189.791882] Starting subtest: basic-with_one_bo
[189.794549] Subtest basic-with_one_bo: SUCCESS (0.003s)
[189.849639] [130/162] (818s left) prime_self_import (basic-with_one_bo_two_files)
[189.906541] Starting subtest: basic-with_one_bo_two_files
[189.908946] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[189.998710] [131/162] (818s left) prime_self_import (basic-with_two_bos)
[190.074096] Starting subtest: basic-with_two_bos
[190.075904] Subtest basic-with_two_bos: SUCCESS (0.003s)
[190.165755] [132/162] (817s left) prime_vgem (basic-fence-flip)
[190.268841] Starting subtest: basic-fence-flip
[190.880103] Subtest basic-fence-flip: SUCCESS (0.606s)
[190.974286] [133/162] (817s left) prime_vgem (basic-fence-mmap)
[191.045718] Starting subtest: basic-fence-mmap
[191.065245] Subtest basic-fence-mmap: SUCCESS (0.019s)
[191.157806] [134/162] (817s left) prime_vgem (basic-fence-read)
[191.278222] Starting subtest: basic-fence-read
[191.307505] Subtest basic-fence-read: SUCCESS (0.029s)
[191.421718] [135/162] (816s left) prime_vgem (basic-gtt)
[191.522672] Starting subtest: basic-gtt
[191.531422] Subtest basic-gtt: SUCCESS (0.009s)
[191.653705] [136/162] (816s left) prime_vgem (basic-read)
[191.746593] Starting subtest: basic-read
[191.759882] Subtest basic-read: SUCCESS (0.013s)
[191.861573] [137/162] (816s left) prime_vgem (basic-write)
[191.945999] Starting subtest: basic-write
[191.960390] Subtest basic-write: SUCCESS (0.014s)
[192.059271] [138/162] (816s left) vgem_basic (setversion)
[192.141601] Starting subtest: setversion
[192.142581] Subtest setversion: SUCCESS (0.000s)
[192.189989] [139/162] (816s left) vgem_basic (create)
[192.258178] Starting subtest: create
[192.258699] Subtest create: SUCCESS (0.000s)
[192.305743] [140/162] (816s left) vgem_basic (debugfs)
[192.374739] Starting subtest: debugfs
[192.375248] Subtest debugfs: SUCCESS (0.000s)
[192.423078] [141/162] (815s left) vgem_basic (dmabuf-export)
[192.491489] Starting subtest: dmabuf-export
[192.493655] Subtest dmabuf-export: SUCCESS (0.001s)
[192.602680] [142/162] (815s left) vgem_basic (dmabuf-fence)
[192.692145] Starting subtest: dmabuf-fence
[192.692805] Subtest dmabuf-fence: SUCCESS (0.001s)
[192.741587] [143/162] (815s left) vgem_basic (dmabuf-fence-before)
[192.808380] Starting subtest: dmabuf-fence-before
[192.808901] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[192.856752] [144/162] (815s left) vgem_basic (dmabuf-mmap)
[192.925624] Starting subtest: dmabuf-mmap
[192.935133] Subtest dmabuf-mmap: SUCCESS (0.010s)
[192.972390] [145/162] (815s left) vgem_basic (mmap)
[193.042187] Starting subtest: mmap
[193.048653] Subtest mmap: SUCCESS (0.007s)
[193.091173] [146/162] (815s left) vgem_basic (second-client)
[193.158086] Starting subtest: second-client
[193.193319] Subtest second-client: SUCCESS (0.034s)
[193.264064] [147/162] (815s left) vgem_basic (sysfs)
[193.341859] Starting subtest: sysfs
[193.342444] Subtest sysfs: SUCCESS (0.001s)
[193.391241] [148/162] (815s left) core_hotunplug (unbind-rebind)
[193.508248] Starting subtest: unbind-rebind
[196.599863] Subtest unbind-rebind: SUCCESS (3.092s)
[196.616112] [149/162] (811s left) vgem_basic (unload)
[196.679939] Starting subtest: unload
[225.666643] Subtest unload: SUCCESS (28.985s)
[225.785258] [150/162] (782s left) i915_module_load (reload)
[225.846273] Starting subtest: reload
[228.273869] Subtest reload: SUCCESS (2.428s)
[228.290261] [151/162] (780s left) gem_lmem_swapping (basic)
[228.364642] Subtest basic: SKIP (0.000s)
[228.382420] [152/162] (780s left) gem_lmem_swapping (parallel-random-engines)
[228.444251] Subtest parallel-random-engines: SKIP (0.000s)
[228.465119] [153/162] (780s left) gem_lmem_swapping (random-engines)
[228.527020] Subtest random-engines: SKIP (0.000s)
[228.548430] [154/162] (780s left) gem_lmem_swapping (verify-random)
[228.609649] Subtest verify-random: SKIP (0.000s)
[228.632264] [155/162] (779s left) i915_pm_rpm (module-reload)
[230.813566] Starting subtest: module-reload
[251.015429] Subtest module-reload: FAIL (20.144s)
[251.566361] [156/162] (757s left) i915_selftest (live)
[252.381736] Starting subtest: live
[252.383169] Starting dynamic subtest: sanitycheck
[252.979453] Dynamic subtest sanitycheck: SUCCESS (0.597s)
[252.986511] Starting dynamic subtest: uncore
[253.553289] Dynamic subtest uncore: SUCCESS (0.573s)
[253.560904] Starting dynamic subtest: workarounds
[254.374402] Dynamic subtest workarounds: SUCCESS (0.820s)
[254.383739] Starting dynamic subtest: gt_engines
[255.522457] Dynamic subtest gt_engines: SUCCESS (1.147s)
[255.529654] Starting dynamic subtest: gt_timelines
[265.171239] Dynamic subtest gt_timelines: SUCCESS (9.648s)
[265.178437] Starting dynamic subtest: gt_contexts
[265.847328] Dynamic subtest gt_contexts: SUCCESS (0.675s)
[265.856763] Starting dynamic subtest: gt_lrc
[269.369577] Dynamic subtest gt_lrc: SUCCESS (3.521s)
[269.375953] Starting dynamic subtest: gt_mocs
[270.007377] Dynamic subtest gt_mocs: SUCCESS (0.637s)
[270.016233] Starting dynamic subtest: gt_pm
[276.488291] Dynamic subtest gt_pm: SUCCESS (6.480s)
[276.495323] Starting dynamic subtest: gt_heartbeat
[277.185354] Dynamic subtest gt_heartbeat: SUCCESS (0.696s)
[277.192471] Starting dynamic subtest: gt_tlb
[278.853324] Dynamic subtest gt_tlb: SUCCESS (1.667s)
[278.861662] Starting dynamic subtest: requests
[286.838471] Dynamic subtest requests: SUCCESS (7.984s)
[286.844792] Starting dynamic subtest: migrate
[290.111259] Dynamic subtest migrate: SUCCESS (3.272s)
[290.118511] Starting dynamic subtest: active
[290.710416] Dynamic subtest active: SUCCESS (0.598s)
[290.717537] Starting dynamic subtest: objects
[291.945237] Dynamic subtest objects: SUCCESS (1.234s)
[291.953350] Starting dynamic subtest: mman
[296.012535] Dynamic subtest mman: SUCCESS (4.066s)
[296.019555] Starting dynamic subtest: dmabuf
[296.616474] Dynamic subtest dmabuf: SUCCESS (0.603s)
[296.623805] Starting dynamic subtest: vma
[297.205381] Dynamic subtest vma: SUCCESS (0.588s)
[297.214762] Starting dynamic subtest: coherency
[298.522270] Dynamic subtest coherency: SUCCESS (1.316s)
[298.531625] Starting dynamic subtest: gtt
[306.789412] Dynamic subtest gtt: SUCCESS (8.266s)
[306.798902] Starting dynamic subtest: gem
[307.529502] Dynamic subtest gem: SUCCESS (0.739s)
[307.538911] Starting dynamic subtest: evict
[309.296380] Dynamic subtest evict: SUCCESS (1.766s)
[309.305685] Starting dynamic subtest: hugepages
[316.376250] Dynamic subtest hugepages: SUCCESS (7.079s)
[316.386221] Starting dynamic subtest: gem_contexts
[331.707386] Dynamic subtest gem_contexts: SUCCESS (15.330s)
[331.714933] Starting dynamic subtest: client
[332.324374] Dynamic subtest client: SUCCESS (0.616s)
[332.334074] Starting dynamic subtest: gem_migrate
[332.894382] Dynamic subtest gem_migrate: SUCCESS (0.569s)
[332.902285] Starting dynamic subtest: reset
[334.255423] Dynamic subtest reset: SUCCESS (1.360s)
[334.261392] Starting dynamic subtest: memory_region
[334.805492] Dynamic subtest memory_region: SUCCESS (0.549s)
[334.812617] Starting dynamic subtest: hangcheck
[363.928475] Dynamic subtest hangcheck: SUCCESS (29.122s)
[363.935522] Starting dynamic subtest: execlists
[376.684364] Dynamic subtest execlists: SUCCESS (12.755s)
[376.692197] Starting dynamic subtest: ring_submission
[377.244384] Dynamic subtest ring_submission: SUCCESS (0.559s)
[377.251247] Starting dynamic subtest: perf
[377.864438] Dynamic subtest perf: SUCCESS (0.619s)
[377.870932] Starting dynamic subtest: slpc
[378.421269] Dynamic subtest slpc: SUCCESS (0.556s)
[378.428042] Starting dynamic subtest: guc
[378.981422] Dynamic subtest guc: SUCCESS (0.559s)
[378.988517] Starting dynamic subtest: guc_multi_lrc
[379.555344] Dynamic subtest guc_multi_lrc: SUCCESS (0.573s)
[379.565021] Starting dynamic subtest: guc_hang
[380.148411] Dynamic subtest guc_hang: SUCCESS (0.592s)
[380.155953] Starting dynamic subtest: late_gt_pm
[380.756464] Dynamic subtest late_gt_pm: SUCCESS (0.607s)
[380.757203] Subtest live: SUCCESS (128.376s)
[380.953431] [157/162] (627s left) dmabuf (all-tests)
[381.031808] Starting subtest: all-tests
[381.034824] Starting dynamic subtest: sanitycheck
[381.095498] Dynamic subtest sanitycheck: SUCCESS (0.062s)
[381.115577] Starting dynamic subtest: dma_fence
[381.279924] Dynamic subtest dma_fence: SUCCESS (0.182s)
[381.287399] Starting dynamic subtest: dma_fence_chain
[392.674062] Dynamic subtest dma_fence_chain: SUCCESS (11.393s)
[392.686452] Starting dynamic subtest: dma_fence_unwrap
[392.735550] Dynamic subtest dma_fence_unwrap: SUCCESS (0.060s)
[392.757957] Starting dynamic subtest: dma_resv
[392.805469] Dynamic subtest dma_resv: SUCCESS (0.068s)
[392.806637] Subtest all-tests: SUCCESS (11.775s)
[392.834315] [158/162] (615s left) i915_suspend (basic-s2idle-without-i915)
[392.902907] Starting subtest: basic-s2idle-without-i915
[410.784930] Subtest basic-s2idle-without-i915: SUCCESS (3.508s)
[417.189237] [159/162] (611s left) i915_suspend (basic-s3-without-i915)
[417.253924] Starting subtest: basic-s3-without-i915
[437.570427] Subtest basic-s3-without-i915: SUCCESS (2.993s)
[440.625171] [160/162] (607s left) gem_exec_suspend (basic-s0)
[440.740324] Starting subtest: basic-S0
[440.740881] Starting dynamic subtest: smem
[457.940365] Dynamic subtest smem: SUCCESS (2.879s)
[457.940890] Subtest basic-S0: SUCCESS (2.880s)
[463.088101] [161/162] (604s left) gem_exec_suspend (basic-s3)
[463.160069] Starting subtest: basic-S3
[463.160582] Starting dynamic subtest: smem
[482.318450] Dynamic subtest smem: SUCCESS (3.234s)
[482.325814] Subtest basic-S3: SUCCESS (3.235s)
[486.368865] [162/162] (600s left) kms_pipe_crc_basic (suspend-read-crc)
[486.652479] Starting subtest: suspend-read-crc
[486.653121] Starting dynamic subtest: pipe-A-DP-1
[505.371653] Dynamic subtest pipe-A-DP-1: SUCCESS (2.722s)
[510.910443] Closing watchdogs
Done.