[46.526256] Initializing watchdogs
[46.526397]   /dev/watchdog0
[46.536576] [001/165] (960s left) i915_module_load (load)
[46.635946] Starting subtest: load
[49.220934] Subtest load: SUCCESS (2.585s)
[49.248832] [002/165] (957s left) core_auth (basic-auth)
[49.352386] Starting subtest: basic-auth
[49.353524] Subtest basic-auth: SUCCESS (0.001s)
[49.449288] [003/165] (957s left) debugfs_test (read_all_entries)
[49.549862] Starting subtest: read_all_entries
[49.624780] Subtest read_all_entries: SUCCESS (0.074s)
[49.744495] [004/165] (957s left) debugfs_test (basic-hwmon)
[49.837112] Starting subtest: basic-hwmon
[49.839334] Subtest basic-hwmon: SKIP (0.000s)
[49.935654] [005/165] (957s left) fbdev (eof)
[50.040327] Starting subtest: eof
[50.041413] Subtest eof: SUCCESS (0.001s)
[50.123522] [006/165] (957s left) fbdev (info)
[50.226640] Starting subtest: info
[50.227800] Subtest info: SUCCESS (0.000s)
[50.313618] [007/165] (956s left) fbdev (nullptr)
[50.439291] Starting subtest: nullptr
[50.440478] Subtest nullptr: SUCCESS (0.000s)
[50.506548] [008/165] (956s left) fbdev (read)
[50.605992] Starting subtest: read
[57.687604] Subtest read: SUCCESS (7.047s)
[57.729723] [009/165] (949s left) fbdev (write)
[57.830037] Starting subtest: write
[61.610282] Subtest write: SUCCESS (3.745s)
[61.650196] [010/165] (945s left) gem_basic (bad-close)
[61.745171] Starting subtest: bad-close
[61.747480] Subtest bad-close: SUCCESS (0.000s)
[61.825699] [011/165] (945s left) gem_basic (create-close)
[61.915890] Starting subtest: create-close
[61.918238] Subtest create-close: SUCCESS (0.001s)
[62.022218] [012/165] (945s left) gem_basic (create-fd-close)
[62.108630] Starting subtest: create-fd-close
[62.110652] Subtest create-fd-close: SUCCESS (0.000s)
[62.221876] [013/165] (945s left) gem_busy (busy@all-engines)
[62.332936] Starting subtest: busy
[62.334199] Starting dynamic subtest: all-engines
[62.408032] Dynamic subtest all-engines: SUCCESS (0.075s)
[62.409358] Subtest busy: SUCCESS (0.075s)
[62.808206] [014/165] (944s left) gem_close_race (basic-process)
[62.919676] Starting subtest: basic-process
[62.980816] Subtest basic-process: SUCCESS (0.061s)
[63.191623] [015/165] (944s left) gem_close_race (basic-threads)
[63.303583] Starting subtest: basic-threads
[64.492067] Subtest basic-threads: SUCCESS (1.188s)
[64.723920] [016/165] (942s left) gem_ctx_create (basic)
[64.831345] Starting subtest: basic
[64.832519] Subtest basic: SUCCESS (0.000s)
[65.043209] [017/165] (942s left) gem_ctx_create (basic-files)
[65.147227] Starting subtest: basic-files
[67.172213] Subtest basic-files: SUCCESS (2.025s)
[67.447492] [018/165] (940s left) gem_ctx_exec (basic)
[67.542930] Starting subtest: basic
[67.548458] Subtest basic: SUCCESS (0.005s)
[67.662486] [019/165] (939s left) gem_exec_basic (basic)
[67.767350] Starting subtest: basic
[67.768489] Starting dynamic subtest: rcs0-smem
[67.769608] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[67.781093] Starting dynamic subtest: bcs0-smem
[67.782378] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[67.792967] Starting dynamic subtest: vcs0-smem
[67.794269] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[67.804899] Starting dynamic subtest: vecs0-smem
[67.806088] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[67.807334] Subtest basic: SUCCESS (0.005s)
[67.977992] [020/165] (939s left) gem_exec_create (basic)
[68.084292] Starting subtest: basic
[68.085462] Starting dynamic subtest: smem
[70.125213] Dynamic subtest smem: SUCCESS (2.041s)
[70.126329] Subtest basic: SUCCESS (2.041s)
[70.363696] [021/165] (937s left) gem_exec_fence (basic-busy)
[70.621182] Starting subtest: basic-busy
[70.622669] Starting dynamic subtest: rcs0
[70.656961] Dynamic subtest rcs0: SUCCESS (0.043s)
[70.671189] Starting dynamic subtest: bcs0
[70.685673] Dynamic subtest bcs0: SUCCESS (0.029s)
[70.697335] Starting dynamic subtest: vcs0
[70.713604] Dynamic subtest vcs0: SUCCESS (0.028s)
[70.725216] Starting dynamic subtest: vecs0
[70.740358] Dynamic subtest vecs0: SUCCESS (0.027s)
[70.741533] Subtest basic-busy: SUCCESS (0.128s)
[71.349808] [022/165] (936s left) gem_exec_fence (basic-wait)
[71.602510] Starting subtest: basic-wait
[71.604059] Starting dynamic subtest: rcs0
[71.631445] Dynamic subtest rcs0: SUCCESS (0.034s)
[71.644228] Starting dynamic subtest: bcs0
[71.670781] Dynamic subtest bcs0: SUCCESS (0.038s)
[71.686764] Starting dynamic subtest: vcs0
[71.695029] Dynamic subtest vcs0: SUCCESS (0.024s)
[71.708260] Starting dynamic subtest: vecs0
[71.713352] Dynamic subtest vecs0: SUCCESS (0.018s)
[71.714400] Subtest basic-wait: SUCCESS (0.117s)
[72.287208] [023/165] (935s left) gem_exec_fence (basic-await)
[72.542314] Starting subtest: basic-await
[72.543435] Starting dynamic subtest: rcs0
[72.635362] Dynamic subtest rcs0: SUCCESS (0.100s)
[72.655643] Starting dynamic subtest: bcs0
[72.742302] Dynamic subtest bcs0: SUCCESS (0.106s)
[72.769700] Starting dynamic subtest: vcs0
[72.876114] Dynamic subtest vcs0: SUCCESS (0.133s)
[72.903755] Starting dynamic subtest: vecs0
[73.006977] Dynamic subtest vecs0: SUCCESS (0.131s)
[73.009774] Subtest basic-await: SUCCESS (0.473s)
[73.587911] [024/165] (934s left) gem_exec_fence (nb-await)
[73.839959] Starting subtest: nb-await
[73.841177] Starting dynamic subtest: rcs0
[73.898132] Dynamic subtest rcs0: SUCCESS (0.066s)
[73.916390] Starting dynamic subtest: bcs0
[73.967798] Dynamic subtest bcs0: SUCCESS (0.069s)
[73.989447] Starting dynamic subtest: vcs0
[74.037971] Dynamic subtest vcs0: SUCCESS (0.070s)
[74.066805] Starting dynamic subtest: vecs0
[74.111730] Dynamic subtest vecs0: SUCCESS (0.073s)
[74.113868] Subtest nb-await: SUCCESS (0.280s)
[74.654738] [025/165] (933s left) gem_exec_gttfill (basic)
[74.763957] Starting subtest: basic
[81.009262] Subtest basic: SUCCESS (6.237s)
[81.195164] [026/165] (926s left) gem_exec_parallel (engines)
[81.376430] Starting subtest: engines
[81.377623] Starting dynamic subtest: basic
[83.792086] Dynamic subtest basic: SUCCESS (2.394s)
[83.823503] Starting dynamic subtest: contexts
[87.811424] Dynamic subtest contexts: SUCCESS (4.031s)
[87.825466] Starting dynamic subtest: fds
[95.744364] Dynamic subtest fds: SUCCESS (7.943s)
[95.777752] Starting dynamic subtest: userptr
[98.165256] Dynamic subtest userptr: SUCCESS (2.395s)
[98.167358] Subtest engines: SUCCESS (16.764s)
[98.362137] [027/165] (909s left) gem_exec_store (basic)
[98.472404] Starting subtest: basic
[98.540728] Subtest basic: SUCCESS (0.068s)
[98.749278] [028/165] (909s left) gem_flink_basic (bad-flink)
[98.839458] Starting subtest: bad-flink
[98.840654] Subtest bad-flink: SUCCESS (0.000s)
[98.930465] [029/165] (908s left) gem_flink_basic (bad-open)
[99.019515] Starting subtest: bad-open
[99.020839] Subtest bad-open: SUCCESS (0.000s)
[99.113736] [030/165] (908s left) gem_flink_basic (basic)
[99.201864] Starting subtest: basic
[99.203163] Subtest basic: SUCCESS (0.000s)
[99.314878] [031/165] (908s left) gem_flink_basic (double-flink)
[99.404811] Starting subtest: double-flink
[99.406024] Subtest double-flink: SUCCESS (0.000s)
[99.514771] [032/165] (908s left) gem_flink_basic (flink-lifetime)
[99.601968] Starting subtest: flink-lifetime
[99.604423] Subtest flink-lifetime: SUCCESS (0.002s)
[99.714558] [033/165] (908s left) gem_huc_copy (huc-copy)
[99.811852] Starting subtest: huc-copy
[99.814188] Subtest huc-copy: SKIP (0.000s)
[99.930891] [034/165] (908s left) gem_linear_blits (basic)
[100.165328] Starting subtest: basic
[100.178993] Subtest basic: SUCCESS (0.013s)
[100.317654] [035/165] (907s left) gem_mmap (basic)
[100.477426] Starting subtest: basic
[100.482237] Subtest basic: SUCCESS (0.001s)
[100.583271] [036/165] (907s left) gem_mmap_gtt (basic)
[100.672919] Starting subtest: basic
[100.674150] Subtest basic: SUCCESS (0.001s)
[100.758277] [037/165] (907s left) gem_render_linear_blits (basic)
[100.856104] Starting subtest: basic
[100.906969] Subtest basic: SUCCESS (0.051s)
[100.974523] [038/165] (907s left) gem_render_tiled_blits (basic)
[101.067208] Starting subtest: basic
[101.125905] Subtest basic: SUCCESS (0.059s)
[101.195106] [039/165] (906s left) gem_ringfill (basic-all)
[101.426421] Starting subtest: basic-all
[102.678716] Subtest basic-all: SUCCESS (1.243s)
[102.775674] [040/165] (905s left) gem_softpin (allocator-basic)
[102.873493] Starting subtest: allocator-basic
[103.042793] Subtest allocator-basic: SUCCESS (0.160s)
[103.152655] [041/165] (905s left) gem_softpin (allocator-basic-reserve)
[103.248887] Starting subtest: allocator-basic-reserve
[103.419855] Subtest allocator-basic-reserve: SUCCESS (0.162s)
[103.534134] [042/165] (904s left) gem_softpin (safe-alignment)
[103.634949] Starting subtest: safe-alignment
[103.653320] Subtest safe-alignment: SUCCESS (0.018s)
[103.769886] [043/165] (904s left) gem_sync (basic-all)
[103.879937] Starting subtest: basic-all
[106.177452] Subtest basic-all: SUCCESS (2.275s)
[106.372702] [044/165] (901s left) gem_sync (basic-each)
[106.479686] Starting subtest: basic-each
[108.710908] Subtest basic-each: SUCCESS (2.231s)
[108.949430] [045/165] (899s left) gem_tiled_blits (basic)
[109.162645] Starting subtest: basic
[109.191416] Subtest basic: SUCCESS (0.029s)
[109.329323] [046/165] (898s left) gem_tiled_fence_blits (basic)
[109.626334] Starting subtest: basic
[109.654275] Subtest basic: SUCCESS (0.028s)
[109.808639] [047/165] (898s left) gem_tiled_pread_basic
[110.311928] [048/165] (898s left) gem_wait (busy@all-engines)
[110.429097] Starting subtest: busy
[110.430259] Starting dynamic subtest: all-engines
[110.958439] Dynamic subtest all-engines: SUCCESS (0.529s)
[110.959493] Subtest busy: SUCCESS (0.530s)
[111.212064] [049/165] (897s left) gem_wait (wait@all-engines)
[111.327708] Starting subtest: wait
[111.328854] Starting dynamic subtest: all-engines
[112.366450] Dynamic subtest all-engines: SUCCESS (1.038s)
[112.367566] Subtest wait: SUCCESS (1.038s)
[112.615421] [050/165] (895s left) i915_getparams_basic (basic-eu-total)
[112.705777] Starting subtest: basic-eu-total
[112.708255] Subtest basic-eu-total: SUCCESS (0.000s)
[112.795144] [051/165] (895s left) i915_getparams_basic (basic-subslice-total)
[112.886487] Starting subtest: basic-subslice-total
[112.888657] Subtest basic-subslice-total: SUCCESS (0.000s)
[112.979257] [052/165] (895s left) i915_hangman (error-state-basic)
[113.083617] Starting subtest: error-state-basic
[113.095746] Subtest error-state-basic: SUCCESS (0.011s)
[113.193056] [053/165] (895s left) i915_pciid
[113.377873] [054/165] (895s left) kms_addfb_basic (addfb25-4-tiled)
[113.507630] Starting subtest: addfb25-4-tiled
[113.508736] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[113.613059] [055/165] (894s left) kms_addfb_basic (addfb25-bad-modifier)
[113.705667] Starting subtest: addfb25-bad-modifier
[113.706734] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[113.830055] [056/165] (894s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[113.922904] Starting subtest: addfb25-framebuffer-vs-set-tiling
[113.924079] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[114.063615] [057/165] (894s left) kms_addfb_basic (addfb25-modifier-no-flag)
[114.154942] Starting subtest: addfb25-modifier-no-flag
[114.156040] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[114.280764] [058/165] (894s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[114.372087] Starting subtest: addfb25-x-tiled-legacy
[114.373100] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[114.513893] [059/165] (894s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[114.606125] Starting subtest: addfb25-x-tiled-mismatch-legacy
[114.607145] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[114.726609] [060/165] (893s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[114.844761] Starting subtest: addfb25-yf-tiled-legacy
[114.845901] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[114.947174] [061/165] (893s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[115.066173] Starting subtest: addfb25-y-tiled-legacy
[115.067303] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[115.165409] [062/165] (893s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[115.281433] Starting subtest: addfb25-y-tiled-small-legacy
[115.282628] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[115.381664] [063/165] (893s left) kms_addfb_basic (bad-pitch-0)
[115.473686] Starting subtest: bad-pitch-0
[115.474814] Subtest bad-pitch-0: SUCCESS (0.000s)
[115.598020] [064/165] (893s left) kms_addfb_basic (bad-pitch-1024)
[115.690141] Starting subtest: bad-pitch-1024
[115.691184] Subtest bad-pitch-1024: SUCCESS (0.000s)
[115.810471] [065/165] (893s left) kms_addfb_basic (bad-pitch-128)
[115.899713] Starting subtest: bad-pitch-128
[115.900832] Subtest bad-pitch-128: SUCCESS (0.000s)
[116.032192] [066/165] (892s left) kms_addfb_basic (bad-pitch-256)
[116.121276] Starting subtest: bad-pitch-256
[116.122403] Subtest bad-pitch-256: SUCCESS (0.000s)
[116.250145] [067/165] (892s left) kms_addfb_basic (bad-pitch-32)
[116.339778] Starting subtest: bad-pitch-32
[116.343028] Subtest bad-pitch-32: SUCCESS (0.000s)
[116.474220] [068/165] (892s left) kms_addfb_basic (bad-pitch-63)
[116.564689] Starting subtest: bad-pitch-63
[116.565709] Subtest bad-pitch-63: SUCCESS (0.000s)
[116.702979] [069/165] (892s left) kms_addfb_basic (bad-pitch-65536)
[116.793565] Starting subtest: bad-pitch-65536
[116.794678] Subtest bad-pitch-65536: SUCCESS (0.000s)
[116.916181] [070/165] (892s left) kms_addfb_basic (bad-pitch-999)
[117.007384] Starting subtest: bad-pitch-999
[117.008524] Subtest bad-pitch-999: SUCCESS (0.000s)
[117.128231] [071/165] (891s left) kms_addfb_basic (basic)
[117.218478] Starting subtest: basic
[117.219488] Subtest basic: SUCCESS (0.000s)
[117.350742] [072/165] (891s left) kms_addfb_basic (basic-x-tiled-legacy)
[117.442428] Starting subtest: basic-x-tiled-legacy
[117.443516] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[117.585071] [073/165] (891s left) kms_addfb_basic (basic-y-tiled-legacy)
[117.677058] Starting subtest: basic-y-tiled-legacy
[117.678145] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[117.806085] [074/165] (891s left) kms_addfb_basic (bo-too-small)
[117.925085] Starting subtest: bo-too-small
[117.926215] Subtest bo-too-small: SUCCESS (0.000s)
[118.034296] [075/165] (891s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[118.152279] Starting subtest: bo-too-small-due-to-tiling
[118.153437] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[118.269994] [076/165] (890s left) kms_addfb_basic (clobberred-modifier)
[118.359496] Starting subtest: clobberred-modifier
[118.360614] Subtest clobberred-modifier: SUCCESS (0.000s)
[118.501643] [077/165] (890s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[118.594118] Starting subtest: framebuffer-vs-set-tiling
[118.595203] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[118.720884] [078/165] (890s left) kms_addfb_basic (invalid-get-prop)
[118.810375] Starting subtest: invalid-get-prop
[118.811406] Subtest invalid-get-prop: SUCCESS (0.000s)
[118.932307] [079/165] (890s left) kms_addfb_basic (invalid-get-prop-any)
[119.021947] Starting subtest: invalid-get-prop-any
[119.023127] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[119.170890] [080/165] (890s left) kms_addfb_basic (invalid-set-prop)
[119.265378] Starting subtest: invalid-set-prop
[119.266427] Subtest invalid-set-prop: SUCCESS (0.000s)
[119.385640] [081/165] (889s left) kms_addfb_basic (invalid-set-prop-any)
[119.476756] Starting subtest: invalid-set-prop-any
[119.477973] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[119.601741] [082/165] (889s left) kms_addfb_basic (no-handle)
[119.692441] Starting subtest: no-handle
[119.693592] Subtest no-handle: SUCCESS (0.000s)
[119.816086] [083/165] (889s left) kms_addfb_basic (size-max)
[119.937112] Starting subtest: size-max
[119.938274] Subtest size-max: SUCCESS (0.000s)
[120.054073] [084/165] (889s left) kms_addfb_basic (small-bo)
[120.172334] Starting subtest: small-bo
[120.173536] Subtest small-bo: SUCCESS (0.000s)
[120.270431] [085/165] (889s left) kms_addfb_basic (tile-pitch-mismatch)
[120.360124] Starting subtest: tile-pitch-mismatch
[120.361192] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[120.487137] [086/165] (888s left) kms_addfb_basic (too-high)
[120.605539] Starting subtest: too-high
[120.606755] Subtest too-high: SUCCESS (0.001s)
[120.720251] [087/165] (888s left) kms_addfb_basic (too-wide)
[120.841603] Starting subtest: too-wide
[120.842858] Subtest too-wide: SUCCESS (0.000s)
[120.952850] [088/165] (888s left) kms_addfb_basic (unused-handle)
[121.044532] Starting subtest: unused-handle
[121.045636] Subtest unused-handle: SUCCESS (0.000s)
[121.170833] [089/165] (888s left) kms_addfb_basic (unused-modifier)
[121.261345] Starting subtest: unused-modifier
[121.262420] Subtest unused-modifier: SUCCESS (0.000s)
[121.388042] [090/165] (888s left) kms_addfb_basic (unused-offsets)
[121.479343] Starting subtest: unused-offsets
[121.480427] Subtest unused-offsets: SUCCESS (0.000s)
[121.610714] [091/165] (887s left) kms_addfb_basic (unused-pitches)
[121.701822] Starting subtest: unused-pitches
[121.702889] Subtest unused-pitches: SUCCESS (0.000s)
[121.838798] [092/165] (887s left) kms_busy (basic)
[121.963848] Starting subtest: basic
[121.964931] Starting dynamic subtest: flip
[122.969900] Dynamic subtest flip: SUCCESS (1.005s)
[122.986959] Starting dynamic subtest: modeset
[126.829422] Dynamic subtest modeset: SUCCESS (3.858s)
[126.830731] Subtest basic: SUCCESS (4.865s)
[127.803619] [093/165] (881s left) kms_prop_blob (basic)
[127.898316] Starting subtest: basic
[127.899408] Subtest basic: SUCCESS (0.000s)
[127.986963] [094/165] (881s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[128.106326] Starting subtest: basic-busy-flip-before-cursor-atomic
[128.108577] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[128.183306] [095/165] (881s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[128.301920] Starting subtest: basic-busy-flip-before-cursor-legacy
[128.304079] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[128.386729] [096/165] (881s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[128.504120] Starting subtest: basic-flip-after-cursor-atomic
[129.814989] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.311s)
[130.746321] [097/165] (879s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[130.865295] Starting subtest: basic-flip-after-cursor-legacy
[132.175421] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.309s)
[133.112786] [098/165] (876s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[133.231923] Starting subtest: basic-flip-after-cursor-varying-size
[134.509631] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.277s)
[135.481920] [099/165] (874s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[135.602552] Starting subtest: basic-flip-before-cursor-atomic
[136.909977] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.309s)
[137.854505] [100/165] (872s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[137.971971] Starting subtest: basic-flip-before-cursor-legacy
[139.277398] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.306s)
[140.216309] [101/165] (869s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[140.335571] Starting subtest: basic-flip-before-cursor-varying-size
[141.641669] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.306s)
[142.584625] [102/165] (867s left) kms_dsc (dsc-basic)
[142.704191] Starting subtest: dsc-basic
[142.706184] Subtest dsc-basic: SKIP (0.001s)
[142.778737] [103/165] (867s left) kms_flip (basic-flip-vs-dpms)
[142.877125] Starting subtest: basic-flip-vs-dpms
[142.880093] Starting dynamic subtest: A-eDP1
[145.710383] Dynamic subtest A-eDP1: SUCCESS (2.828s)
[145.726743] Starting dynamic subtest: B-eDP1
[148.140852] Dynamic subtest B-eDP1: SUCCESS (2.430s)
[148.172254] Starting dynamic subtest: C-eDP1
[150.576650] Dynamic subtest C-eDP1: SUCCESS (2.434s)
[150.579134] Subtest basic-flip-vs-dpms: SUCCESS (7.702s)
[151.489482] [104/165] (858s left) kms_flip (basic-flip-vs-modeset)
[151.587320] Starting subtest: basic-flip-vs-modeset
[151.590332] Starting dynamic subtest: A-eDP1
[154.481537] Dynamic subtest A-eDP1: SUCCESS (2.889s)
[154.509726] Starting dynamic subtest: B-eDP1
[156.910037] Dynamic subtest B-eDP1: SUCCESS (2.425s)
[156.927030] Starting dynamic subtest: C-eDP1
[159.282025] Dynamic subtest C-eDP1: SUCCESS (2.371s)
[159.283199] Subtest basic-flip-vs-modeset: SUCCESS (7.695s)
[160.249311] [105/165] (849s left) kms_flip (basic-flip-vs-wf_vblank)
[160.347451] Starting subtest: basic-flip-vs-wf_vblank
[160.350369] Starting dynamic subtest: A-eDP1
[162.713208] Dynamic subtest A-eDP1: SUCCESS (2.359s)
[162.730111] Starting dynamic subtest: B-eDP1
[164.997053] Dynamic subtest B-eDP1: SUCCESS (2.284s)
[165.015542] Starting dynamic subtest: C-eDP1
[167.236901] Dynamic subtest C-eDP1: SUCCESS (2.238s)
[167.241596] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.895s)
[168.570236] [106/165] (841s left) kms_flip (basic-plain-flip)
[168.670129] Starting subtest: basic-plain-flip
[168.673498] Starting dynamic subtest: A-eDP1
[170.747531] Dynamic subtest A-eDP1: SUCCESS (2.073s)
[170.770139] Starting dynamic subtest: B-eDP1
[172.715333] Dynamic subtest B-eDP1: SUCCESS (1.965s)
[172.734472] Starting dynamic subtest: C-eDP1
[174.698673] Dynamic subtest C-eDP1: SUCCESS (1.981s)
[174.703209] Subtest basic-plain-flip: SUCCESS (6.034s)
[175.993953] [107/165] (834s left) kms_force_connector_basic (force-connector-state)
[176.096161] Starting subtest: force-connector-state
[176.703430] Subtest force-connector-state: SUCCESS (0.599s)
[177.738994] [108/165] (832s left) kms_force_connector_basic (force-edid)
[177.832930] Starting subtest: force-edid
[178.091508] Subtest force-edid: SUCCESS (0.258s)
[178.235249] [109/165] (831s left) kms_force_connector_basic (force-load-detect)
[178.328489] Starting subtest: force-load-detect
[178.330705] Subtest force-load-detect: SKIP (0.000s)
[178.447274] [110/165] (831s left) kms_force_connector_basic (prune-stale-modes)
[178.541394] Starting subtest: prune-stale-modes
[178.857070] Subtest prune-stale-modes: SUCCESS (0.316s)
[179.004074] [111/165] (831s left) kms_frontbuffer_tracking (basic)
[179.131478] Starting subtest: basic
[182.465394] Subtest basic: SUCCESS (3.334s)
[182.589669] [112/165] (827s left) kms_hdmi_inject (inject-audio)
[183.003309] Starting subtest: inject-audio
[183.329946] Subtest inject-audio: SUCCESS (0.326s)
[183.940509] [113/165] (826s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[184.061232] Starting subtest: compare-crc-sanitycheck-xr24
[184.063796] Starting dynamic subtest: pipe-A-eDP-1
[184.755460] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[184.773367] Starting dynamic subtest: pipe-B-eDP-1
[186.199519] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.444s)
[186.220331] Starting dynamic subtest: pipe-C-eDP-1
[187.577780] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.375s)
[187.580252] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.517s)
[188.539475] [114/165] (821s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[188.659144] Starting subtest: compare-crc-sanitycheck-nv12
[188.661937] Starting dynamic subtest: pipe-A-eDP-1
[189.598358] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.935s)
[189.617885] Starting dynamic subtest: pipe-B-eDP-1
[190.988637] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.388s)
[191.010655] Starting dynamic subtest: pipe-C-eDP-1
[192.393553] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.399s)
[192.395282] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.732s)
[193.306198] [115/165] (816s left) kms_pipe_crc_basic (hang-read-crc)
[193.424837] Starting subtest: hang-read-crc
[193.427543] Starting dynamic subtest: pipe-A-eDP-1
[195.825635] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.396s)
[195.851234] Starting dynamic subtest: pipe-B-eDP-1
[198.911149] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.080s)
[198.933954] Starting dynamic subtest: pipe-C-eDP-1
[201.926579] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.011s)
[201.927819] Subtest hang-read-crc: SUCCESS (8.502s)
[202.871665] [116/165] (807s left) kms_pipe_crc_basic (nonblocking-crc)
[202.991890] Starting subtest: nonblocking-crc
[202.994672] Starting dynamic subtest: pipe-A-eDP-1
[203.883196] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.887s)
[203.912167] Starting dynamic subtest: pipe-B-eDP-1
[205.523437] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.632s)
[205.542977] Starting dynamic subtest: pipe-C-eDP-1
[207.070933] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.544s)
[207.072235] Subtest nonblocking-crc: SUCCESS (4.079s)
[207.998627] [117/165] (802s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[208.119392] Starting subtest: nonblocking-crc-frame-sequence
[208.120454] Starting dynamic subtest: pipe-A-eDP-1
[209.024262] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.901s)
[209.043690] Starting dynamic subtest: pipe-B-eDP-1
[210.622088] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.593s)
[210.654355] Starting dynamic subtest: pipe-C-eDP-1
[212.179765] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.550s)
[212.181114] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.061s)
[213.114830] [118/165] (797s left) kms_pipe_crc_basic (read-crc)
[213.236314] Starting subtest: read-crc
[213.239243] Starting dynamic subtest: pipe-A-eDP-1
[214.072934] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.835s)
[214.091980] Starting dynamic subtest: pipe-B-eDP-1
[215.627336] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.550s)
[215.646937] Starting dynamic subtest: pipe-C-eDP-1
[217.125108] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.493s)
[217.126476] Subtest read-crc: SUCCESS (3.890s)
[218.040167] [119/165] (792s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[218.160177] Starting subtest: read-crc-frame-sequence
[218.163028] Starting dynamic subtest: pipe-A-eDP-1
[218.983104] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.820s)
[219.002263] Starting dynamic subtest: pipe-B-eDP-1
[220.555583] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.568s)
[220.576346] Starting dynamic subtest: pipe-C-eDP-1
[222.053196] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.492s)
[222.054409] Subtest read-crc-frame-sequence: SUCCESS (3.893s)
[222.968444] [120/165] (787s left) kms_pm_backlight (basic-brightness)
[223.089851] Starting subtest: basic-brightness
[223.196774] Starting dynamic subtest: eDP-1
[223.780895] Dynamic subtest eDP-1: SUCCESS (0.585s)
[223.782048] Subtest basic-brightness: SUCCESS (0.692s)
[224.700309] [121/165] (785s left) kms_pm_rpm (basic-pci-d3-state)
[226.532325] Starting subtest: basic-pci-d3-state
[230.931372] Subtest basic-pci-d3-state: SUCCESS (4.397s)
[231.137578] [122/165] (779s left) kms_pm_rpm (basic-rte)
[231.225872] Starting subtest: basic-rte
[241.169688] Subtest basic-rte: SUCCESS (9.942s)
[246.492469] [123/165] (764s left) kms_psr (primary_page_flip)
[246.616604] Starting subtest: primary_page_flip
[250.474914] Subtest primary_page_flip: SUCCESS (3.858s)
[251.450994] [124/165] (759s left) kms_psr (cursor_plane_move)
[251.574015] Starting subtest: cursor_plane_move
[255.355918] Subtest cursor_plane_move: SUCCESS (3.782s)
[256.312450] [125/165] (754s left) kms_psr (sprite_plane_onoff)
[256.434865] Starting subtest: sprite_plane_onoff
[260.339442] Subtest sprite_plane_onoff: SUCCESS (3.903s)
[261.306247] [126/165] (749s left) kms_psr (primary_mmap_gtt)
[261.435199] Starting subtest: primary_mmap_gtt
[265.261377] Subtest primary_mmap_gtt: SUCCESS (3.825s)
[266.235587] [127/165] (744s left) kms_setmode (basic-clone-single-crtc)
[266.330717] Starting subtest: basic-clone-single-crtc
[266.333977] Subtest basic-clone-single-crtc: SKIP (0.000s)
[266.417311] [128/165] (744s left) i915_pm_rps (basic-api)
[266.516175] Starting subtest: basic-api
[266.520589] Subtest basic-api: SUCCESS (0.004s)
[266.618591] [129/165] (744s left) prime_self_import (basic-llseek-bad)
[266.707596] Starting subtest: basic-llseek-bad
[266.712635] Subtest basic-llseek-bad: SUCCESS (0.003s)
[266.823347] [130/165] (743s left) prime_self_import (basic-llseek-size)
[266.911099] Starting subtest: basic-llseek-size
[266.915537] Subtest basic-llseek-size: SUCCESS (0.004s)
[267.019786] [131/165] (743s left) prime_self_import (basic-with_fd_dup)
[267.105114] Starting subtest: basic-with_fd_dup
[267.109665] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[267.219111] [132/165] (743s left) prime_self_import (basic-with_one_bo)
[267.307115] Starting subtest: basic-with_one_bo
[267.311914] Subtest basic-with_one_bo: SUCCESS (0.005s)
[267.397239] [133/165] (743s left) prime_self_import (basic-with_one_bo_two_files)
[267.485743] Starting subtest: basic-with_one_bo_two_files
[267.490349] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[267.603630] [134/165] (743s left) prime_self_import (basic-with_two_bos)
[267.692979] Starting subtest: basic-with_two_bos
[267.697594] Subtest basic-with_two_bos: SUCCESS (0.005s)
[267.803027] [135/165] (743s left) prime_vgem (basic-fence-flip)
[267.925620] Starting subtest: basic-fence-flip
[268.476482] Subtest basic-fence-flip: SUCCESS (0.550s)
[268.605898] [136/165] (742s left) prime_vgem (basic-fence-mmap)
[268.724672] Starting subtest: basic-fence-mmap
[268.775360] Subtest basic-fence-mmap: SUCCESS (0.050s)
[268.888488] [137/165] (742s left) prime_vgem (basic-fence-read)
[269.008492] Starting subtest: basic-fence-read
[269.085473] Subtest basic-fence-read: SUCCESS (0.077s)
[269.172510] [138/165] (741s left) prime_vgem (basic-gtt)
[269.286042] Starting subtest: basic-gtt
[269.311659] Subtest basic-gtt: SUCCESS (0.025s)
[269.420852] [139/165] (741s left) prime_vgem (basic-read)
[269.536256] Starting subtest: basic-read
[269.574688] Subtest basic-read: SUCCESS (0.038s)
[269.686080] [140/165] (741s left) prime_vgem (basic-write)
[269.803334] Starting subtest: basic-write
[269.847952] Subtest basic-write: SUCCESS (0.044s)
[269.934797] [141/165] (741s left) vgem_basic (setversion)
[270.029100] Starting subtest: setversion
[270.031438] Subtest setversion: SUCCESS (0.000s)
[270.099675] [142/165] (741s left) vgem_basic (create)
[270.196150] Starting subtest: create
[270.197343] Subtest create: SUCCESS (0.000s)
[270.270212] [143/165] (740s left) vgem_basic (debugfs)
[270.362302] Starting subtest: debugfs
[270.363474] Subtest debugfs: SUCCESS (0.001s)
[270.433778] [144/165] (740s left) vgem_basic (dmabuf-export)
[270.529268] Starting subtest: dmabuf-export
[270.548901] Subtest dmabuf-export: SUCCESS (0.018s)
[270.657240] [145/165] (740s left) vgem_basic (dmabuf-fence)
[270.763523] Starting subtest: dmabuf-fence
[270.764735] Subtest dmabuf-fence: SUCCESS (0.001s)
[270.839358] [146/165] (740s left) vgem_basic (dmabuf-fence-before)
[270.947392] Starting subtest: dmabuf-fence-before
[270.948737] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[271.022067] [147/165] (740s left) vgem_basic (dmabuf-mmap)
[271.130232] Starting subtest: dmabuf-mmap
[271.155681] Subtest dmabuf-mmap: SUCCESS (0.026s)
[271.238077] [148/165] (740s left) vgem_basic (mmap)
[271.347235] Starting subtest: mmap
[271.365156] Subtest mmap: SUCCESS (0.017s)
[271.436819] [149/165] (739s left) vgem_basic (second-client)
[271.547427] Starting subtest: second-client
[271.582112] Subtest second-client: SUCCESS (0.033s)
[271.656922] [150/165] (739s left) vgem_basic (sysfs)
[271.764160] Starting subtest: sysfs
[271.765440] Subtest sysfs: SUCCESS (0.001s)
[271.839048] [151/165] (739s left) core_hotunplug (unbind-rebind)
[271.980727] Starting subtest: unbind-rebind
[276.475728] Subtest unbind-rebind: SUCCESS (4.495s)
[276.511082] [152/165] (734s left) vgem_basic (unload)
[276.605918] Starting subtest: unload
[305.644546] Subtest unload: SUCCESS (29.004s)
[305.731710] [153/165] (705s left) i915_module_load (reload)
[305.821781] Starting subtest: reload
[309.194739] Subtest reload: SUCCESS (3.373s)
[309.225372] [154/165] (702s left) gem_lmem_swapping (basic)
[309.345616] Subtest basic: SKIP (0.000s)
[309.397596] [155/165] (702s left) gem_lmem_swapping (parallel-random-engines)
[309.494258] Subtest parallel-random-engines: SKIP (0.000s)
[309.547111] [156/165] (702s left) gem_lmem_swapping (random-engines)
[309.643443] Subtest random-engines: SKIP (0.000s)
[309.696819] [157/165] (701s left) gem_lmem_swapping (verify-random)
[309.792894] Subtest verify-random: SKIP (0.000s)
[309.847627] [158/165] (701s left) i915_pm_rpm (module-reload)
[322.290906] Starting subtest: module-reload
[351.631104] Subtest module-reload: SUCCESS (29.331s)
[351.656354] [159/165] (660s left) i915_selftest (live)
[351.759885] Starting subtest: live
[351.761277] Starting dynamic subtest: sanitycheck
[353.637411] Dynamic subtest sanitycheck: SUCCESS (1.870s)
[353.652491] Starting dynamic subtest: uncore
[355.471022] Dynamic subtest uncore: SUCCESS (1.830s)
[355.486252] Starting dynamic subtest: workarounds
[357.594414] Dynamic subtest workarounds: SUCCESS (2.120s)
[357.609420] Starting dynamic subtest: gt_engines
[359.867437] Dynamic subtest gt_engines: SUCCESS (2.269s)
[359.882856] Starting dynamic subtest: gt_timelines
[372.919422] Dynamic subtest gt_timelines: SUCCESS (13.049s)
[372.934806] Starting dynamic subtest: gt_contexts
[374.890922] Dynamic subtest gt_contexts: SUCCESS (1.968s)
[374.906227] Starting dynamic subtest: gt_lrc
[379.288546] Dynamic subtest gt_lrc: SUCCESS (4.394s)
[379.303565] Starting dynamic subtest: gt_mocs
[381.233506] Dynamic subtest gt_mocs: SUCCESS (1.941s)
[381.248657] Starting dynamic subtest: gt_pm
[386.350410] Dynamic subtest gt_pm: SUCCESS (5.113s)
[386.366047] Starting dynamic subtest: gt_heartbeat
[388.321044] Dynamic subtest gt_heartbeat: SUCCESS (1.969s)
[388.336040] Starting dynamic subtest: gt_tlb
[391.446521] Dynamic subtest gt_tlb: SUCCESS (3.120s)
[391.461628] Starting dynamic subtest: requests
[399.028524] Dynamic subtest requests: SUCCESS (7.578s)
[399.043754] Starting dynamic subtest: migrate
[403.630614] Dynamic subtest migrate: SUCCESS (4.598s)
[403.645946] Starting dynamic subtest: active
[405.482374] Dynamic subtest active: SUCCESS (1.848s)
[405.497697] Starting dynamic subtest: objects
[408.918499] Dynamic subtest objects: SUCCESS (3.432s)
[408.933705] Starting dynamic subtest: mman
[415.856352] Dynamic subtest mman: SUCCESS (6.934s)
[415.871736] Starting dynamic subtest: dmabuf
[417.758739] Dynamic subtest dmabuf: SUCCESS (1.899s)
[417.773979] Starting dynamic subtest: vma
[419.619836] Dynamic subtest vma: SUCCESS (1.857s)
[419.635282] Starting dynamic subtest: coherency
[424.149630] Dynamic subtest coherency: SUCCESS (4.526s)
[424.164951] Starting dynamic subtest: gtt
[433.575444] Dynamic subtest gtt: SUCCESS (9.422s)
[433.590783] Starting dynamic subtest: gem
[435.630675] Dynamic subtest gem: SUCCESS (2.051s)
[435.645798] Starting dynamic subtest: evict
[439.496564] Dynamic subtest evict: SUCCESS (3.862s)
[439.512420] Starting dynamic subtest: hugepages
[447.226506] Dynamic subtest hugepages: SUCCESS (7.727s)
[447.242142] Starting dynamic subtest: gem_contexts
[462.490190] Dynamic subtest gem_contexts: SUCCESS (15.260s)
[462.506266] Starting dynamic subtest: client
[464.408135] Dynamic subtest client: SUCCESS (1.915s)
[464.424318] Starting dynamic subtest: gem_migrate
[466.256417] Dynamic subtest gem_migrate: SUCCESS (1.845s)
[466.272585] Starting dynamic subtest: reset
[471.685426] Dynamic subtest reset: SUCCESS (5.426s)
[471.701212] Starting dynamic subtest: memory_region
[473.568542] Dynamic subtest memory_region: SUCCESS (1.879s)
[473.584277] Starting dynamic subtest: hangcheck
[498.029418] Dynamic subtest hangcheck: SUCCESS (24.457s)
[498.045457] Starting dynamic subtest: execlists
[508.066040] Dynamic subtest execlists: SUCCESS (10.033s)
[508.081676] Starting dynamic subtest: ring_submission
[509.919491] Dynamic subtest ring_submission: SUCCESS (1.850s)
[509.935443] Starting dynamic subtest: perf
[511.910585] Dynamic subtest perf: SUCCESS (1.988s)
[511.926379] Starting dynamic subtest: slpc
[513.748590] Dynamic subtest slpc: SUCCESS (1.834s)
[513.764279] Starting dynamic subtest: guc
[515.608363] Dynamic subtest guc: SUCCESS (1.856s)
[515.624486] Starting dynamic subtest: guc_multi_lrc
[517.452528] Dynamic subtest guc_multi_lrc: SUCCESS (1.841s)
[517.468547] Starting dynamic subtest: guc_hang
[519.322063] Dynamic subtest guc_hang: SUCCESS (1.866s)
[519.338348] Starting dynamic subtest: late_gt_pm
[521.195375] Dynamic subtest late_gt_pm: SUCCESS (1.870s)
[521.196585] Subtest live: SUCCESS (169.431s)
[521.222185] [160/165] (490s left) dmabuf (all-tests)
[521.319668] Starting subtest: all-tests
[521.320989] Starting dynamic subtest: sanitycheck
[521.394744] Dynamic subtest sanitycheck: SUCCESS (0.073s)
[521.414962] Starting dynamic subtest: dma_fence
[521.581638] Dynamic subtest dma_fence: SUCCESS (0.183s)
[521.602257] Starting dynamic subtest: dma_fence_chain
[539.261969] Dynamic subtest dma_fence_chain: SUCCESS (17.668s)
[539.275240] Starting dynamic subtest: dma_fence_unwrap
[539.325759] Dynamic subtest dma_fence_unwrap: SUCCESS (0.068s)
[539.346617] Starting dynamic subtest: dma_resv
[539.405763] Dynamic subtest dma_resv: SUCCESS (0.076s)
[539.407980] Subtest all-tests: SUCCESS (18.087s)
[539.440169] [161/165] (472s left) i915_suspend (basic-s2idle-without-i915)
[539.529355] Starting subtest: basic-s2idle-without-i915
[562.686110] Subtest basic-s2idle-without-i915: SUCCESS (8.175s)
[563.018958] [162/165] (463s left) i915_suspend (basic-s3-without-i915)
[563.111720] Starting subtest: basic-s3-without-i915
[586.748745] Subtest basic-s3-without-i915: SUCCESS (6.876s)
[587.088252] [163/165] (456s left) gem_exec_suspend (basic-s0)
[587.209178] Starting subtest: basic-S0
[587.210372] Starting dynamic subtest: smem
[606.842712] Dynamic subtest smem: SUCCESS (3.727s)
[606.843855] Subtest basic-S0: SUCCESS (3.729s)
[607.059391] [164/165] (452s left) gem_exec_suspend (basic-s3)
[607.166311] Starting subtest: basic-S3
[607.167468] Starting dynamic subtest: smem
[625.864280] Dynamic subtest smem: SUCCESS (3.727s)
[625.865554] Subtest basic-S3: SUCCESS (3.729s)
[626.067587] [165/165] (448s left) kms_pipe_crc_basic (suspend-read-crc)
[626.189784] Starting subtest: suspend-read-crc
[626.192941] Starting dynamic subtest: pipe-A-eDP-1
[643.838972] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.526s)
[643.871127] Starting dynamic subtest: pipe-B-eDP-1
[662.802508] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.174s)
[662.838921] Starting dynamic subtest: pipe-C-eDP-1
[680.800366] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.136s)
[680.802038] Subtest suspend-read-crc: SUCCESS (8.857s)
[681.704407] Closing watchdogs
Done.