[58.740096] Initializing watchdogs
[58.740287]   /dev/watchdog0
[58.774134] [001/165] (960s left) i915_module_load (load)
[59.006852] Starting subtest: load
[61.510697] Subtest load: SUCCESS (2.506s)
[61.603825] [002/165] (957s left) core_auth (basic-auth)
[61.891858] Starting subtest: basic-auth
[61.912597] Subtest basic-auth: SUCCESS (0.004s)
[62.000909] [003/165] (957s left) debugfs_test (read_all_entries)
[62.251228] Starting subtest: read_all_entries
[62.651965] Subtest read_all_entries: SUCCESS (0.399s)
[62.849332] [004/165] (956s left) debugfs_test (basic-hwmon)
[63.076850] Starting subtest: basic-hwmon
[63.119779] Subtest basic-hwmon: SKIP (0.000s)
[63.259676] [005/165] (956s left) fbdev (eof)
[63.479642] Starting subtest: eof
[63.501618] Subtest eof: SUCCESS (0.002s)
[63.611187] [006/165] (956s left) fbdev (info)
[63.817719] Starting subtest: info
[63.838966] Subtest info: SUCCESS (0.000s)
[63.948659] [007/165] (955s left) fbdev (nullptr)
[64.164153] Starting subtest: nullptr
[64.186572] Subtest nullptr: SUCCESS (0.000s)
[64.317399] [008/165] (955s left) fbdev (read)
[64.534317] Starting subtest: read
[66.005602] Subtest read: SUCCESS (1.475s)
[66.126150] [009/165] (953s left) fbdev (write)
[66.340356] Starting subtest: write
[67.094126] Subtest write: SUCCESS (0.763s)
[67.212151] [010/165] (952s left) gem_basic (bad-close)
[67.452767] Starting subtest: bad-close
[67.500988] Subtest bad-close: SUCCESS (0.000s)
[67.593153] [011/165] (952s left) gem_basic (create-close)
[67.832633] Starting subtest: create-close
[67.881719] Subtest create-close: SUCCESS (0.000s)
[67.976679] [012/165] (952s left) gem_basic (create-fd-close)
[68.213287] Starting subtest: create-fd-close
[68.255231] Subtest create-fd-close: SUCCESS (0.000s)
[68.343732] [013/165] (951s left) gem_busy (busy@all-engines)
[68.583408] Starting subtest: busy
[68.604210] Starting dynamic subtest: all-engines
[68.667812] Dynamic subtest all-engines: SUCCESS (0.092s)
[68.690098] Subtest busy: SUCCESS (0.092s)
[69.075137] [014/165] (951s left) gem_close_race (basic-process)
[69.334493] Starting subtest: basic-process
[69.419098] Subtest basic-process: SUCCESS (0.105s)
[69.643217] [015/165] (950s left) gem_close_race (basic-threads)
[69.874850] Starting subtest: basic-threads
[71.080189] Subtest basic-threads: SUCCESS (1.208s)
[71.308863] [016/165] (949s left) gem_ctx_create (basic)
[71.548760] Starting subtest: basic
[71.569344] Subtest basic: SUCCESS (0.000s)
[71.760987] [017/165] (948s left) gem_ctx_create (basic-files)
[71.992783] Starting subtest: basic-files
[74.018184] Subtest basic-files: SUCCESS (2.038s)
[74.294562] [018/165] (946s left) gem_ctx_exec (basic)
[74.562863] Starting subtest: basic
[74.584629] Subtest basic: SUCCESS (0.008s)
[74.673830] [019/165] (946s left) gem_exec_basic (basic)
[74.909743] Starting subtest: basic
[74.932413] Starting dynamic subtest: rcs0-smem
[74.953646] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[74.986801] Starting dynamic subtest: bcs0-smem
[75.009730] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[75.045846] Starting dynamic subtest: vcs0-smem
[75.067241] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[75.100830] Starting dynamic subtest: vecs0-smem
[75.122576] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[75.150397] Subtest basic: SUCCESS (0.012s)
[75.238250] [020/165] (945s left) gem_exec_create (basic)
[75.478494] Starting subtest: basic
[75.499197] Starting dynamic subtest: smem
[77.546622] Dynamic subtest smem: SUCCESS (2.062s)
[77.568070] Subtest basic: SUCCESS (2.062s)
[77.761516] [021/165] (943s left) gem_exec_fence (basic-busy)
[78.119788] Starting subtest: basic-busy
[78.142832] Starting dynamic subtest: rcs0
[78.186481] Dynamic subtest rcs0: SUCCESS (0.027s)
[78.222158] Starting dynamic subtest: bcs0
[78.266846] Dynamic subtest bcs0: SUCCESS (0.032s)
[78.300192] Starting dynamic subtest: vcs0
[78.342943] Dynamic subtest vcs0: SUCCESS (0.029s)
[78.378080] Starting dynamic subtest: vecs0
[78.417103] Dynamic subtest vecs0: SUCCESS (0.023s)
[78.439742] Subtest basic-busy: SUCCESS (0.114s)
[79.015266] [022/165] (942s left) gem_exec_fence (basic-wait)
[79.368272] Starting subtest: basic-wait
[79.390087] Starting dynamic subtest: rcs0
[79.410549] Dynamic subtest rcs0: SUCCESS (0.030s)
[79.446724] Starting dynamic subtest: bcs0
[79.467748] Dynamic subtest bcs0: SUCCESS (0.033s)
[79.506064] Starting dynamic subtest: vcs0
[79.527564] Dynamic subtest vcs0: SUCCESS (0.029s)
[79.561550] Starting dynamic subtest: vecs0
[79.583235] Dynamic subtest vecs0: SUCCESS (0.030s)
[79.607440] Subtest basic-wait: SUCCESS (0.123s)
[80.180276] [023/165] (940s left) gem_exec_fence (basic-await)
[80.535242] Starting subtest: basic-await
[80.555398] Starting dynamic subtest: rcs0
[80.675401] Dynamic subtest rcs0: SUCCESS (0.138s)
[80.713241] Starting dynamic subtest: bcs0
[80.804841] Dynamic subtest bcs0: SUCCESS (0.129s)
[80.845294] Starting dynamic subtest: vcs0
[80.939530] Dynamic subtest vcs0: SUCCESS (0.135s)
[80.978813] Starting dynamic subtest: vecs0
[81.074532] Dynamic subtest vecs0: SUCCESS (0.134s)
[81.095199] Subtest basic-await: SUCCESS (0.539s)
[81.780219] [024/165] (939s left) gem_exec_fence (nb-await)
[82.130572] Starting subtest: nb-await
[82.152362] Starting dynamic subtest: rcs0
[82.210084] Dynamic subtest rcs0: SUCCESS (0.078s)
[82.250645] Starting dynamic subtest: bcs0
[82.295718] Dynamic subtest bcs0: SUCCESS (0.070s)
[82.333561] Starting dynamic subtest: vcs0
[82.354065] Dynamic subtest vcs0: SUCCESS (0.071s)
[82.392308] Starting dynamic subtest: vecs0
[82.437257] Dynamic subtest vecs0: SUCCESS (0.069s)
[82.458231] Subtest nb-await: SUCCESS (0.290s)
[83.110589] [025/165] (938s left) gem_exec_gttfill (basic)
[83.348743] Starting subtest: basic
[105.386423] Subtest basic: SUCCESS (21.874s)
[105.480789] [026/165] (915s left) gem_exec_parallel (engines)
[105.873753] Starting subtest: engines
[105.896748] Starting dynamic subtest: basic
[109.618676] Dynamic subtest basic: SUCCESS (3.584s)
[109.657555] Starting dynamic subtest: contexts
[113.872146] Dynamic subtest contexts: SUCCESS (4.422s)
[113.908659] Starting dynamic subtest: fds
[120.494296] Dynamic subtest fds: SUCCESS (6.443s)
[120.533933] Starting dynamic subtest: userptr
[124.367148] Dynamic subtest userptr: SUCCESS (3.867s)
[124.388819] Subtest engines: SUCCESS (18.318s)
[124.500740] [027/165] (897s left) gem_exec_store (basic)
[124.805347] Starting subtest: basic
[124.826071] Subtest basic: SUCCESS (0.065s)
[125.029924] [028/165] (896s left) gem_flink_basic (bad-flink)
[125.257946] Starting subtest: bad-flink
[125.277986] Subtest bad-flink: SUCCESS (0.000s)
[125.370380] [029/165] (896s left) gem_flink_basic (bad-open)
[125.598044] Starting subtest: bad-open
[125.620313] Subtest bad-open: SUCCESS (0.000s)
[125.710281] [030/165] (896s left) gem_flink_basic (basic)
[125.938646] Starting subtest: basic
[125.958555] Subtest basic: SUCCESS (0.000s)
[126.050988] [031/165] (895s left) gem_flink_basic (double-flink)
[126.282074] Starting subtest: double-flink
[126.304427] Subtest double-flink: SUCCESS (0.000s)
[126.393590] [032/165] (895s left) gem_flink_basic (flink-lifetime)
[126.619382] Starting subtest: flink-lifetime
[126.640390] Subtest flink-lifetime: SUCCESS (0.003s)
[126.731993] [033/165] (895s left) gem_huc_copy (huc-copy)
[126.962107] Starting subtest: huc-copy
[126.984163] Subtest huc-copy: SUCCESS (0.005s)
[127.077623] [034/165] (895s left) gem_linear_blits (basic)
[127.468179] Starting subtest: basic
[127.489273] Subtest basic: SUCCESS (0.020s)
[127.657955] [035/165] (894s left) gem_mmap (basic)
[128.060135] Starting subtest: basic
[128.165534] Subtest basic: SUCCESS (0.001s)
[128.257308] [036/165] (894s left) gem_mmap_gtt (basic)
[128.493905] Starting subtest: basic
[128.514976] Subtest basic: SUCCESS (0.002s)
[128.605406] [037/165] (893s left) gem_render_linear_blits (basic)
[128.835211] Starting subtest: basic
[128.940182] Subtest basic: SUCCESS (0.083s)
[129.031113] [038/165] (893s left) gem_render_tiled_blits (basic)
[129.266277] Starting subtest: basic
[129.389275] Subtest basic: SUCCESS (0.114s)
[129.494910] [039/165] (893s left) gem_ringfill (basic-all)
[130.005555] Starting subtest: basic-all
[131.184925] Subtest basic-all: SUCCESS (1.204s)
[131.335202] [040/165] (891s left) gem_softpin (allocator-basic)
[131.571981] Starting subtest: allocator-basic
[131.790663] Subtest allocator-basic: SUCCESS (0.241s)
[131.946816] [041/165] (890s left) gem_softpin (allocator-basic-reserve)
[132.174615] Starting subtest: allocator-basic-reserve
[132.394872] Subtest allocator-basic-reserve: SUCCESS (0.244s)
[132.531157] [042/165] (890s left) gem_softpin (safe-alignment)
[132.757560] Starting subtest: safe-alignment
[132.780369] Subtest safe-alignment: SUCCESS (0.030s)
[132.911126] [043/165] (890s left) gem_sync (basic-all)
[133.322419] Starting subtest: basic-all
[135.227275] Subtest basic-all: SUCCESS (2.069s)
[135.445508] [044/165] (887s left) gem_sync (basic-each)
[135.850128] Starting subtest: basic-each
[138.034606] Subtest basic-each: SUCCESS (2.348s)
[138.244975] [045/165] (884s left) gem_tiled_blits (basic)
[138.615381] Starting subtest: basic
[138.662772] Subtest basic: SUCCESS (0.049s)
[138.856674] [046/165] (884s left) gem_tiled_fence_blits (basic)
[139.422858] Starting subtest: basic
[139.469349] Subtest basic: SUCCESS (0.044s)
[139.659313] [047/165] (883s left) gem_tiled_pread_basic
[140.398140] [048/165] (882s left) gem_wait (busy@all-engines)
[140.658363] Starting subtest: busy
[140.680662] Starting dynamic subtest: all-engines
[141.180351] Dynamic subtest all-engines: SUCCESS (0.530s)
[141.203237] Subtest busy: SUCCESS (0.531s)
[141.447877] [049/165] (882s left) gem_wait (wait@all-engines)
[141.698514] Starting subtest: wait
[141.719302] Starting dynamic subtest: all-engines
[142.735843] Dynamic subtest all-engines: SUCCESS (1.039s)
[142.757082] Subtest wait: SUCCESS (1.039s)
[142.994600] [050/165] (880s left) i915_getparams_basic (basic-eu-total)
[143.223124] Starting subtest: basic-eu-total
[143.265483] Subtest basic-eu-total: SUCCESS (0.000s)
[143.358097] [051/165] (880s left) i915_getparams_basic (basic-subslice-total)
[143.590122] Starting subtest: basic-subslice-total
[143.632380] Subtest basic-subslice-total: SUCCESS (0.000s)
[143.727555] [052/165] (880s left) i915_hangman (error-state-basic)
[143.963297] Starting subtest: error-state-basic
[144.238739] Subtest error-state-basic: SUCCESS (0.276s)
[144.353175] [053/165] (879s left) i915_pciid
[144.694874] [054/165] (879s left) kms_addfb_basic (addfb25-4-tiled)
[145.074437] Starting subtest: addfb25-4-tiled
[145.093407] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[145.196166] [055/165] (878s left) kms_addfb_basic (addfb25-bad-modifier)
[145.423964] Starting subtest: addfb25-bad-modifier
[145.446295] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[145.633331] [056/165] (878s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[145.898791] Starting subtest: addfb25-framebuffer-vs-set-tiling
[145.927086] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[146.119427] [057/165] (878s left) kms_addfb_basic (addfb25-modifier-no-flag)
[146.386251] Starting subtest: addfb25-modifier-no-flag
[146.412621] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[146.561580] [058/165] (877s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[146.822529] Starting subtest: addfb25-x-tiled-legacy
[146.847323] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[147.080959] [059/165] (877s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[147.342969] Starting subtest: addfb25-x-tiled-mismatch-legacy
[147.367256] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[147.530452] [060/165] (877s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[147.861832] Starting subtest: addfb25-yf-tiled-legacy
[147.888995] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[147.998261] [061/165] (876s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[148.321363] Starting subtest: addfb25-y-tiled-legacy
[148.347124] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[148.447214] [062/165] (876s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[148.771443] Starting subtest: addfb25-y-tiled-small-legacy
[148.791997] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[148.889306] [063/165] (875s left) kms_addfb_basic (bad-pitch-0)
[149.121788] Starting subtest: bad-pitch-0
[149.144986] Subtest bad-pitch-0: SUCCESS (0.000s)
[149.316581] [064/165] (875s left) kms_addfb_basic (bad-pitch-1024)
[149.546608] Starting subtest: bad-pitch-1024
[149.570967] Subtest bad-pitch-1024: SUCCESS (0.000s)
[149.746084] [065/165] (875s left) kms_addfb_basic (bad-pitch-128)
[149.982433] Starting subtest: bad-pitch-128
[150.003945] Subtest bad-pitch-128: SUCCESS (0.000s)
[150.165652] [066/165] (874s left) kms_addfb_basic (bad-pitch-256)
[150.401076] Starting subtest: bad-pitch-256
[150.421412] Subtest bad-pitch-256: SUCCESS (0.000s)
[150.581618] [067/165] (874s left) kms_addfb_basic (bad-pitch-32)
[150.800026] Starting subtest: bad-pitch-32
[150.821911] Subtest bad-pitch-32: SUCCESS (0.000s)
[151.015575] [068/165] (874s left) kms_addfb_basic (bad-pitch-63)
[151.258513] Starting subtest: bad-pitch-63
[151.280974] Subtest bad-pitch-63: SUCCESS (0.000s)
[151.447971] [069/165] (873s left) kms_addfb_basic (bad-pitch-65536)
[151.681193] Starting subtest: bad-pitch-65536
[151.704285] Subtest bad-pitch-65536: SUCCESS (0.001s)
[151.882530] [070/165] (873s left) kms_addfb_basic (bad-pitch-999)
[152.116188] Starting subtest: bad-pitch-999
[152.137533] Subtest bad-pitch-999: SUCCESS (0.000s)
[152.315743] [071/165] (873s left) kms_addfb_basic (basic)
[152.553503] Starting subtest: basic
[152.576187] Subtest basic: SUCCESS (0.000s)
[152.749341] [072/165] (872s left) kms_addfb_basic (basic-x-tiled-legacy)
[152.979686] Starting subtest: basic-x-tiled-legacy
[153.002691] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[153.179956] [073/165] (872s left) kms_addfb_basic (basic-y-tiled-legacy)
[153.411823] Starting subtest: basic-y-tiled-legacy
[153.432876] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[153.597673] [074/165] (872s left) kms_addfb_basic (bo-too-small)
[153.902219] Starting subtest: bo-too-small
[153.922465] Subtest bo-too-small: SUCCESS (0.001s)
[154.021301] [075/165] (871s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[154.338516] Starting subtest: bo-too-small-due-to-tiling
[154.366472] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[154.489468] [076/165] (871s left) kms_addfb_basic (clobberred-modifier)
[154.760974] Starting subtest: clobberred-modifier
[154.788499] Subtest clobberred-modifier: SUCCESS (0.000s)
[154.950562] [077/165] (871s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[155.224938] Starting subtest: framebuffer-vs-set-tiling
[155.253072] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[155.407127] [078/165] (870s left) kms_addfb_basic (invalid-get-prop)
[155.665258] Starting subtest: invalid-get-prop
[155.692596] Subtest invalid-get-prop: SUCCESS (0.000s)
[155.857738] [079/165] (870s left) kms_addfb_basic (invalid-get-prop-any)
[156.114574] Starting subtest: invalid-get-prop-any
[156.138579] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[156.305606] [080/165] (870s left) kms_addfb_basic (invalid-set-prop)
[156.556075] Starting subtest: invalid-set-prop
[156.580136] Subtest invalid-set-prop: SUCCESS (0.000s)
[156.753659] [081/165] (869s left) kms_addfb_basic (invalid-set-prop-any)
[156.985245] Starting subtest: invalid-set-prop-any
[157.005790] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[157.182416] [082/165] (869s left) kms_addfb_basic (no-handle)
[157.414329] Starting subtest: no-handle
[157.436996] Subtest no-handle: SUCCESS (0.000s)
[157.600528] [083/165] (869s left) kms_addfb_basic (size-max)
[157.924823] Starting subtest: size-max
[157.947202] Subtest size-max: SUCCESS (0.000s)
[158.039823] [084/165] (868s left) kms_addfb_basic (small-bo)
[158.335811] Starting subtest: small-bo
[158.356703] Subtest small-bo: SUCCESS (0.000s)
[158.462639] [085/165] (868s left) kms_addfb_basic (tile-pitch-mismatch)
[158.698519] Starting subtest: tile-pitch-mismatch
[158.720489] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[158.895649] [086/165] (867s left) kms_addfb_basic (too-high)
[159.218150] Starting subtest: too-high
[159.238865] Subtest too-high: SUCCESS (0.002s)
[159.333867] [087/165] (867s left) kms_addfb_basic (too-wide)
[159.652601] Starting subtest: too-wide
[159.675721] Subtest too-wide: SUCCESS (0.000s)
[159.768983] [088/165] (867s left) kms_addfb_basic (unused-handle)
[160.000451] Starting subtest: unused-handle
[160.020448] Subtest unused-handle: SUCCESS (0.000s)
[160.182347] [089/165] (866s left) kms_addfb_basic (unused-modifier)
[160.415668] Starting subtest: unused-modifier
[160.438161] Subtest unused-modifier: SUCCESS (0.000s)
[160.615926] [090/165] (866s left) kms_addfb_basic (unused-offsets)
[160.847895] Starting subtest: unused-offsets
[160.870457] Subtest unused-offsets: SUCCESS (0.000s)
[161.048251] [091/165] (866s left) kms_addfb_basic (unused-pitches)
[161.282991] Starting subtest: unused-pitches
[161.304581] Subtest unused-pitches: SUCCESS (0.000s)
[161.477307] [092/165] (865s left) kms_busy (basic)
[161.810794] Starting subtest: basic
[161.832137] Starting dynamic subtest: flip
[162.851549] Dynamic subtest flip: SUCCESS (1.032s)
[162.892626] Starting dynamic subtest: modeset
[164.238796] Dynamic subtest modeset: SUCCESS (1.401s)
[164.262505] Subtest basic: SUCCESS (2.437s)
[164.625273] [093/165] (862s left) kms_prop_blob (basic)
[164.867692] Starting subtest: basic
[164.890329] Subtest basic: SUCCESS (0.000s)
[164.984431] [094/165] (862s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[165.317055] Starting subtest: basic-busy-flip-before-cursor-atomic
[165.360770] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[165.454428] [095/165] (862s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[165.758951] Starting subtest: basic-busy-flip-before-cursor-legacy
[165.798920] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[165.897563] [096/165] (861s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[166.199091] Starting subtest: basic-flip-after-cursor-atomic
[167.289972] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.062s)
[167.643623] [097/165] (860s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[167.964652] Starting subtest: basic-flip-after-cursor-legacy
[169.022473] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.058s)
[169.380594] [098/165] (858s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[169.701946] Starting subtest: basic-flip-after-cursor-varying-size
[170.760803] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.074s)
[171.123689] [099/165] (856s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[171.422279] Starting subtest: basic-flip-before-cursor-atomic
[172.499545] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.058s)
[172.848698] [100/165] (855s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[173.164682] Starting subtest: basic-flip-before-cursor-legacy
[174.212786] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.047s)
[174.573696] [101/165] (853s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[174.905131] Starting subtest: basic-flip-before-cursor-varying-size
[175.979852] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.059s)
[176.322973] [102/165] (851s left) kms_dsc (dsc-basic)
[176.659584] Subtest dsc-basic: SKIP (0.000s)
[176.755095] [103/165] (851s left) kms_flip (basic-flip-vs-dpms)
[177.088703] Starting subtest: basic-flip-vs-dpms
[177.109068] Starting dynamic subtest: A-DP1
[178.319490] Dynamic subtest A-DP1: SUCCESS (1.242s)
[178.358827] Starting dynamic subtest: B-DP1
[179.465129] Dynamic subtest B-DP1: SUCCESS (1.144s)
[179.505083] Starting dynamic subtest: C-DP1
[180.596226] Dynamic subtest C-DP1: SUCCESS (1.128s)
[180.619091] Subtest basic-flip-vs-dpms: SUCCESS (3.523s)
[181.006772] [104/165] (847s left) kms_flip (basic-flip-vs-modeset)
[181.332309] Starting subtest: basic-flip-vs-modeset
[181.353854] Starting dynamic subtest: A-DP1
[182.611420] Dynamic subtest A-DP1: SUCCESS (1.277s)
[182.662570] Starting dynamic subtest: B-DP1
[183.754882] Dynamic subtest B-DP1: SUCCESS (1.127s)
[183.800583] Starting dynamic subtest: C-DP1
[184.868153] Dynamic subtest C-DP1: SUCCESS (1.141s)
[184.895927] Subtest basic-flip-vs-modeset: SUCCESS (3.555s)
[185.262408] [105/165] (843s left) kms_flip (basic-flip-vs-wf_vblank)
[185.591652] Starting subtest: basic-flip-vs-wf_vblank
[185.611604] Starting dynamic subtest: A-DP1
[187.042987] Dynamic subtest A-DP1: SUCCESS (1.465s)
[187.084611] Starting dynamic subtest: B-DP1
[188.434075] Dynamic subtest B-DP1: SUCCESS (1.394s)
[188.474232] Starting dynamic subtest: C-DP1
[189.831780] Dynamic subtest C-DP1: SUCCESS (1.393s)
[189.851878] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.262s)
[190.291110] [106/165] (838s left) kms_flip (basic-plain-flip)
[190.618153] Starting subtest: basic-plain-flip
[190.639101] Starting dynamic subtest: A-DP1
[191.793445] Dynamic subtest A-DP1: SUCCESS (1.184s)
[191.832761] Starting dynamic subtest: B-DP1
[192.907512] Dynamic subtest B-DP1: SUCCESS (1.111s)
[192.946036] Starting dynamic subtest: C-DP1
[194.035665] Dynamic subtest C-DP1: SUCCESS (1.112s)
[194.067129] Subtest basic-plain-flip: SUCCESS (3.418s)
[194.485891] [107/165] (834s left) kms_force_connector_basic (force-connector-state)
[194.781971] Subtest force-connector-state: SKIP (0.000s)
[194.906982] [108/165] (833s left) kms_force_connector_basic (force-edid)
[195.189267] Subtest force-edid: SKIP (0.000s)
[195.287424] [109/165] (833s left) kms_force_connector_basic (force-load-detect)
[195.542630] Subtest force-load-detect: SKIP (0.000s)
[195.641879] [110/165] (833s left) kms_force_connector_basic (prune-stale-modes)
[195.896458] Subtest prune-stale-modes: SKIP (0.000s)
[195.991466] [111/165] (833s left) kms_frontbuffer_tracking (basic)
[196.399421] Starting subtest: basic
[199.338606] Subtest basic: SUCCESS (3.017s)
[199.482373] [112/165] (829s left) kms_hdmi_inject (inject-audio)
[199.746191] Subtest inject-audio: SKIP (0.000s)
[199.846258] [113/165] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[200.162312] Starting subtest: compare-crc-sanitycheck-xr24
[200.190734] Starting dynamic subtest: pipe-A-DP-1
[200.615252] Dynamic subtest pipe-A-DP-1: SUCCESS (0.434s)
[200.669155] Starting dynamic subtest: pipe-B-DP-1
[201.207771] Dynamic subtest pipe-B-DP-1: SUCCESS (0.607s)
[201.248730] Starting dynamic subtest: pipe-C-DP-1
[201.848509] Dynamic subtest pipe-C-DP-1: SUCCESS (0.614s)
[201.869455] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.668s)
[202.198501] [114/165] (827s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[202.505178] Starting subtest: compare-crc-sanitycheck-nv12
[202.525674] Starting dynamic subtest: pipe-A-DP-1
[202.570421] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[202.610384] Starting dynamic subtest: pipe-B-DP-1
[202.652632] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[202.693287] Starting dynamic subtest: pipe-C-DP-1
[202.735416] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[202.780344] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[202.875969] [115/165] (826s left) kms_pipe_crc_basic (hang-read-crc)
[203.175037] Starting subtest: hang-read-crc
[203.196468] Starting dynamic subtest: pipe-A-DP-1
[204.688714] Dynamic subtest pipe-A-DP-1: SUCCESS (1.473s)
[204.736643] Starting dynamic subtest: pipe-B-DP-1
[206.272900] Dynamic subtest pipe-B-DP-1: SUCCESS (1.621s)
[206.323433] Starting dynamic subtest: pipe-C-DP-1
[207.899149] Dynamic subtest pipe-C-DP-1: SUCCESS (1.618s)
[207.920586] Subtest hang-read-crc: SUCCESS (4.726s)
[208.267086] [116/165] (821s left) kms_pipe_crc_basic (nonblocking-crc)
[208.571397] Starting subtest: nonblocking-crc
[208.592650] Starting dynamic subtest: pipe-A-DP-1
[209.133445] Dynamic subtest pipe-A-DP-1: SUCCESS (0.563s)
[209.174615] Starting dynamic subtest: pipe-B-DP-1
[209.885864] Dynamic subtest pipe-B-DP-1: SUCCESS (0.744s)
[209.925347] Starting dynamic subtest: pipe-C-DP-1
[210.655767] Dynamic subtest pipe-C-DP-1: SUCCESS (0.770s)
[210.677994] Subtest nonblocking-crc: SUCCESS (2.090s)
[211.027852] [117/165] (818s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[211.310493] Starting subtest: nonblocking-crc-frame-sequence
[211.330486] Starting dynamic subtest: pipe-A-DP-1
[211.926490] Dynamic subtest pipe-A-DP-1: SUCCESS (0.601s)
[211.970334] Starting dynamic subtest: pipe-B-DP-1
[212.664896] Dynamic subtest pipe-B-DP-1: SUCCESS (0.749s)
[212.716171] Starting dynamic subtest: pipe-C-DP-1
[213.432677] Dynamic subtest pipe-C-DP-1: SUCCESS (0.754s)
[213.456257] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.119s)
[213.800892] [118/165] (816s left) kms_pipe_crc_basic (read-crc)
[214.136790] Starting subtest: read-crc
[214.161924] Starting dynamic subtest: pipe-A-DP-1
[214.644832] Dynamic subtest pipe-A-DP-1: SUCCESS (0.496s)
[214.690143] Starting dynamic subtest: pipe-B-DP-1
[215.328965] Dynamic subtest pipe-B-DP-1: SUCCESS (0.694s)
[215.371159] Starting dynamic subtest: pipe-C-DP-1
[216.014802] Dynamic subtest pipe-C-DP-1: SUCCESS (0.703s)
[216.038355] Subtest read-crc: SUCCESS (1.908s)
[216.390621] [119/165] (813s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[216.695477] Starting subtest: read-crc-frame-sequence
[216.716083] Starting dynamic subtest: pipe-A-DP-1
[217.240344] Dynamic subtest pipe-A-DP-1: SUCCESS (0.521s)
[217.281731] Starting dynamic subtest: pipe-B-DP-1
[217.937029] Dynamic subtest pipe-B-DP-1: SUCCESS (0.693s)
[217.989893] Starting dynamic subtest: pipe-C-DP-1
[218.624954] Dynamic subtest pipe-C-DP-1: SUCCESS (0.697s)
[218.645491] Subtest read-crc-frame-sequence: SUCCESS (1.925s)
[218.983267] [120/165] (811s left) kms_pm_backlight (basic-brightness)
[219.318179] Subtest basic-brightness: SKIP (0.000s)
[219.416128] [121/165] (810s left) kms_pm_rpm (basic-pci-d3-state)
[221.310038] Starting subtest: basic-pci-d3-state
[221.964563] Subtest basic-pci-d3-state: SUCCESS (0.641s)
[222.185120] [122/165] (808s left) kms_pm_rpm (basic-rte)
[222.375202] Starting subtest: basic-rte
[224.688970] Subtest basic-rte: SUCCESS (2.296s)
[225.569608] [123/165] (804s left) kms_psr (psr_primary_page_flip)
[225.907558] Subtest psr_primary_page_flip: SKIP (0.000s)
[226.006953] [124/165] (804s left) kms_psr (psr_cursor_plane_move)
[226.327835] Subtest psr_cursor_plane_move: SKIP (0.000s)
[226.429085] [125/165] (804s left) kms_psr (psr_sprite_plane_onoff)
[226.753546] Subtest psr_sprite_plane_onoff: SKIP (0.000s)
[226.852261] [126/165] (803s left) kms_psr (psr_primary_mmap_gtt)
[227.178028] Subtest psr_primary_mmap_gtt: SKIP (0.000s)
[227.278673] [127/165] (803s left) kms_setmode (basic-clone-single-crtc)
[227.529350] Starting subtest: basic-clone-single-crtc
[227.595841] Subtest basic-clone-single-crtc: SKIP (0.000s)
[227.698712] [128/165] (803s left) i915_pm_rps (basic-api)
[227.943395] Starting subtest: basic-api
[227.964074] Subtest basic-api: SUCCESS (0.007s)
[228.083589] [129/165] (802s left) prime_self_import (basic-llseek-bad)
[228.303539] Starting subtest: basic-llseek-bad
[228.347391] Subtest basic-llseek-bad: SUCCESS (0.004s)
[228.448639] [130/165] (802s left) prime_self_import (basic-llseek-size)
[228.660791] Starting subtest: basic-llseek-size
[228.705910] Subtest basic-llseek-size: SUCCESS (0.006s)
[228.801829] [131/165] (802s left) prime_self_import (basic-with_fd_dup)
[229.018643] Starting subtest: basic-with_fd_dup
[229.062380] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[229.162439] [132/165] (802s left) prime_self_import (basic-with_one_bo)
[229.373736] Starting subtest: basic-with_one_bo
[229.418512] Subtest basic-with_one_bo: SUCCESS (0.007s)
[229.515804] [133/165] (801s left) prime_self_import (basic-with_one_bo_two_files)
[229.705132] Starting subtest: basic-with_one_bo_two_files
[229.747993] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[229.848204] [134/165] (801s left) prime_self_import (basic-with_two_bos)
[230.057703] Starting subtest: basic-with_two_bos
[230.102833] Subtest basic-with_two_bos: SUCCESS (0.006s)
[230.198570] [135/165] (801s left) prime_vgem (basic-fence-flip)
[230.444861] Starting subtest: basic-fence-flip
[231.025565] Subtest basic-fence-flip: SUCCESS (0.601s)
[231.152532] [136/165] (800s left) prime_vgem (basic-fence-mmap)
[231.408717] Starting subtest: basic-fence-mmap
[231.454494] Subtest basic-fence-mmap: SUCCESS (0.072s)
[231.577862] [137/165] (800s left) prime_vgem (basic-fence-read)
[231.831337] Starting subtest: basic-fence-read
[231.908953] Subtest basic-fence-read: SUCCESS (0.110s)
[232.035752] [138/165] (799s left) prime_vgem (basic-gtt)
[232.288131] Starting subtest: basic-gtt
[232.308950] Subtest basic-gtt: SUCCESS (0.035s)
[232.451490] [139/165] (799s left) prime_vgem (basic-read)
[232.721657] Starting subtest: basic-read
[232.748066] Subtest basic-read: SUCCESS (0.053s)
[232.900567] [140/165] (799s left) prime_vgem (basic-write)
[233.199379] Starting subtest: basic-write
[233.229434] Subtest basic-write: SUCCESS (0.065s)
[233.381960] [141/165] (798s left) vgem_basic (setversion)
[233.653759] Starting subtest: setversion
[233.702458] Subtest setversion: SUCCESS (0.000s)
[233.800116] [142/165] (798s left) vgem_basic (create)
[234.035903] Starting subtest: create
[234.059741] Subtest create: SUCCESS (0.000s)
[234.156251] [143/165] (798s left) vgem_basic (debugfs)
[234.370401] Starting subtest: debugfs
[234.392718] Subtest debugfs: SUCCESS (0.001s)
[234.490198] [144/165] (797s left) vgem_basic (dmabuf-export)
[234.727653] Starting subtest: dmabuf-export
[234.773633] Subtest dmabuf-export: SUCCESS (0.010s)
[234.879841] [145/165] (797s left) vgem_basic (dmabuf-fence)
[235.091185] Starting subtest: dmabuf-fence
[235.112273] Subtest dmabuf-fence: SUCCESS (0.002s)
[235.215690] [146/165] (797s left) vgem_basic (dmabuf-fence-before)
[235.456063] Starting subtest: dmabuf-fence-before
[235.478084] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[235.579974] [147/165] (797s left) vgem_basic (dmabuf-mmap)
[235.790904] Starting subtest: dmabuf-mmap
[235.812522] Subtest dmabuf-mmap: SUCCESS (0.042s)
[235.920756] [148/165] (796s left) vgem_basic (mmap)
[236.153189] Starting subtest: mmap
[236.175990] Subtest mmap: SUCCESS (0.027s)
[236.273219] [149/165] (796s left) vgem_basic (second-client)
[236.509400] Starting subtest: second-client
[236.552296] Subtest second-client: SUCCESS (0.033s)
[236.651500] [150/165] (796s left) vgem_basic (sysfs)
[236.889246] Starting subtest: sysfs
[236.911597] Subtest sysfs: SUCCESS (0.001s)
[237.015742] [151/165] (796s left) core_hotunplug (unbind-rebind)
[237.347788] Starting subtest: unbind-rebind
[241.252447] Subtest unbind-rebind: SUCCESS (3.911s)
[241.352207] [152/165] (791s left) vgem_basic (unload)
[241.550482] Starting subtest: unload
[270.731506] Subtest unload: SUCCESS (29.009s)
[270.831464] [153/165] (762s left) i915_module_load (reload)
[271.047321] Starting subtest: reload
[274.695307] Subtest reload: SUCCESS (3.648s)
[274.816972] [154/165] (758s left) gem_lmem_swapping (basic)
[275.140229] Subtest basic: SKIP (0.000s)
[275.236719] [155/165] (758s left) gem_lmem_swapping (parallel-random-engines)
[275.466191] Subtest parallel-random-engines: SKIP (0.000s)
[275.560248] [156/165] (758s left) gem_lmem_swapping (random-engines)
[275.814104] Subtest random-engines: SKIP (0.000s)
[275.909733] [157/165] (757s left) gem_lmem_swapping (verify-random)
[276.160434] Subtest verify-random: SKIP (0.000s)
[276.254653] [158/165] (757s left) i915_pm_rpm (module-reload)
[278.516085] Starting subtest: module-reload
[287.797456] Subtest module-reload: SUCCESS (9.307s)
[288.095229] [159/165] (745s left) i915_selftest (live)
[288.329803] Starting subtest: live
[288.351686] Starting dynamic subtest: sanitycheck
[289.819095] Dynamic subtest sanitycheck: SUCCESS (1.487s)
[289.855304] Starting dynamic subtest: uncore
[291.299588] Dynamic subtest uncore: SUCCESS (1.476s)
[291.347155] Starting dynamic subtest: workarounds
[293.169280] Dynamic subtest workarounds: SUCCESS (1.864s)
[293.216347] Starting dynamic subtest: gt_engines
[295.357293] Dynamic subtest gt_engines: SUCCESS (2.185s)
[295.399640] Starting dynamic subtest: gt_timelines
[317.230891] Dynamic subtest gt_timelines: SUCCESS (21.871s)
[317.277806] Starting dynamic subtest: gt_contexts
[319.045734] Dynamic subtest gt_contexts: SUCCESS (1.812s)
[319.083824] Starting dynamic subtest: gt_lrc
[323.650441] Dynamic subtest gt_lrc: SUCCESS (4.598s)
[323.687546] Starting dynamic subtest: gt_mocs
[325.339037] Dynamic subtest gt_mocs: SUCCESS (1.679s)
[325.381620] Starting dynamic subtest: gt_pm
[334.375290] Dynamic subtest gt_pm: SUCCESS (9.041s)
[334.410298] Starting dynamic subtest: gt_heartbeat
[349.389222] Dynamic subtest gt_heartbeat: FAIL (10.341s)
[349.428188] Starting dynamic subtest: gt_tlb
[349.448522] Dynamic subtest gt_tlb: SUCCESS (2.308s)
[349.485231] Starting dynamic subtest: requests
[354.958684] Dynamic subtest requests: SUCCESS (7.926s)
[355.004961] Starting dynamic subtest: migrate
[359.932523] Dynamic subtest migrate: SUCCESS (4.969s)
[359.979496] Starting dynamic subtest: active
[361.561239] Dynamic subtest active: SUCCESS (1.624s)
[361.596796] Starting dynamic subtest: objects
[366.125838] Dynamic subtest objects: SUCCESS (4.560s)
[366.174268] Starting dynamic subtest: mman
[375.053416] Dynamic subtest mman: SUCCESS (8.925s)
[375.088601] Starting dynamic subtest: dmabuf
[376.632433] Dynamic subtest dmabuf: SUCCESS (1.577s)
[376.667437] Starting dynamic subtest: vma
[378.117782] Dynamic subtest vma: SUCCESS (1.480s)
[378.164497] Starting dynamic subtest: coherency
[382.411991] Dynamic subtest coherency: SUCCESS (4.289s)
[382.460406] Starting dynamic subtest: gtt
[391.681867] Dynamic subtest gtt: SUCCESS (9.268s)
[391.719496] Starting dynamic subtest: gem
[394.537259] Dynamic subtest gem: SUCCESS (2.845s)
[394.589566] Starting dynamic subtest: evict
[400.718605] Dynamic subtest evict: SUCCESS (6.184s)
[400.756704] Starting dynamic subtest: hugepages
[408.733557] Dynamic subtest hugepages: SUCCESS (8.011s)
[408.771634] Starting dynamic subtest: gem_contexts
[433.623371] Dynamic subtest gem_contexts: SUCCESS (24.714s)
[433.657926] Starting dynamic subtest: client
[435.136583] Dynamic subtest client: SUCCESS (1.684s)
[435.171945] Starting dynamic subtest: gem_migrate
[436.638069] Dynamic subtest gem_migrate: SUCCESS (1.497s)
[436.673864] Starting dynamic subtest: reset
[449.447327] Dynamic subtest reset: SUCCESS (12.808s)
[449.495176] Starting dynamic subtest: memory_region
[451.118476] Dynamic subtest memory_region: SUCCESS (1.666s)
[451.154295] Starting dynamic subtest: hangcheck
[476.014914] Dynamic subtest hangcheck: SUCCESS (24.895s)
[476.052217] Starting dynamic subtest: execlists
[488.537945] Dynamic subtest execlists: SUCCESS (12.513s)
[488.594726] Starting dynamic subtest: ring_submission
[490.205969] Dynamic subtest ring_submission: SUCCESS (1.670s)
[490.243162] Starting dynamic subtest: perf
[491.982323] Dynamic subtest perf: SUCCESS (1.773s)
[492.031800] Starting dynamic subtest: slpc
[493.492420] Dynamic subtest slpc: SUCCESS (1.508s)
[493.542895] Starting dynamic subtest: guc
[495.164499] Dynamic subtest guc: SUCCESS (1.667s)
[495.215950] Starting dynamic subtest: guc_multi_lrc
[496.855254] Dynamic subtest guc_multi_lrc: SUCCESS (1.687s)
[496.890731] Starting dynamic subtest: guc_hang
[498.371900] Dynamic subtest guc_hang: SUCCESS (1.513s)
[498.410949] Starting dynamic subtest: late_gt_pm
[500.113777] Dynamic subtest late_gt_pm: SUCCESS (1.738s)
[500.137080] Subtest live: FAIL (211.785s)
[500.230107] [160/165] (533s left) dmabuf (all-tests)
[500.456301] Starting subtest: all-tests
[500.475598] Starting dynamic subtest: sanitycheck
[500.537135] Dynamic subtest sanitycheck: SUCCESS (0.081s)
[500.574126] Starting dynamic subtest: dma_fence
[500.727821] Dynamic subtest dma_fence: SUCCESS (0.189s)
[500.763540] Starting dynamic subtest: dma_fence_chain
[524.227093] Dynamic subtest dma_fence_chain: SUCCESS (23.498s)
[524.268292] Starting dynamic subtest: dma_fence_unwrap
[524.323400] Dynamic subtest dma_fence_unwrap: SUCCESS (0.072s)
[524.362519] Starting dynamic subtest: dma_resv
[524.385409] Dynamic subtest dma_resv: SUCCESS (0.069s)
[524.408493] Subtest all-tests: SUCCESS (23.923s)
[524.511796] [161/165] (509s left) i915_suspend (basic-s2idle-without-i915)
[524.734466] Starting subtest: basic-s2idle-without-i915
[544.910837] Subtest basic-s2idle-without-i915: SUCCESS (5.192s)
[548.264764] [162/165] (504s left) i915_suspend (basic-s3-without-i915)
[548.489854] Starting subtest: basic-s3-without-i915
[566.610741] Subtest basic-s3-without-i915: SUCCESS (3.241s)
[570.121096] [163/165] (500s left) gem_exec_suspend (basic-s0)
[570.402239] Starting subtest: basic-S0
[570.425689] Starting dynamic subtest: smem
[591.293257] Dynamic subtest smem: SUCCESS (5.707s)
[591.314301] Subtest basic-S0: SUCCESS (5.709s)
[595.714234] [164/165] (494s left) gem_exec_suspend (basic-s3)
[595.953328] Starting subtest: basic-S3
[595.972826] Starting dynamic subtest: smem
[616.609475] Dynamic subtest smem: SUCCESS (5.656s)
[616.631514] Subtest basic-S3: SUCCESS (5.659s)
[620.999474] [165/165] (488s left) kms_pipe_crc_basic (suspend-read-crc)
[621.303471] Starting subtest: suspend-read-crc
[621.323276] Starting dynamic subtest: pipe-A-DP-1
[639.485369] Dynamic subtest pipe-A-DP-1: SUCCESS (2.326s)
[639.530137] Starting dynamic subtest: pipe-B-DP-1
[657.520275] Dynamic subtest pipe-B-DP-1: SUCCESS (2.588s)
[657.568847] Starting dynamic subtest: pipe-C-DP-1
[676.494347] Dynamic subtest pipe-C-DP-1: SUCCESS (2.583s)
[676.516092] Subtest suspend-read-crc: SUCCESS (7.513s)
[680.914432] Closing watchdogs
Done.