[42.506673] Initializing watchdogs
[42.506816]   /dev/watchdog0
[42.516829] [001/165] (960s left) i915_module_load (load)
[42.617557] Starting subtest: load
[45.202189] Subtest load: SUCCESS (2.583s)
[45.226160] [002/165] (957s left) core_auth (basic-auth)
[45.330272] Starting subtest: basic-auth
[45.331852] Subtest basic-auth: SUCCESS (0.002s)
[45.422802] [003/165] (957s left) debugfs_test (read_all_entries)
[45.525186] Starting subtest: read_all_entries
[45.609162] Subtest read_all_entries: SUCCESS (0.081s)
[45.744597] [004/165] (957s left) debugfs_test (basic-hwmon)
[45.840824] Starting subtest: basic-hwmon
[45.843058] Subtest basic-hwmon: SKIP (0.000s)
[45.950208] [005/165] (957s left) fbdev (eof)
[46.054106] Starting subtest: eof
[46.057130] Subtest eof: SUCCESS (0.002s)
[46.131549] [006/165] (957s left) fbdev (info)
[46.232670] Starting subtest: info
[46.233854] Subtest info: SUCCESS (0.000s)
[46.308083] [007/165] (956s left) fbdev (nullptr)
[46.435728] Starting subtest: nullptr
[46.436868] Subtest nullptr: SUCCESS (0.000s)
[46.501590] [008/165] (956s left) fbdev (read)
[46.603658] Starting subtest: read
[53.695231] Subtest read: SUCCESS (7.081s)
[53.760466] [009/165] (949s left) fbdev (write)
[53.860401] Starting subtest: write
[57.643214] Subtest write: SUCCESS (3.748s)
[57.682612] [010/165] (945s left) gem_basic (bad-close)
[57.777434] Starting subtest: bad-close
[57.779586] Subtest bad-close: SUCCESS (0.000s)
[57.850899] [011/165] (945s left) gem_basic (create-close)
[57.939911] Starting subtest: create-close
[57.942218] Subtest create-close: SUCCESS (0.000s)
[58.050762] [012/165] (945s left) gem_basic (create-fd-close)
[58.139302] Starting subtest: create-fd-close
[58.141359] Subtest create-fd-close: SUCCESS (0.000s)
[58.235375] [013/165] (945s left) gem_busy (busy@all-engines)
[58.344674] Starting subtest: busy
[58.345863] Starting dynamic subtest: all-engines
[58.424425] Dynamic subtest all-engines: SUCCESS (0.080s)
[58.425581] Subtest busy: SUCCESS (0.080s)
[58.828083] [014/165] (944s left) gem_close_race (basic-process)
[58.942573] Starting subtest: basic-process
[59.019096] Subtest basic-process: SUCCESS (0.076s)
[59.223895] [015/165] (944s left) gem_close_race (basic-threads)
[59.335635] Starting subtest: basic-threads
[60.531067] Subtest basic-threads: SUCCESS (1.194s)
[60.770992] [016/165] (942s left) gem_ctx_create (basic)
[60.896991] Starting subtest: basic
[60.898171] Subtest basic: SUCCESS (0.000s)
[61.104463] [017/165] (942s left) gem_ctx_create (basic-files)
[61.212734] Starting subtest: basic-files
[63.236282] Subtest basic-files: SUCCESS (2.025s)
[63.464867] [018/165] (940s left) gem_ctx_exec (basic)
[63.558538] Starting subtest: basic
[63.563541] Subtest basic: SUCCESS (0.004s)
[63.675742] [019/165] (939s left) gem_exec_basic (basic)
[63.776863] Starting subtest: basic
[63.778188] Starting dynamic subtest: rcs0-smem
[63.779355] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[63.790745] Starting dynamic subtest: bcs0-smem
[63.792102] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[63.802558] Starting dynamic subtest: vcs0-smem
[63.803777] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[63.814172] Starting dynamic subtest: vecs0-smem
[63.815617] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[63.816990] Subtest basic: SUCCESS (0.006s)
[63.990738] [020/165] (939s left) gem_exec_create (basic)
[64.095658] Starting subtest: basic
[64.096799] Starting dynamic subtest: smem
[66.145357] Dynamic subtest smem: SUCCESS (2.049s)
[66.146455] Subtest basic: SUCCESS (2.049s)
[66.399044] [021/165] (937s left) gem_exec_fence (basic-busy)
[66.668531] Starting subtest: basic-busy
[66.669672] Starting dynamic subtest: rcs0
[66.699072] Dynamic subtest rcs0: SUCCESS (0.035s)
[66.717551] Starting dynamic subtest: bcs0
[66.721489] Dynamic subtest bcs0: SUCCESS (0.026s)
[66.733345] Starting dynamic subtest: vcs0
[66.742235] Dynamic subtest vcs0: SUCCESS (0.021s)
[66.753937] Starting dynamic subtest: vecs0
[66.764245] Dynamic subtest vecs0: SUCCESS (0.022s)
[66.765405] Subtest basic-busy: SUCCESS (0.105s)
[67.365489] [022/165] (936s left) gem_exec_fence (basic-wait)
[67.619452] Starting subtest: basic-wait
[67.620949] Starting dynamic subtest: rcs0
[67.634600] Dynamic subtest rcs0: SUCCESS (0.025s)
[67.647373] Starting dynamic subtest: bcs0
[67.668068] Dynamic subtest bcs0: SUCCESS (0.033s)
[67.680154] Starting dynamic subtest: vcs0
[67.707610] Dynamic subtest vcs0: SUCCESS (0.039s)
[67.722916] Starting dynamic subtest: vecs0
[67.732851] Dynamic subtest vecs0: SUCCESS (0.024s)
[67.734194] Subtest basic-wait: SUCCESS (0.123s)
[68.277981] [023/165] (935s left) gem_exec_fence (basic-await)
[68.538902] Starting subtest: basic-await
[68.540162] Starting dynamic subtest: rcs0
[68.632127] Dynamic subtest rcs0: SUCCESS (0.100s)
[68.662972] Starting dynamic subtest: bcs0
[68.764581] Dynamic subtest bcs0: SUCCESS (0.131s)
[68.792960] Starting dynamic subtest: vcs0
[68.896550] Dynamic subtest vcs0: SUCCESS (0.131s)
[68.923178] Starting dynamic subtest: vecs0
[69.029487] Dynamic subtest vecs0: SUCCESS (0.132s)
[69.031811] Subtest basic-await: SUCCESS (0.498s)
[69.599407] [024/165] (934s left) gem_exec_fence (nb-await)
[69.847740] Starting subtest: nb-await
[69.849097] Starting dynamic subtest: rcs0
[69.905578] Dynamic subtest rcs0: SUCCESS (0.065s)
[69.926739] Starting dynamic subtest: bcs0
[69.978804] Dynamic subtest bcs0: SUCCESS (0.073s)
[70.006121] Starting dynamic subtest: vcs0
[70.054967] Dynamic subtest vcs0: SUCCESS (0.076s)
[70.084574] Starting dynamic subtest: vecs0
[70.131284] Dynamic subtest vecs0: SUCCESS (0.076s)
[70.133285] Subtest nb-await: SUCCESS (0.292s)
[70.667788] [025/165] (933s left) gem_exec_gttfill (basic)
[70.776528] Starting subtest: basic
[76.795246] Subtest basic: SUCCESS (6.011s)
[76.987969] [026/165] (926s left) gem_exec_parallel (engines)
[77.173625] Starting subtest: engines
[77.174874] Starting dynamic subtest: basic
[79.574097] Dynamic subtest basic: SUCCESS (2.384s)
[79.587314] Starting dynamic subtest: contexts
[86.469361] Dynamic subtest contexts: SUCCESS (6.908s)
[86.490603] Starting dynamic subtest: fds
[96.976645] Dynamic subtest fds: SUCCESS (10.515s)
[97.008869] Starting dynamic subtest: userptr
[99.420317] Dynamic subtest userptr: SUCCESS (2.421s)
[99.422347] Subtest engines: SUCCESS (22.230s)
[99.634923] [027/165] (904s left) gem_exec_store (basic)
[99.741431] Starting subtest: basic
[99.796560] Subtest basic: SUCCESS (0.055s)
[99.999528] [028/165] (903s left) gem_flink_basic (bad-flink)
[100.090582] Starting subtest: bad-flink
[100.091818] Subtest bad-flink: SUCCESS (0.000s)
[100.177849] [029/165] (903s left) gem_flink_basic (bad-open)
[100.266765] Starting subtest: bad-open
[100.268170] Subtest bad-open: SUCCESS (0.000s)
[100.348801] [030/165] (903s left) gem_flink_basic (basic)
[100.442075] Starting subtest: basic
[100.443559] Subtest basic: SUCCESS (0.000s)
[100.548872] [031/165] (903s left) gem_flink_basic (double-flink)
[100.636428] Starting subtest: double-flink
[100.637614] Subtest double-flink: SUCCESS (0.000s)
[100.730597] [032/165] (903s left) gem_flink_basic (flink-lifetime)
[100.819461] Starting subtest: flink-lifetime
[100.822186] Subtest flink-lifetime: SUCCESS (0.002s)
[100.933423] [033/165] (902s left) gem_huc_copy (huc-copy)
[101.029954] Starting subtest: huc-copy
[101.032472] Subtest huc-copy: SKIP (0.000s)
[101.149852] [034/165] (902s left) gem_linear_blits (basic)
[101.385362] Starting subtest: basic
[101.399913] Subtest basic: SUCCESS (0.015s)
[101.537958] [035/165] (902s left) gem_mmap (basic)
[101.698532] Starting subtest: basic
[101.704760] Subtest basic: SUCCESS (0.001s)
[101.817465] [036/165] (902s left) gem_mmap_gtt (basic)
[101.910573] Starting subtest: basic
[101.911734] Subtest basic: SUCCESS (0.002s)
[101.993957] [037/165] (902s left) gem_render_linear_blits (basic)
[102.093795] Starting subtest: basic
[102.145235] Subtest basic: SUCCESS (0.051s)
[102.209967] [038/165] (901s left) gem_render_tiled_blits (basic)
[102.308981] Starting subtest: basic
[102.368196] Subtest basic: SUCCESS (0.059s)
[102.428244] [039/165] (901s left) gem_ringfill (basic-all)
[102.671174] Starting subtest: basic-all
[103.922186] Subtest basic-all: SUCCESS (1.242s)
[104.016102] [040/165] (900s left) gem_softpin (allocator-basic)
[104.114027] Starting subtest: allocator-basic
[104.288396] Subtest allocator-basic: SUCCESS (0.166s)
[104.408498] [041/165] (899s left) gem_softpin (allocator-basic-reserve)
[104.504608] Starting subtest: allocator-basic-reserve
[104.678091] Subtest allocator-basic-reserve: SUCCESS (0.162s)
[104.805967] [042/165] (899s left) gem_softpin (safe-alignment)
[104.904856] Starting subtest: safe-alignment
[104.923367] Subtest safe-alignment: SUCCESS (0.018s)
[105.054443] [043/165] (899s left) gem_sync (basic-all)
[105.162383] Starting subtest: basic-all
[107.456959] Subtest basic-all: SUCCESS (2.272s)
[107.643049] [044/165] (896s left) gem_sync (basic-each)
[107.752346] Starting subtest: basic-each
[110.003792] Subtest basic-each: SUCCESS (2.251s)
[110.253283] [045/165] (894s left) gem_tiled_blits (basic)
[110.471642] Starting subtest: basic
[110.500592] Subtest basic: SUCCESS (0.029s)
[110.647104] [046/165] (893s left) gem_tiled_fence_blits (basic)
[110.957813] Starting subtest: basic
[110.986135] Subtest basic: SUCCESS (0.028s)
[111.126348] [047/165] (893s left) gem_tiled_pread_basic
[111.628680] [048/165] (892s left) gem_wait (busy@all-engines)
[111.747740] Starting subtest: busy
[111.748916] Starting dynamic subtest: all-engines
[112.281915] Dynamic subtest all-engines: SUCCESS (0.534s)
[112.283179] Subtest busy: SUCCESS (0.534s)
[112.511083] [049/165] (891s left) gem_wait (wait@all-engines)
[112.628035] Starting subtest: wait
[112.629225] Starting dynamic subtest: all-engines
[113.657165] Dynamic subtest all-engines: SUCCESS (1.030s)
[113.658907] Subtest wait: SUCCESS (1.030s)
[113.894976] [050/165] (890s left) i915_getparams_basic (basic-eu-total)
[113.986434] Starting subtest: basic-eu-total
[113.988495] Subtest basic-eu-total: SUCCESS (0.000s)
[114.078933] [051/165] (890s left) i915_getparams_basic (basic-subslice-total)
[114.169568] Starting subtest: basic-subslice-total
[114.171904] Subtest basic-subslice-total: SUCCESS (0.000s)
[114.264755] [052/165] (890s left) i915_hangman (error-state-basic)
[114.368737] Starting subtest: error-state-basic
[114.380533] Subtest error-state-basic: SUCCESS (0.011s)
[114.477507] [053/165] (890s left) i915_pciid
[114.663208] [054/165] (889s left) kms_addfb_basic (addfb25-4-tiled)
[114.806563] Starting subtest: addfb25-4-tiled
[114.807747] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[114.918143] [055/165] (889s left) kms_addfb_basic (addfb25-bad-modifier)
[115.009866] Starting subtest: addfb25-bad-modifier
[115.010915] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[115.149299] [056/165] (889s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[115.241082] Starting subtest: addfb25-framebuffer-vs-set-tiling
[115.242105] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[115.367188] [057/165] (889s left) kms_addfb_basic (addfb25-modifier-no-flag)
[115.459293] Starting subtest: addfb25-modifier-no-flag
[115.460334] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[115.599159] [058/165] (889s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[115.694253] Starting subtest: addfb25-x-tiled-legacy
[115.695367] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[115.815146] [059/165] (888s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[115.907125] Starting subtest: addfb25-x-tiled-mismatch-legacy
[115.908184] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[116.036830] [060/165] (888s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[116.158076] Starting subtest: addfb25-yf-tiled-legacy
[116.159186] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[116.262397] [061/165] (888s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[116.382074] Starting subtest: addfb25-y-tiled-legacy
[116.383200] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[116.480751] [062/165] (888s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[116.600513] Starting subtest: addfb25-y-tiled-small-legacy
[116.601630] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[116.701316] [063/165] (888s left) kms_addfb_basic (bad-pitch-0)
[116.792654] Starting subtest: bad-pitch-0
[116.793866] Subtest bad-pitch-0: SUCCESS (0.000s)
[116.916908] [064/165] (887s left) kms_addfb_basic (bad-pitch-1024)
[117.008691] Starting subtest: bad-pitch-1024
[117.009890] Subtest bad-pitch-1024: SUCCESS (0.000s)
[117.128880] [065/165] (887s left) kms_addfb_basic (bad-pitch-128)
[117.220412] Starting subtest: bad-pitch-128
[117.221443] Subtest bad-pitch-128: SUCCESS (0.000s)
[117.370924] [066/165] (887s left) kms_addfb_basic (bad-pitch-256)
[117.461831] Starting subtest: bad-pitch-256
[117.463140] Subtest bad-pitch-256: SUCCESS (0.000s)
[117.579751] [067/165] (887s left) kms_addfb_basic (bad-pitch-32)
[117.672558] Starting subtest: bad-pitch-32
[117.673815] Subtest bad-pitch-32: SUCCESS (0.000s)
[117.802201] [068/165] (887s left) kms_addfb_basic (bad-pitch-63)
[117.893789] Starting subtest: bad-pitch-63
[117.894961] Subtest bad-pitch-63: SUCCESS (0.000s)
[118.035856] [069/165] (886s left) kms_addfb_basic (bad-pitch-65536)
[118.127684] Starting subtest: bad-pitch-65536
[118.128809] Subtest bad-pitch-65536: SUCCESS (0.000s)
[118.270884] [070/165] (886s left) kms_addfb_basic (bad-pitch-999)
[118.361144] Starting subtest: bad-pitch-999
[118.362292] Subtest bad-pitch-999: SUCCESS (0.000s)
[118.485716] [071/165] (886s left) kms_addfb_basic (basic)
[118.575740] Starting subtest: basic
[118.576927] Subtest basic: SUCCESS (0.000s)
[118.702559] [072/165] (886s left) kms_addfb_basic (basic-x-tiled-legacy)
[118.791919] Starting subtest: basic-x-tiled-legacy
[118.792992] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[118.916233] [073/165] (886s left) kms_addfb_basic (basic-y-tiled-legacy)
[119.007717] Starting subtest: basic-y-tiled-legacy
[119.008921] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[119.136477] [074/165] (885s left) kms_addfb_basic (bo-too-small)
[119.257128] Starting subtest: bo-too-small
[119.258412] Subtest bo-too-small: SUCCESS (0.000s)
[119.370466] [075/165] (885s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[119.489107] Starting subtest: bo-too-small-due-to-tiling
[119.490210] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[119.585150] [076/165] (885s left) kms_addfb_basic (clobberred-modifier)
[119.675816] Starting subtest: clobberred-modifier
[119.677115] Subtest clobberred-modifier: SUCCESS (0.000s)
[119.803746] [077/165] (885s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[119.893497] Starting subtest: framebuffer-vs-set-tiling
[119.894630] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[120.015386] [078/165] (885s left) kms_addfb_basic (invalid-get-prop)
[120.105751] Starting subtest: invalid-get-prop
[120.106881] Subtest invalid-get-prop: SUCCESS (0.000s)
[120.238211] [079/165] (884s left) kms_addfb_basic (invalid-get-prop-any)
[120.329631] Starting subtest: invalid-get-prop-any
[120.330676] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[120.471846] [080/165] (884s left) kms_addfb_basic (invalid-set-prop)
[120.566629] Starting subtest: invalid-set-prop
[120.567659] Subtest invalid-set-prop: SUCCESS (0.000s)
[120.688570] [081/165] (884s left) kms_addfb_basic (invalid-set-prop-any)
[120.781207] Starting subtest: invalid-set-prop-any
[120.782331] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[120.904538] [082/165] (884s left) kms_addfb_basic (no-handle)
[120.995752] Starting subtest: no-handle
[120.997055] Subtest no-handle: SUCCESS (0.000s)
[121.120487] [083/165] (884s left) kms_addfb_basic (size-max)
[121.243401] Starting subtest: size-max
[121.244479] Subtest size-max: SUCCESS (0.000s)
[121.355480] [084/165] (884s left) kms_addfb_basic (small-bo)
[121.480204] Starting subtest: small-bo
[121.481323] Subtest small-bo: SUCCESS (0.000s)
[121.589834] [085/165] (883s left) kms_addfb_basic (tile-pitch-mismatch)
[121.679796] Starting subtest: tile-pitch-mismatch
[121.680971] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[121.806131] [086/165] (883s left) kms_addfb_basic (too-high)
[121.926368] Starting subtest: too-high
[121.927638] Subtest too-high: SUCCESS (0.001s)
[122.022201] [087/165] (883s left) kms_addfb_basic (too-wide)
[122.142057] Starting subtest: too-wide
[122.143194] Subtest too-wide: SUCCESS (0.000s)
[122.238848] [088/165] (883s left) kms_addfb_basic (unused-handle)
[122.330447] Starting subtest: unused-handle
[122.331534] Subtest unused-handle: SUCCESS (0.000s)
[122.474371] [089/165] (883s left) kms_addfb_basic (unused-modifier)
[122.566459] Starting subtest: unused-modifier
[122.567485] Subtest unused-modifier: SUCCESS (0.000s)
[122.687153] [090/165] (882s left) kms_addfb_basic (unused-offsets)
[122.779698] Starting subtest: unused-offsets
[122.780900] Subtest unused-offsets: SUCCESS (0.000s)
[122.907434] [091/165] (882s left) kms_addfb_basic (unused-pitches)
[122.998076] Starting subtest: unused-pitches
[122.999207] Subtest unused-pitches: SUCCESS (0.000s)
[123.141713] [092/165] (882s left) kms_busy (basic)
[123.266359] Starting subtest: basic
[123.267528] Starting dynamic subtest: flip
[124.278408] Dynamic subtest flip: SUCCESS (1.006s)
[124.293092] Starting dynamic subtest: modeset
[128.112454] Dynamic subtest modeset: SUCCESS (3.838s)
[128.115047] Subtest basic: SUCCESS (4.847s)
[129.085897] [093/165] (876s left) kms_prop_blob (basic)
[129.182794] Starting subtest: basic
[129.184059] Subtest basic: SUCCESS (0.000s)
[129.267102] [094/165] (876s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[129.387319] Starting subtest: basic-busy-flip-before-cursor-atomic
[129.389462] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[129.462434] [095/165] (876s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[129.581574] Starting subtest: basic-busy-flip-before-cursor-legacy
[129.584038] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[129.665198] [096/165] (876s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[129.786428] Starting subtest: basic-flip-after-cursor-atomic
[131.096984] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.312s)
[132.030276] [097/165] (873s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[132.149053] Starting subtest: basic-flip-after-cursor-legacy
[133.439510] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.291s)
[134.396174] [098/165] (871s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[134.515364] Starting subtest: basic-flip-after-cursor-varying-size
[135.829546] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.313s)
[136.767795] [099/165] (869s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[136.903130] Starting subtest: basic-flip-before-cursor-atomic
[138.206235] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.302s)
[139.136111] [100/165] (866s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[139.256068] Starting subtest: basic-flip-before-cursor-legacy
[140.578186] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.320s)
[141.489016] [101/165] (864s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[141.612453] Starting subtest: basic-flip-before-cursor-varying-size
[142.918147] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.307s)
[143.863364] [102/165] (862s left) kms_dsc (dsc-basic)
[143.984842] Starting subtest: dsc-basic
[143.986876] Subtest dsc-basic: SKIP (0.001s)
[144.064879] [103/165] (861s left) kms_flip (basic-flip-vs-dpms)
[144.166936] Starting subtest: basic-flip-vs-dpms
[144.169949] Starting dynamic subtest: A-eDP1
[147.052233] Dynamic subtest A-eDP1: SUCCESS (2.882s)
[147.069978] Starting dynamic subtest: B-eDP1
[149.488280] Dynamic subtest B-eDP1: SUCCESS (2.434s)
[149.504632] Starting dynamic subtest: C-eDP1
[151.878338] Dynamic subtest C-eDP1: SUCCESS (2.386s)
[151.879420] Subtest basic-flip-vs-dpms: SUCCESS (7.711s)
[152.812066] [104/165] (853s left) kms_flip (basic-flip-vs-modeset)
[152.913044] Starting subtest: basic-flip-vs-modeset
[152.916088] Starting dynamic subtest: A-eDP1
[155.761041] Dynamic subtest A-eDP1: SUCCESS (2.845s)
[155.777336] Starting dynamic subtest: B-eDP1
[158.128659] Dynamic subtest B-eDP1: SUCCESS (2.365s)
[158.150331] Starting dynamic subtest: C-eDP1
[160.496339] Dynamic subtest C-eDP1: SUCCESS (2.367s)
[160.498746] Subtest basic-flip-vs-modeset: SUCCESS (7.588s)
[161.467835] [105/165] (844s left) kms_flip (basic-flip-vs-wf_vblank)
[161.569110] Starting subtest: basic-flip-vs-wf_vblank
[161.572336] Starting dynamic subtest: A-eDP1
[163.926701] Dynamic subtest A-eDP1: SUCCESS (2.353s)
[163.948544] Starting dynamic subtest: B-eDP1
[166.153327] Dynamic subtest B-eDP1: SUCCESS (2.224s)
[166.174863] Starting dynamic subtest: C-eDP1
[168.392034] Dynamic subtest C-eDP1: SUCCESS (2.236s)
[168.395611] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.827s)
[169.726178] [106/165] (836s left) kms_flip (basic-plain-flip)
[169.826327] Starting subtest: basic-plain-flip
[169.829662] Starting dynamic subtest: A-eDP1
[171.897542] Dynamic subtest A-eDP1: SUCCESS (2.066s)
[171.918571] Starting dynamic subtest: B-eDP1
[173.870117] Dynamic subtest B-eDP1: SUCCESS (1.966s)
[173.886568] Starting dynamic subtest: C-eDP1
[175.854685] Dynamic subtest C-eDP1: SUCCESS (1.987s)
[175.857745] Subtest basic-plain-flip: SUCCESS (6.032s)
[177.146528] [107/165] (828s left) kms_force_connector_basic (force-connector-state)
[177.246101] Starting subtest: force-connector-state
[177.878680] Subtest force-connector-state: SUCCESS (0.632s)
[178.926696] [108/165] (827s left) kms_force_connector_basic (force-edid)
[179.020518] Starting subtest: force-edid
[179.282493] Subtest force-edid: SUCCESS (0.262s)
[179.426149] [109/165] (826s left) kms_force_connector_basic (force-load-detect)
[179.520442] Starting subtest: force-load-detect
[179.522526] Subtest force-load-detect: SKIP (0.000s)
[179.641192] [110/165] (826s left) kms_force_connector_basic (prune-stale-modes)
[179.737643] Starting subtest: prune-stale-modes
[180.056418] Subtest prune-stale-modes: SUCCESS (0.318s)
[180.194761] [111/165] (825s left) kms_frontbuffer_tracking (basic)
[180.322041] Starting subtest: basic
[183.659189] Subtest basic: SUCCESS (3.337s)
[183.801306] [112/165] (822s left) kms_hdmi_inject (inject-audio)
[184.218046] Starting subtest: inject-audio
[184.545330] Subtest inject-audio: SUCCESS (0.326s)
[185.142465] [113/165] (821s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[185.266329] Starting subtest: compare-crc-sanitycheck-xr24
[185.268881] Starting dynamic subtest: pipe-A-eDP-1
[185.949370] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.677s)
[185.968275] Starting dynamic subtest: pipe-B-eDP-1
[187.329647] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.379s)
[187.352173] Starting dynamic subtest: pipe-C-eDP-1
[188.717906] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.386s)
[188.719107] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.453s)
[189.685963] [114/165] (816s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[189.807100] Starting subtest: compare-crc-sanitycheck-nv12
[189.809767] Starting dynamic subtest: pipe-A-eDP-1
[190.764584] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.954s)
[190.783539] Starting dynamic subtest: pipe-B-eDP-1
[192.178060] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.411s)
[192.202513] Starting dynamic subtest: pipe-C-eDP-1
[193.565906] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.383s)
[193.567610] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.759s)
[194.493645] [115/165] (811s left) kms_pipe_crc_basic (hang-read-crc)
[194.620386] Starting subtest: hang-read-crc
[194.623442] Starting dynamic subtest: pipe-A-eDP-1
[197.038237] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.415s)
[197.056868] Starting dynamic subtest: pipe-B-eDP-1
[200.131230] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.089s)
[200.149626] Starting dynamic subtest: pipe-C-eDP-1
[203.132453] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.999s)
[203.133518] Subtest hang-read-crc: SUCCESS (8.513s)
[204.090640] [116/165] (802s left) kms_pipe_crc_basic (nonblocking-crc)
[204.212309] Starting subtest: nonblocking-crc
[204.215165] Starting dynamic subtest: pipe-A-eDP-1
[205.121104] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.903s)
[205.155680] Starting dynamic subtest: pipe-B-eDP-1
[206.697814] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.570s)
[206.721901] Starting dynamic subtest: pipe-C-eDP-1
[208.238414] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.535s)
[208.239471] Subtest nonblocking-crc: SUCCESS (4.026s)
[209.211675] [117/165] (797s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[209.334121] Starting subtest: nonblocking-crc-frame-sequence
[209.336924] Starting dynamic subtest: pipe-A-eDP-1
[210.224348] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.886s)
[210.265121] Starting dynamic subtest: pipe-B-eDP-1
[211.778447] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.548s)
[211.797910] Starting dynamic subtest: pipe-C-eDP-1
[213.337190] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.553s)
[213.339158] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.003s)
[214.268723] [118/165] (792s left) kms_pipe_crc_basic (read-crc)
[214.392080] Starting subtest: read-crc
[214.395328] Starting dynamic subtest: pipe-A-eDP-1
[215.230441] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.836s)
[215.254938] Starting dynamic subtest: pipe-B-eDP-1
[216.766992] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.529s)
[216.785512] Starting dynamic subtest: pipe-C-eDP-1
[218.265243] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.494s)
[218.266831] Subtest read-crc: SUCCESS (3.873s)
[219.194149] [119/165] (787s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[219.317043] Starting subtest: read-crc-frame-sequence
[219.319832] Starting dynamic subtest: pipe-A-eDP-1
[220.140075] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.820s)
[220.174311] Starting dynamic subtest: pipe-B-eDP-1
[221.658183] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.508s)
[221.684355] Starting dynamic subtest: pipe-C-eDP-1
[223.156769] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.493s)
[223.158995] Subtest read-crc-frame-sequence: SUCCESS (3.840s)
[224.121511] [120/165] (782s left) kms_pm_backlight (basic-brightness)
[224.240434] Starting subtest: basic-brightness
[224.334708] Starting dynamic subtest: eDP-1
[224.891659] Dynamic subtest eDP-1: SUCCESS (0.555s)
[224.893946] Subtest basic-brightness: SUCCESS (0.649s)
[225.816707] [121/165] (780s left) kms_psr (primary_page_flip)
[225.940496] Starting subtest: primary_page_flip
[229.826710] Subtest primary_page_flip: SUCCESS (3.885s)
[230.781081] [122/165] (775s left) kms_psr (cursor_plane_move)
[230.904182] Starting subtest: cursor_plane_move
[234.689400] Subtest cursor_plane_move: SUCCESS (3.783s)
[235.643488] [123/165] (770s left) kms_psr (sprite_plane_onoff)
[235.766785] Starting subtest: sprite_plane_onoff
[239.693348] Subtest sprite_plane_onoff: SUCCESS (3.922s)
[240.635570] [124/165] (765s left) kms_psr (primary_mmap_gtt)
[240.758307] Starting subtest: primary_mmap_gtt
[244.622261] Subtest primary_mmap_gtt: SUCCESS (3.864s)
[245.564047] [125/165] (761s left) kms_setmode (basic-clone-single-crtc)
[245.656385] Starting subtest: basic-clone-single-crtc
[245.660092] Subtest basic-clone-single-crtc: SKIP (0.000s)
[245.746495] [126/165] (760s left) i915_pm_rpm (basic-pci-d3-state)
[247.585464] Starting subtest: basic-pci-d3-state
[251.983876] Subtest basic-pci-d3-state: SUCCESS (4.399s)
[252.190328] [127/165] (754s left) i915_pm_rpm (basic-rte)
[252.280684] Starting subtest: basic-rte
[258.453407] Subtest basic-rte: SUCCESS (6.171s)
[259.946727] [128/165] (746s left) i915_pm_rps (basic-api)
[260.043612] Starting subtest: basic-api
[260.047964] Subtest basic-api: SUCCESS (0.004s)
[260.147285] [129/165] (746s left) prime_self_import (basic-llseek-bad)
[260.237438] Starting subtest: basic-llseek-bad
[260.242097] Subtest basic-llseek-bad: SUCCESS (0.003s)
[260.347550] [130/165] (746s left) prime_self_import (basic-llseek-size)
[260.436646] Starting subtest: basic-llseek-size
[260.441091] Subtest basic-llseek-size: SUCCESS (0.004s)
[260.544750] [131/165] (746s left) prime_self_import (basic-with_fd_dup)
[260.632695] Starting subtest: basic-with_fd_dup
[260.642444] Subtest basic-with_fd_dup: SUCCESS (0.004s)
[260.747778] [132/165] (746s left) prime_self_import (basic-with_one_bo)
[260.833906] Starting subtest: basic-with_one_bo
[260.838623] Subtest basic-with_one_bo: SUCCESS (0.005s)
[260.926213] [133/165] (745s left) prime_self_import (basic-with_one_bo_two_files)
[261.016278] Starting subtest: basic-with_one_bo_two_files
[261.018496] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[261.130282] [134/165] (745s left) prime_self_import (basic-with_two_bos)
[261.218596] Starting subtest: basic-with_two_bos
[261.223149] Subtest basic-with_two_bos: SUCCESS (0.005s)
[261.329828] [135/165] (745s left) prime_vgem (basic-fence-flip)
[261.447314] Starting subtest: basic-fence-flip
[261.987829] Subtest basic-fence-flip: SUCCESS (0.540s)
[262.115601] [136/165] (744s left) prime_vgem (basic-fence-mmap)
[262.231184] Starting subtest: basic-fence-mmap
[262.282063] Subtest basic-fence-mmap: SUCCESS (0.051s)
[262.399763] [137/165] (744s left) prime_vgem (basic-fence-read)
[262.522865] Starting subtest: basic-fence-read
[262.599122] Subtest basic-fence-read: SUCCESS (0.076s)
[262.701629] [138/165] (744s left) prime_vgem (basic-gtt)
[262.831949] Starting subtest: basic-gtt
[262.857713] Subtest basic-gtt: SUCCESS (0.026s)
[262.965069] [139/165] (744s left) prime_vgem (basic-read)
[263.064458] Starting subtest: basic-read
[263.102520] Subtest basic-read: SUCCESS (0.038s)
[263.220151] [140/165] (743s left) prime_vgem (basic-write)
[263.352489] Starting subtest: basic-write
[263.397602] Subtest basic-write: SUCCESS (0.045s)
[263.480177] [141/165] (743s left) vgem_basic (setversion)
[263.574349] Starting subtest: setversion
[263.576597] Subtest setversion: SUCCESS (0.000s)
[263.649895] [142/165] (743s left) vgem_basic (create)
[263.758297] Starting subtest: create
[263.759528] Subtest create: SUCCESS (0.000s)
[263.832518] [143/165] (743s left) vgem_basic (debugfs)
[263.941359] Starting subtest: debugfs
[263.942500] Subtest debugfs: SUCCESS (0.001s)
[264.015484] [144/165] (743s left) vgem_basic (dmabuf-export)
[264.107426] Starting subtest: dmabuf-export
[264.112299] Subtest dmabuf-export: SUCCESS (0.003s)
[264.218779] [145/165] (742s left) vgem_basic (dmabuf-fence)
[264.325766] Starting subtest: dmabuf-fence
[264.327352] Subtest dmabuf-fence: SUCCESS (0.001s)
[264.400181] [146/165] (742s left) vgem_basic (dmabuf-fence-before)
[264.509082] Starting subtest: dmabuf-fence-before
[264.510368] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[264.585875] [147/165] (742s left) vgem_basic (dmabuf-mmap)
[264.691856] Starting subtest: dmabuf-mmap
[264.717318] Subtest dmabuf-mmap: SUCCESS (0.026s)
[264.804078] [148/165] (742s left) vgem_basic (mmap)
[264.908703] Starting subtest: mmap
[264.928408] Subtest mmap: SUCCESS (0.017s)
[264.997581] [149/165] (742s left) vgem_basic (second-client)
[265.108819] Starting subtest: second-client
[265.145522] Subtest second-client: SUCCESS (0.034s)
[265.218777] [150/165] (742s left) vgem_basic (sysfs)
[265.325957] Starting subtest: sysfs
[265.327198] Subtest sysfs: SUCCESS (0.001s)
[265.401941] [151/165] (741s left) core_hotunplug (unbind-rebind)
[265.542831] Starting subtest: unbind-rebind
[270.020240] Subtest unbind-rebind: SUCCESS (4.477s)
[270.056399] [152/165] (737s left) vgem_basic (unload)
[270.152071] Starting subtest: unload
[299.220143] Subtest unload: SUCCESS (29.033s)
[299.328640] [153/165] (708s left) i915_module_load (reload)
[299.420490] Starting subtest: reload
[302.784287] Subtest reload: SUCCESS (3.364s)
[302.815909] [154/165] (704s left) gem_lmem_swapping (basic)
[302.927104] Subtest basic: SKIP (0.000s)
[302.968474] [155/165] (704s left) gem_lmem_swapping (parallel-random-engines)
[303.064912] Subtest parallel-random-engines: SKIP (0.000s)
[303.124042] [156/165] (704s left) gem_lmem_swapping (random-engines)
[303.220723] Subtest random-engines: SKIP (0.000s)
[303.268382] [157/165] (704s left) gem_lmem_swapping (verify-random)
[303.364470] Subtest verify-random: SKIP (0.000s)
[303.418833] [158/165] (704s left) i915_pm_rpm (module-reload)
[315.870740] Starting subtest: module-reload
[350.234479] Subtest module-reload: SUCCESS (34.356s)
[350.262929] [159/165] (657s left) i915_selftest (live)
[350.364595] Starting subtest: live
[350.365791] Starting dynamic subtest: sanitycheck
[352.215858] Dynamic subtest sanitycheck: SUCCESS (1.841s)
[352.231836] Starting dynamic subtest: uncore
[354.080113] Dynamic subtest uncore: SUCCESS (1.861s)
[354.095100] Starting dynamic subtest: workarounds
[356.195390] Dynamic subtest workarounds: SUCCESS (2.112s)
[356.210194] Starting dynamic subtest: gt_engines
[358.488435] Dynamic subtest gt_engines: SUCCESS (2.289s)
[358.503056] Starting dynamic subtest: gt_timelines
[371.284443] Dynamic subtest gt_timelines: SUCCESS (12.792s)
[371.299077] Starting dynamic subtest: gt_contexts
[373.282386] Dynamic subtest gt_contexts: SUCCESS (1.994s)
[373.297321] Starting dynamic subtest: gt_lrc
[377.769401] Dynamic subtest gt_lrc: SUCCESS (4.484s)
[377.783963] Starting dynamic subtest: gt_mocs
[379.763544] Dynamic subtest gt_mocs: SUCCESS (1.990s)
[379.778144] Starting dynamic subtest: gt_pm
[384.897735] Dynamic subtest gt_pm: SUCCESS (5.130s)
[384.912439] Starting dynamic subtest: gt_heartbeat
[386.845392] Dynamic subtest gt_heartbeat: SUCCESS (1.944s)
[386.860617] Starting dynamic subtest: gt_tlb
[389.975210] Dynamic subtest gt_tlb: SUCCESS (3.127s)
[389.990415] Starting dynamic subtest: requests
[397.657888] Dynamic subtest requests: SUCCESS (7.679s)
[397.672880] Starting dynamic subtest: migrate
[402.277125] Dynamic subtest migrate: SUCCESS (4.616s)
[402.292350] Starting dynamic subtest: active
[404.132268] Dynamic subtest active: SUCCESS (1.852s)
[404.147300] Starting dynamic subtest: objects
[407.568422] Dynamic subtest objects: SUCCESS (3.433s)
[407.583367] Starting dynamic subtest: mman
[414.513551] Dynamic subtest mman: SUCCESS (6.941s)
[414.528419] Starting dynamic subtest: dmabuf
[416.435477] Dynamic subtest dmabuf: SUCCESS (1.918s)
[416.450528] Starting dynamic subtest: vma
[418.329763] Dynamic subtest vma: SUCCESS (1.895s)
[418.343995] Starting dynamic subtest: coherency
[422.871361] Dynamic subtest coherency: SUCCESS (4.534s)
[422.886794] Starting dynamic subtest: gtt
[432.350459] Dynamic subtest gtt: SUCCESS (9.476s)
[432.365921] Starting dynamic subtest: gem
[434.340544] Dynamic subtest gem: SUCCESS (1.987s)
[434.355825] Starting dynamic subtest: evict
[438.216956] Dynamic subtest evict: SUCCESS (3.873s)
[438.232360] Starting dynamic subtest: hugepages
[445.973399] Dynamic subtest hugepages: SUCCESS (7.754s)
[445.988936] Starting dynamic subtest: gem_contexts
[461.535161] Dynamic subtest gem_contexts: SUCCESS (15.558s)
[461.551206] Starting dynamic subtest: client
[463.401612] Dynamic subtest client: SUCCESS (1.863s)
[463.417344] Starting dynamic subtest: gem_migrate
[465.278422] Dynamic subtest gem_migrate: SUCCESS (1.873s)
[465.293934] Starting dynamic subtest: reset
[470.708342] Dynamic subtest reset: SUCCESS (5.426s)
[470.724139] Starting dynamic subtest: memory_region
[472.614474] Dynamic subtest memory_region: SUCCESS (1.903s)
[472.629908] Starting dynamic subtest: hangcheck
[497.000681] Dynamic subtest hangcheck: SUCCESS (24.382s)
[497.016119] Starting dynamic subtest: execlists
[507.119685] Dynamic subtest execlists: SUCCESS (10.115s)
[507.135212] Starting dynamic subtest: ring_submission
[509.046434] Dynamic subtest ring_submission: SUCCESS (1.923s)
[509.062145] Starting dynamic subtest: perf
[511.028386] Dynamic subtest perf: SUCCESS (1.978s)
[511.044222] Starting dynamic subtest: slpc
[512.924622] Dynamic subtest slpc: SUCCESS (1.893s)
[512.940093] Starting dynamic subtest: guc
[514.783556] Dynamic subtest guc: SUCCESS (1.855s)
[514.799395] Starting dynamic subtest: guc_multi_lrc
[516.643403] Dynamic subtest guc_multi_lrc: SUCCESS (1.856s)
[516.659381] Starting dynamic subtest: guc_hang
[518.509305] Dynamic subtest guc_hang: SUCCESS (1.863s)
[518.525071] Starting dynamic subtest: late_gt_pm
[520.418754] Dynamic subtest late_gt_pm: SUCCESS (1.906s)
[520.420121] Subtest live: SUCCESS (170.047s)
[520.445079] [160/165] (487s left) dmabuf (all-tests)
[520.542843] Starting subtest: all-tests
[520.544129] Starting dynamic subtest: sanitycheck
[520.610731] Dynamic subtest sanitycheck: SUCCESS (0.066s)
[520.630223] Starting dynamic subtest: dma_fence
[520.798751] Dynamic subtest dma_fence: SUCCESS (0.184s)
[520.817080] Starting dynamic subtest: dma_fence_chain
[539.374224] Dynamic subtest dma_fence_chain: SUCCESS (18.563s)
[539.387439] Starting dynamic subtest: dma_fence_unwrap
[539.430668] Dynamic subtest dma_fence_unwrap: SUCCESS (0.061s)
[539.449696] Starting dynamic subtest: dma_resv
[539.519852] Dynamic subtest dma_resv: SUCCESS (0.085s)
[539.521994] Subtest all-tests: SUCCESS (18.978s)
[539.555671] [161/165] (468s left) i915_suspend (basic-s2idle-without-i915)
[539.645094] Starting subtest: basic-s2idle-without-i915
[562.622034] Subtest basic-s2idle-without-i915: SUCCESS (8.204s)
[563.027807] [162/165] (459s left) i915_suspend (basic-s3-without-i915)
[563.118732] Starting subtest: basic-s3-without-i915
[586.828223] Subtest basic-s3-without-i915: SUCCESS (7.016s)
[587.140331] [163/165] (452s left) gem_exec_suspend (basic-s0)
[587.270218] Starting subtest: basic-S0
[587.271487] Starting dynamic subtest: smem
[606.891269] Dynamic subtest smem: SUCCESS (3.795s)
[606.892649] Subtest basic-S0: SUCCESS (3.797s)
[607.099773] [164/165] (448s left) gem_exec_suspend (basic-s3)
[607.208327] Starting subtest: basic-S3
[607.209626] Starting dynamic subtest: smem
[625.907294] Dynamic subtest smem: SUCCESS (3.738s)
[625.908939] Subtest basic-S3: SUCCESS (3.739s)
[626.121070] [165/165] (444s left) kms_pipe_crc_basic (suspend-read-crc)
[626.244156] Starting subtest: suspend-read-crc
[626.247213] Starting dynamic subtest: pipe-A-eDP-1
[643.872808] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.539s)
[643.906622] Starting dynamic subtest: pipe-B-eDP-1
[662.821663] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.165s)
[662.866259] Starting dynamic subtest: pipe-C-eDP-1
[680.805226] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.120s)
[680.807382] Subtest suspend-read-crc: SUCCESS (8.847s)
[681.678518] Closing watchdogs
Done.