[41.643834] Initializing watchdogs
[41.643978]   /dev/watchdog0
[41.654308] [001/164] (960s left) i915_module_load (load)
[41.758611] Starting subtest: load
[44.465206] Subtest load: SUCCESS (2.707s)
[44.491823] [002/164] (957s left) core_auth (basic-auth)
[44.599546] Starting subtest: basic-auth
[44.602160] Subtest basic-auth: SUCCESS (0.001s)
[44.687958] [003/164] (957s left) debugfs_test (read_all_entries)
[44.789245] Starting subtest: read_all_entries
[44.871037] Subtest read_all_entries: SUCCESS (0.080s)
[44.982079] [004/164] (957s left) debugfs_test (basic-hwmon)
[45.079661] Starting subtest: basic-hwmon
[45.081959] Subtest basic-hwmon: SKIP (0.000s)
[45.179620] [005/164] (957s left) fbdev (eof)
[45.284101] Starting subtest: eof
[45.285317] Subtest eof: SUCCESS (0.001s)
[45.354524] [006/164] (956s left) fbdev (info)
[45.462704] Starting subtest: info
[45.465691] Subtest info: SUCCESS (0.000s)
[45.532163] [007/164] (956s left) fbdev (nullptr)
[45.633293] Starting subtest: nullptr
[45.634461] Subtest nullptr: SUCCESS (0.000s)
[45.702308] [008/164] (956s left) fbdev (read)
[45.800357] Starting subtest: read
[52.911372] Subtest read: SUCCESS (7.076s)
[52.959463] [009/164] (949s left) fbdev (write)
[53.057216] Starting subtest: write
[56.841752] Subtest write: SUCCESS (3.748s)
[56.877932] [010/164] (945s left) gem_basic (bad-close)
[56.970498] Starting subtest: bad-close
[56.972810] Subtest bad-close: SUCCESS (0.000s)
[57.045441] [011/164] (945s left) gem_basic (create-close)
[57.134745] Starting subtest: create-close
[57.136888] Subtest create-close: SUCCESS (0.000s)
[57.231407] [012/164] (945s left) gem_basic (create-fd-close)
[57.323454] Starting subtest: create-fd-close
[57.325555] Subtest create-fd-close: SUCCESS (0.000s)
[57.414766] [013/164] (945s left) gem_busy (busy@all-engines)
[57.524485] Starting subtest: busy
[57.525661] Starting dynamic subtest: all-engines
[57.595629] Dynamic subtest all-engines: SUCCESS (0.071s)
[57.596756] Subtest busy: SUCCESS (0.071s)
[58.001894] [014/164] (944s left) gem_close_race (basic-process)
[58.120146] Starting subtest: basic-process
[58.192953] Subtest basic-process: SUCCESS (0.074s)
[58.401970] [015/164] (944s left) gem_close_race (basic-threads)
[58.514203] Starting subtest: basic-threads
[59.680360] Subtest basic-threads: SUCCESS (1.166s)
[59.919577] [016/164] (942s left) gem_ctx_create (basic)
[60.031502] Starting subtest: basic
[60.032679] Subtest basic: SUCCESS (0.000s)
[60.239064] [017/164] (942s left) gem_ctx_create (basic-files)
[60.347841] Starting subtest: basic-files
[62.377159] Subtest basic-files: SUCCESS (2.029s)
[62.604172] [018/164] (939s left) gem_ctx_exec (basic)
[62.698992] Starting subtest: basic
[62.703575] Subtest basic: SUCCESS (0.005s)
[62.823722] [019/164] (939s left) gem_exec_basic (basic)
[62.926900] Starting subtest: basic
[62.928167] Starting dynamic subtest: rcs0-smem
[62.929244] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[62.940766] Starting dynamic subtest: bcs0-smem
[62.941977] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[62.952457] Starting dynamic subtest: vcs0-smem
[62.953770] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[62.964255] Starting dynamic subtest: vecs0-smem
[62.965504] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[62.966652] Subtest basic: SUCCESS (0.006s)
[63.139851] [020/164] (939s left) gem_exec_create (basic)
[63.250286] Starting subtest: basic
[63.251402] Starting dynamic subtest: smem
[65.291872] Dynamic subtest smem: SUCCESS (2.042s)
[65.292995] Subtest basic: SUCCESS (2.042s)
[65.540158] [021/164] (937s left) gem_exec_fence (basic-busy)
[65.802100] Starting subtest: basic-busy
[65.803496] Starting dynamic subtest: rcs0
[65.827842] Dynamic subtest rcs0: SUCCESS (0.033s)
[65.842340] Starting dynamic subtest: bcs0
[65.854351] Dynamic subtest bcs0: SUCCESS (0.027s)
[65.865404] Starting dynamic subtest: vcs0
[65.879463] Dynamic subtest vcs0: SUCCESS (0.025s)
[65.890683] Starting dynamic subtest: vecs0
[65.912079] Dynamic subtest vecs0: SUCCESS (0.032s)
[65.913453] Subtest basic-busy: SUCCESS (0.119s)
[66.530521] [022/164] (936s left) gem_exec_fence (basic-wait)
[66.788255] Starting subtest: basic-wait
[66.789740] Starting dynamic subtest: rcs0
[66.807098] Dynamic subtest rcs0: SUCCESS (0.028s)
[66.822883] Starting dynamic subtest: bcs0
[66.825498] Dynamic subtest bcs0: SUCCESS (0.018s)
[66.837864] Starting dynamic subtest: vcs0
[66.845442] Dynamic subtest vcs0: SUCCESS (0.020s)
[66.857187] Starting dynamic subtest: vecs0
[66.864974] Dynamic subtest vecs0: SUCCESS (0.019s)
[66.866306] Subtest basic-wait: SUCCESS (0.087s)
[67.414806] [023/164] (935s left) gem_exec_fence (basic-await)
[67.672458] Starting subtest: basic-await
[67.673582] Starting dynamic subtest: rcs0
[67.766666] Dynamic subtest rcs0: SUCCESS (0.101s)
[67.786289] Starting dynamic subtest: bcs0
[67.873969] Dynamic subtest bcs0: SUCCESS (0.107s)
[67.902381] Starting dynamic subtest: vcs0
[68.016287] Dynamic subtest vcs0: SUCCESS (0.133s)
[68.042674] Starting dynamic subtest: vecs0
[68.143756] Dynamic subtest vecs0: SUCCESS (0.134s)
[68.146162] Subtest basic-await: SUCCESS (0.478s)
[68.732360] [024/164] (934s left) gem_exec_fence (nb-await)
[68.995429] Starting subtest: nb-await
[68.996544] Starting dynamic subtest: rcs0
[69.051542] Dynamic subtest rcs0: SUCCESS (0.063s)
[69.072712] Starting dynamic subtest: bcs0
[69.124710] Dynamic subtest bcs0: SUCCESS (0.072s)
[69.144164] Starting dynamic subtest: vcs0
[69.195552] Dynamic subtest vcs0: SUCCESS (0.070s)
[69.223669] Starting dynamic subtest: vecs0
[69.271639] Dynamic subtest vecs0: SUCCESS (0.075s)
[69.273572] Subtest nb-await: SUCCESS (0.283s)
[69.831195] [025/164] (932s left) gem_exec_gttfill (basic)
[69.940902] Starting subtest: basic
[76.333573] Subtest basic: SUCCESS (6.392s)
[76.536741] [026/164] (926s left) gem_exec_parallel (engines)
[76.714474] Starting subtest: engines
[76.715658] Starting dynamic subtest: basic
[79.367228] Dynamic subtest basic: SUCCESS (2.629s)
[79.380867] Starting dynamic subtest: contexts
[83.418848] Dynamic subtest contexts: SUCCESS (4.066s)
[83.433310] Starting dynamic subtest: fds
[94.385276] Dynamic subtest fds: SUCCESS (10.975s)
[94.406485] Starting dynamic subtest: userptr
[96.949479] Dynamic subtest userptr: SUCCESS (2.539s)
[96.951500] Subtest engines: SUCCESS (20.211s)
[97.162750] [027/164] (905s left) gem_exec_store (basic)
[97.273071] Starting subtest: basic
[97.336511] Subtest basic: SUCCESS (0.063s)
[97.546293] [028/164] (905s left) gem_flink_basic (bad-flink)
[97.640133] Starting subtest: bad-flink
[97.641461] Subtest bad-flink: SUCCESS (0.000s)
[97.728252] [029/164] (905s left) gem_flink_basic (bad-open)
[97.818522] Starting subtest: bad-open
[97.819877] Subtest bad-open: SUCCESS (0.000s)
[97.913718] [030/164] (905s left) gem_flink_basic (basic)
[98.005591] Starting subtest: basic
[98.006746] Subtest basic: SUCCESS (0.000s)
[98.112822] [031/164] (904s left) gem_flink_basic (double-flink)
[98.205987] Starting subtest: double-flink
[98.207294] Subtest double-flink: SUCCESS (0.000s)
[98.317054] [032/164] (904s left) gem_flink_basic (flink-lifetime)
[98.410563] Starting subtest: flink-lifetime
[98.413241] Subtest flink-lifetime: SUCCESS (0.002s)
[98.516776] [033/164] (904s left) gem_huc_copy (huc-copy)
[98.615580] Starting subtest: huc-copy
[98.617807] Subtest huc-copy: SKIP (0.000s)
[98.730161] [034/164] (904s left) gem_linear_blits (basic)
[98.982171] Starting subtest: basic
[98.993814] Subtest basic: SUCCESS (0.012s)
[99.132238] [035/164] (903s left) gem_mmap (basic)
[99.290432] Starting subtest: basic
[99.295368] Subtest basic: SUCCESS (0.001s)
[99.396110] [036/164] (903s left) gem_mmap_gtt (basic)
[99.491366] Starting subtest: basic
[99.492534] Subtest basic: SUCCESS (0.001s)
[99.573034] [037/164] (903s left) gem_render_linear_blits (basic)
[99.671372] Starting subtest: basic
[99.722702] Subtest basic: SUCCESS (0.051s)
[99.772818] [038/164] (903s left) gem_render_tiled_blits (basic)
[99.869325] Starting subtest: basic
[99.929145] Subtest basic: SUCCESS (0.060s)
[99.989913] [039/164] (903s left) gem_ringfill (basic-all)
[100.227577] Starting subtest: basic-all
[101.464392] Subtest basic-all: SUCCESS (1.228s)
[101.550365] [040/164] (901s left) gem_softpin (allocator-basic)
[101.648437] Starting subtest: allocator-basic
[101.828845] Subtest allocator-basic: SUCCESS (0.172s)
[101.951216] [041/164] (901s left) gem_softpin (allocator-basic-reserve)
[102.048044] Starting subtest: allocator-basic-reserve
[102.226665] Subtest allocator-basic-reserve: SUCCESS (0.170s)
[102.348605] [042/164] (900s left) gem_softpin (safe-alignment)
[102.448265] Starting subtest: safe-alignment
[102.467690] Subtest safe-alignment: SUCCESS (0.019s)
[102.585310] [043/164] (900s left) gem_sync (basic-all)
[102.697225] Starting subtest: basic-all
[104.968945] Subtest basic-all: SUCCESS (2.272s)
[105.185859] [044/164] (898s left) gem_sync (basic-each)
[105.296944] Starting subtest: basic-each
[107.555577] Subtest basic-each: SUCCESS (2.258s)
[107.789084] [045/164] (895s left) gem_tiled_blits (basic)
[108.018972] Starting subtest: basic
[108.048989] Subtest basic: SUCCESS (0.030s)
[108.196432] [046/164] (895s left) gem_tiled_fence_blits (basic)
[108.505247] Starting subtest: basic
[108.533882] Subtest basic: SUCCESS (0.028s)
[108.672481] [047/164] (894s left) gem_tiled_pread_basic
[109.192453] [048/164] (894s left) gem_wait (busy@all-engines)
[109.312506] Starting subtest: busy
[109.313683] Starting dynamic subtest: all-engines
[109.848749] Dynamic subtest all-engines: SUCCESS (0.536s)
[109.849854] Subtest busy: SUCCESS (0.536s)
[110.109560] [049/164] (893s left) gem_wait (wait@all-engines)
[110.223917] Starting subtest: wait
[110.225140] Starting dynamic subtest: all-engines
[111.260519] Dynamic subtest all-engines: SUCCESS (1.036s)
[111.262517] Subtest wait: SUCCESS (1.036s)
[111.508079] [050/164] (892s left) i915_getparams_basic (basic-eu-total)
[111.600258] Starting subtest: basic-eu-total
[111.602529] Subtest basic-eu-total: SUCCESS (0.000s)
[111.694041] [051/164] (891s left) i915_getparams_basic (basic-subslice-total)
[111.788536] Starting subtest: basic-subslice-total
[111.790744] Subtest basic-subslice-total: SUCCESS (0.000s)
[111.875298] [052/164] (891s left) i915_hangman (error-state-basic)
[111.983985] Starting subtest: error-state-basic
[111.996211] Subtest error-state-basic: SUCCESS (0.013s)
[112.093587] [053/164] (891s left) i915_pciid
[112.278314] [054/164] (891s left) kms_addfb_basic (addfb25-4-tiled)
[112.410720] Starting subtest: addfb25-4-tiled
[112.411853] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[112.506326] [055/164] (891s left) kms_addfb_basic (addfb25-bad-modifier)
[112.600392] Starting subtest: addfb25-bad-modifier
[112.601419] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[112.745830] [056/164] (890s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[112.839837] Starting subtest: addfb25-framebuffer-vs-set-tiling
[112.840917] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[112.979609] [057/164] (890s left) kms_addfb_basic (addfb25-modifier-no-flag)
[113.073062] Starting subtest: addfb25-modifier-no-flag
[113.074117] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[113.189437] [058/164] (890s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[113.284594] Starting subtest: addfb25-x-tiled-legacy
[113.285703] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[113.412346] [059/164] (890s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[113.505680] Starting subtest: addfb25-x-tiled-mismatch-legacy
[113.506809] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[113.628924] [060/164] (890s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[113.750733] Starting subtest: addfb25-yf-tiled-legacy
[113.751861] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[113.865078] [061/164] (889s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[113.987753] Starting subtest: addfb25-y-tiled-legacy
[113.988924] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[114.097565] [062/164] (889s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[114.220115] Starting subtest: addfb25-y-tiled-small-legacy
[114.221210] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[114.330726] [063/164] (889s left) kms_addfb_basic (bad-pitch-0)
[114.423183] Starting subtest: bad-pitch-0
[114.424326] Subtest bad-pitch-0: SUCCESS (0.000s)
[114.563660] [064/164] (889s left) kms_addfb_basic (bad-pitch-1024)
[114.657798] Starting subtest: bad-pitch-1024
[114.658854] Subtest bad-pitch-1024: SUCCESS (0.000s)
[114.780209] [065/164] (889s left) kms_addfb_basic (bad-pitch-128)
[114.873014] Starting subtest: bad-pitch-128
[114.874187] Subtest bad-pitch-128: SUCCESS (0.000s)
[115.014920] [066/164] (888s left) kms_addfb_basic (bad-pitch-256)
[115.105500] Starting subtest: bad-pitch-256
[115.106598] Subtest bad-pitch-256: SUCCESS (0.000s)
[115.242058] [067/164] (888s left) kms_addfb_basic (bad-pitch-32)
[115.334715] Starting subtest: bad-pitch-32
[115.335828] Subtest bad-pitch-32: SUCCESS (0.000s)
[115.463181] [068/164] (888s left) kms_addfb_basic (bad-pitch-63)
[115.554520] Starting subtest: bad-pitch-63
[115.555727] Subtest bad-pitch-63: SUCCESS (0.000s)
[115.699641] [069/164] (888s left) kms_addfb_basic (bad-pitch-65536)
[115.792512] Starting subtest: bad-pitch-65536
[115.793689] Subtest bad-pitch-65536: SUCCESS (0.000s)
[115.932204] [070/164] (888s left) kms_addfb_basic (bad-pitch-999)
[116.024736] Starting subtest: bad-pitch-999
[116.026076] Subtest bad-pitch-999: SUCCESS (0.000s)
[116.164902] [071/164] (887s left) kms_addfb_basic (basic)
[116.256551] Starting subtest: basic
[116.257679] Subtest basic: SUCCESS (0.000s)
[116.382289] [072/164] (887s left) kms_addfb_basic (basic-x-tiled-legacy)
[116.476899] Starting subtest: basic-x-tiled-legacy
[116.478088] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[116.616180] [073/164] (887s left) kms_addfb_basic (basic-y-tiled-legacy)
[116.708854] Starting subtest: basic-y-tiled-legacy
[116.710073] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[116.850783] [074/164] (887s left) kms_addfb_basic (bo-too-small)
[116.970608] Starting subtest: bo-too-small
[116.971816] Subtest bo-too-small: SUCCESS (0.000s)
[117.084350] [075/164] (887s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[117.206082] Starting subtest: bo-too-small-due-to-tiling
[117.207210] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[117.298071] [076/164] (886s left) kms_addfb_basic (clobberred-modifier)
[117.390604] Starting subtest: clobberred-modifier
[117.391710] Subtest clobberred-modifier: SUCCESS (0.000s)
[117.517103] [077/164] (886s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[117.610960] Starting subtest: framebuffer-vs-set-tiling
[117.612113] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[117.731612] [078/164] (886s left) kms_addfb_basic (invalid-get-prop)
[117.825792] Starting subtest: invalid-get-prop
[117.826926] Subtest invalid-get-prop: SUCCESS (0.000s)
[117.967570] [079/164] (886s left) kms_addfb_basic (invalid-get-prop-any)
[118.061556] Starting subtest: invalid-get-prop-any
[118.062791] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[118.203753] [080/164] (886s left) kms_addfb_basic (invalid-set-prop)
[118.297352] Starting subtest: invalid-set-prop
[118.298478] Subtest invalid-set-prop: SUCCESS (0.000s)
[118.434165] [081/164] (886s left) kms_addfb_basic (invalid-set-prop-any)
[118.525095] Starting subtest: invalid-set-prop-any
[118.526131] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[118.650211] [082/164] (885s left) kms_addfb_basic (no-handle)
[118.744231] Starting subtest: no-handle
[118.745312] Subtest no-handle: SUCCESS (0.000s)
[118.867343] [083/164] (885s left) kms_addfb_basic (size-max)
[118.985869] Starting subtest: size-max
[118.987083] Subtest size-max: SUCCESS (0.000s)
[119.109196] [084/164] (885s left) kms_addfb_basic (small-bo)
[119.233078] Starting subtest: small-bo
[119.234176] Subtest small-bo: SUCCESS (0.000s)
[119.329271] [085/164] (885s left) kms_addfb_basic (tile-pitch-mismatch)
[119.423969] Starting subtest: tile-pitch-mismatch
[119.425083] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[119.549894] [086/164] (885s left) kms_addfb_basic (too-high)
[119.672293] Starting subtest: too-high
[119.673413] Subtest too-high: SUCCESS (0.001s)
[119.785319] [087/164] (884s left) kms_addfb_basic (too-wide)
[119.908507] Starting subtest: too-wide
[119.909668] Subtest too-wide: SUCCESS (0.000s)
[120.019549] [088/164] (884s left) kms_addfb_basic (unused-handle)
[120.112575] Starting subtest: unused-handle
[120.113793] Subtest unused-handle: SUCCESS (0.000s)
[120.251518] [089/164] (884s left) kms_addfb_basic (unused-modifier)
[120.343546] Starting subtest: unused-modifier
[120.344892] Subtest unused-modifier: SUCCESS (0.000s)
[120.464327] [090/164] (884s left) kms_addfb_basic (unused-offsets)
[120.557124] Starting subtest: unused-offsets
[120.558235] Subtest unused-offsets: SUCCESS (0.000s)
[120.687389] [091/164] (884s left) kms_addfb_basic (unused-pitches)
[120.782116] Starting subtest: unused-pitches
[120.783163] Subtest unused-pitches: SUCCESS (0.000s)
[120.920119] [092/164] (883s left) kms_busy (basic)
[121.049247] Starting subtest: basic
[121.051757] Starting dynamic subtest: flip
[122.051596] Dynamic subtest flip: SUCCESS (1.000s)
[122.068902] Starting dynamic subtest: modeset
[125.886222] Dynamic subtest modeset: SUCCESS (3.833s)
[125.887350] Subtest basic: SUCCESS (4.837s)
[126.847765] [093/164] (877s left) kms_prop_blob (basic)
[126.947591] Starting subtest: basic
[126.948669] Subtest basic: SUCCESS (0.000s)
[127.028904] [094/164] (877s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[127.150278] Starting subtest: basic-busy-flip-before-cursor-atomic
[127.152338] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[127.245629] [095/164] (877s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[127.368624] Starting subtest: basic-busy-flip-before-cursor-legacy
[127.370725] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[127.456525] [096/164] (877s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[127.575137] Starting subtest: basic-flip-after-cursor-atomic
[128.873241] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.299s)
[129.850976] [097/164] (875s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[129.970452] Starting subtest: basic-flip-after-cursor-legacy
[131.278143] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.307s)
[132.221272] [098/164] (872s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[132.342681] Starting subtest: basic-flip-after-cursor-varying-size
[133.612213] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.271s)
[134.586400] [099/164] (870s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[134.708372] Starting subtest: basic-flip-before-cursor-atomic
[136.015032] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.307s)
[136.952576] [100/164] (868s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[137.073109] Starting subtest: basic-flip-before-cursor-legacy
[138.376828] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.303s)
[139.323863] [101/164] (865s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[139.445246] Starting subtest: basic-flip-before-cursor-varying-size
[140.750943] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.306s)
[141.690272] [102/164] (863s left) kms_flip (basic-flip-vs-dpms)
[141.793214] Starting subtest: basic-flip-vs-dpms
[141.795566] Starting dynamic subtest: A-eDP1
[144.687224] Dynamic subtest A-eDP1: SUCCESS (2.891s)
[144.705586] Starting dynamic subtest: B-eDP1
[147.054116] Dynamic subtest B-eDP1: SUCCESS (2.366s)
[147.071534] Starting dynamic subtest: C-eDP1
[149.427583] Dynamic subtest C-eDP1: SUCCESS (2.371s)
[149.432311] Subtest basic-flip-vs-dpms: SUCCESS (7.640s)
[150.337642] [103/164] (854s left) kms_flip (basic-flip-vs-modeset)
[150.437858] Starting subtest: basic-flip-vs-modeset
[150.441326] Starting dynamic subtest: A-eDP1
[153.321684] Dynamic subtest A-eDP1: SUCCESS (2.879s)
[153.338705] Starting dynamic subtest: B-eDP1
[155.697433] Dynamic subtest B-eDP1: SUCCESS (2.372s)
[155.713361] Starting dynamic subtest: C-eDP1
[158.068120] Dynamic subtest C-eDP1: SUCCESS (2.369s)
[158.069299] Subtest basic-flip-vs-modeset: SUCCESS (7.629s)
[159.036030] [104/164] (846s left) kms_flip (basic-flip-vs-wf_vblank)
[159.138433] Starting subtest: basic-flip-vs-wf_vblank
[159.141237] Starting dynamic subtest: A-eDP1
[161.494745] Dynamic subtest A-eDP1: SUCCESS (2.352s)
[161.513781] Starting dynamic subtest: B-eDP1
[163.721964] Dynamic subtest B-eDP1: SUCCESS (2.225s)
[163.740766] Starting dynamic subtest: C-eDP1
[165.955365] Dynamic subtest C-eDP1: SUCCESS (2.231s)
[165.959243] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.822s)
[167.290397] [105/164] (837s left) kms_flip (basic-plain-flip)
[167.392372] Starting subtest: basic-plain-flip
[167.396370] Starting dynamic subtest: A-eDP1
[169.468454] Dynamic subtest A-eDP1: SUCCESS (2.072s)
[169.491047] Starting dynamic subtest: B-eDP1
[171.432253] Dynamic subtest B-eDP1: SUCCESS (1.961s)
[171.450854] Starting dynamic subtest: C-eDP1
[173.420408] Dynamic subtest C-eDP1: SUCCESS (1.986s)
[173.425230] Subtest basic-plain-flip: SUCCESS (6.034s)
[174.723180] [106/164] (830s left) kms_force_connector_basic (force-connector-state)
[174.820503] Starting subtest: force-connector-state
[175.434736] Subtest force-connector-state: SUCCESS (0.614s)
[176.488650] [107/164] (828s left) kms_force_connector_basic (force-edid)
[176.584962] Starting subtest: force-edid
[176.855456] Subtest force-edid: SUCCESS (0.265s)
[176.982800] [108/164] (828s left) kms_force_connector_basic (force-load-detect)
[177.079150] Starting subtest: force-load-detect
[177.081422] Subtest force-load-detect: SKIP (0.000s)
[177.182501] [109/164] (828s left) kms_force_connector_basic (prune-stale-modes)
[177.279577] Starting subtest: prune-stale-modes
[177.599684] Subtest prune-stale-modes: SUCCESS (0.319s)
[177.735836] [110/164] (827s left) kms_frontbuffer_tracking (basic)
[177.864927] Starting subtest: basic
[181.218280] Subtest basic: SUCCESS (3.353s)
[181.360101] [111/164] (823s left) kms_hdmi_inject (inject-audio)
[181.773780] Starting subtest: inject-audio
[182.099223] Subtest inject-audio: SUCCESS (0.324s)
[182.705677] [112/164] (822s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[182.829253] Starting subtest: compare-crc-sanitycheck-xr24
[182.830365] Starting dynamic subtest: pipe-A-eDP-1
[183.524338] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.688s)
[183.542054] Starting dynamic subtest: pipe-B-eDP-1
[184.917236] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.395s)
[184.938417] Starting dynamic subtest: pipe-C-eDP-1
[186.304407] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.379s)
[186.305864] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.473s)
[187.259229] [113/164] (818s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[187.381275] Starting subtest: compare-crc-sanitycheck-nv12
[187.382346] Starting dynamic subtest: pipe-A-eDP-1
[188.319284] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.937s)
[188.342458] Starting dynamic subtest: pipe-B-eDP-1
[189.741581] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.414s)
[189.774766] Starting dynamic subtest: pipe-C-eDP-1
[191.109901] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.366s)
[191.111240] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.731s)
[192.029477] [114/164] (813s left) kms_pipe_crc_basic (hang-read-crc)
[192.152751] Starting subtest: hang-read-crc
[192.153950] Starting dynamic subtest: pipe-A-eDP-1
[194.534792] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.378s)
[194.556952] Starting dynamic subtest: pipe-B-eDP-1
[197.563718] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.025s)
[197.583716] Starting dynamic subtest: pipe-C-eDP-1
[200.573468] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.006s)
[200.575279] Subtest hang-read-crc: SUCCESS (8.423s)
[201.531249] [115/164] (803s left) kms_pipe_crc_basic (nonblocking-crc)
[201.652732] Starting subtest: nonblocking-crc
[201.655442] Starting dynamic subtest: pipe-A-eDP-1
[202.544477] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.886s)
[202.580378] Starting dynamic subtest: pipe-B-eDP-1
[204.127567] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.577s)
[204.160340] Starting dynamic subtest: pipe-C-eDP-1
[205.687967] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.552s)
[205.689736] Subtest nonblocking-crc: SUCCESS (4.034s)
[206.652600] [116/164] (798s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[206.774484] Starting subtest: nonblocking-crc-frame-sequence
[206.777336] Starting dynamic subtest: pipe-A-eDP-1
[207.630588] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.852s)
[207.649824] Starting dynamic subtest: pipe-B-eDP-1
[209.214705] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.580s)
[209.242459] Starting dynamic subtest: pipe-C-eDP-1
[210.759613] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.539s)
[210.761395] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.986s)
[211.708240] [117/164] (793s left) kms_pipe_crc_basic (read-crc)
[211.828472] Starting subtest: read-crc
[211.831734] Starting dynamic subtest: pipe-A-eDP-1
[212.654596] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.823s)
[212.685246] Starting dynamic subtest: pipe-B-eDP-1
[214.166466] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.503s)
[214.186041] Starting dynamic subtest: pipe-C-eDP-1
[215.662744] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.493s)
[215.663966] Subtest read-crc: SUCCESS (3.834s)
[216.635640] [118/164] (788s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[216.757323] Starting subtest: read-crc-frame-sequence
[216.760125] Starting dynamic subtest: pipe-A-eDP-1
[217.579573] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.819s)
[217.598674] Starting dynamic subtest: pipe-B-eDP-1
[219.152549] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.567s)
[219.181898] Starting dynamic subtest: pipe-C-eDP-1
[220.650510] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.492s)
[220.652546] Subtest read-crc-frame-sequence: SUCCESS (3.894s)
[221.561182] [119/164] (784s left) kms_pm_backlight (basic-brightness)
[221.685040] Starting subtest: basic-brightness
[221.797080] Starting dynamic subtest: eDP-1
[222.374034] Dynamic subtest eDP-1: SUCCESS (0.586s)
[222.375132] Subtest basic-brightness: SUCCESS (0.690s)
[223.291208] [120/164] (782s left) kms_psr (primary_page_flip)
[223.416147] Starting subtest: primary_page_flip
[227.222478] Subtest primary_page_flip: SUCCESS (3.805s)
[228.156042] [121/164] (777s left) kms_psr (cursor_plane_move)
[228.281575] Starting subtest: cursor_plane_move
[232.064947] Subtest cursor_plane_move: SUCCESS (3.783s)
[233.019873] [122/164] (772s left) kms_psr (sprite_plane_onoff)
[233.145693] Starting subtest: sprite_plane_onoff
[237.046532] Subtest sprite_plane_onoff: SUCCESS (3.899s)
[237.974955] [123/164] (767s left) kms_psr (primary_mmap_gtt)
[238.098863] Starting subtest: primary_mmap_gtt
[242.024319] Subtest primary_mmap_gtt: SUCCESS (3.925s)
[242.939897] [124/164] (762s left) kms_setmode (basic-clone-single-crtc)
[243.036506] Starting subtest: basic-clone-single-crtc
[243.039848] Subtest basic-clone-single-crtc: SKIP (0.000s)
[243.124329] [125/164] (762s left) i915_pm_rpm (basic-pci-d3-state)
[244.955587] Starting subtest: basic-pci-d3-state
[249.360604] Subtest basic-pci-d3-state: SUCCESS (4.405s)
[249.587258] [126/164] (756s left) i915_pm_rpm (basic-rte)
[249.678278] Starting subtest: basic-rte
[255.830499] Subtest basic-rte: SUCCESS (6.149s)
[261.209696] [127/164] (744s left) i915_pm_rps (basic-api)
[261.310124] Starting subtest: basic-api
[261.314448] Subtest basic-api: SUCCESS (0.004s)
[261.409376] [128/164] (744s left) prime_self_import (basic-llseek-bad)
[261.499769] Starting subtest: basic-llseek-bad
[261.504462] Subtest basic-llseek-bad: SUCCESS (0.003s)
[261.609656] [129/164] (744s left) prime_self_import (basic-llseek-size)
[261.696076] Starting subtest: basic-llseek-size
[261.700430] Subtest basic-llseek-size: SUCCESS (0.004s)
[261.809119] [130/164] (744s left) prime_self_import (basic-with_fd_dup)
[261.898405] Starting subtest: basic-with_fd_dup
[261.903188] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[262.016484] [131/164] (743s left) prime_self_import (basic-with_one_bo)
[262.106627] Starting subtest: basic-with_one_bo
[262.111511] Subtest basic-with_one_bo: SUCCESS (0.005s)
[262.203990] [132/164] (743s left) prime_self_import (basic-with_one_bo_two_files)
[262.292853] Starting subtest: basic-with_one_bo_two_files
[262.297423] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[262.410038] [133/164] (743s left) prime_self_import (basic-with_two_bos)
[262.499572] Starting subtest: basic-with_two_bos
[262.504177] Subtest basic-with_two_bos: SUCCESS (0.005s)
[262.610663] [134/164] (743s left) prime_vgem (basic-fence-flip)
[262.752135] Starting subtest: basic-fence-flip
[263.299745] Subtest basic-fence-flip: SUCCESS (0.549s)
[263.412787] [135/164] (742s left) prime_vgem (basic-fence-mmap)
[263.550704] Starting subtest: basic-fence-mmap
[263.602242] Subtest basic-fence-mmap: SUCCESS (0.051s)
[263.712341] [136/164] (742s left) prime_vgem (basic-fence-read)
[263.841928] Starting subtest: basic-fence-read
[263.919528] Subtest basic-fence-read: SUCCESS (0.078s)
[264.012918] [137/164] (742s left) prime_vgem (basic-gtt)
[264.136204] Starting subtest: basic-gtt
[264.161463] Subtest basic-gtt: SUCCESS (0.025s)
[264.279588] [138/164] (741s left) prime_vgem (basic-read)
[264.393719] Starting subtest: basic-read
[264.431920] Subtest basic-read: SUCCESS (0.038s)
[264.529537] [139/164] (741s left) prime_vgem (basic-write)
[264.652621] Starting subtest: basic-write
[264.698168] Subtest basic-write: SUCCESS (0.045s)
[264.795023] [140/164] (741s left) vgem_basic (setversion)
[264.903361] Starting subtest: setversion
[264.905826] Subtest setversion: SUCCESS (0.000s)
[264.981409] [141/164] (741s left) vgem_basic (create)
[265.086729] Starting subtest: create
[265.087992] Subtest create: SUCCESS (0.000s)
[265.165290] [142/164] (741s left) vgem_basic (debugfs)
[265.269723] Starting subtest: debugfs
[265.270979] Subtest debugfs: SUCCESS (0.001s)
[265.346045] [143/164] (740s left) vgem_basic (dmabuf-export)
[265.455194] Starting subtest: dmabuf-export
[265.461094] Subtest dmabuf-export: SUCCESS (0.005s)
[265.583758] [144/164] (740s left) vgem_basic (dmabuf-fence)
[265.687383] Starting subtest: dmabuf-fence
[265.688514] Subtest dmabuf-fence: SUCCESS (0.001s)
[265.760928] [145/164] (740s left) vgem_basic (dmabuf-fence-before)
[265.870391] Starting subtest: dmabuf-fence-before
[265.871595] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[265.947778] [146/164] (740s left) vgem_basic (dmabuf-mmap)
[266.053873] Starting subtest: dmabuf-mmap
[266.079129] Subtest dmabuf-mmap: SUCCESS (0.026s)
[266.163744] [147/164] (740s left) vgem_basic (mmap)
[266.270720] Starting subtest: mmap
[266.288223] Subtest mmap: SUCCESS (0.018s)
[266.360509] [148/164] (740s left) vgem_basic (second-client)
[266.470905] Starting subtest: second-client
[266.506587] Subtest second-client: SUCCESS (0.034s)
[266.581173] [149/164] (739s left) vgem_basic (sysfs)
[266.687802] Starting subtest: sysfs
[266.689136] Subtest sysfs: SUCCESS (0.001s)
[266.763144] [150/164] (739s left) core_hotunplug (unbind-rebind)
[266.904521] Starting subtest: unbind-rebind
[271.375439] Subtest unbind-rebind: SUCCESS (4.471s)
[271.411359] [151/164] (735s left) vgem_basic (unload)
[271.507344] Starting subtest: unload
[300.557948] Subtest unload: SUCCESS (29.015s)
[300.667601] [152/164] (705s left) i915_module_load (reload)
[300.759377] Starting subtest: reload
[304.193371] Subtest reload: SUCCESS (3.434s)
[304.223760] [153/164] (702s left) gem_lmem_swapping (basic)
[304.337230] Subtest basic: SKIP (0.000s)
[304.377826] [154/164] (702s left) gem_lmem_swapping (parallel-random-engines)
[304.475777] Subtest parallel-random-engines: SKIP (0.000s)
[304.528938] [155/164] (702s left) gem_lmem_swapping (random-engines)
[304.628748] Subtest random-engines: SKIP (0.000s)
[304.678677] [156/164] (702s left) gem_lmem_swapping (verify-random)
[304.776484] Subtest verify-random: SKIP (0.000s)
[304.828996] [157/164] (701s left) i915_pm_rpm (module-reload)
[317.292546] Starting subtest: module-reload
[351.805146] Subtest module-reload: SUCCESS (34.504s)
[351.830495] [158/164] (654s left) i915_selftest (live)
[351.937354] Starting subtest: live
[351.938611] Starting dynamic subtest: sanitycheck
[353.808459] Dynamic subtest sanitycheck: SUCCESS (1.861s)
[353.823810] Starting dynamic subtest: uncore
[355.686733] Dynamic subtest uncore: SUCCESS (1.875s)
[355.701885] Starting dynamic subtest: workarounds
[357.840328] Dynamic subtest workarounds: SUCCESS (2.150s)
[357.855857] Starting dynamic subtest: gt_engines
[360.143530] Dynamic subtest gt_engines: SUCCESS (2.300s)
[360.158640] Starting dynamic subtest: gt_timelines
[373.694618] Dynamic subtest gt_timelines: SUCCESS (13.547s)
[373.710035] Starting dynamic subtest: gt_contexts
[375.644522] Dynamic subtest gt_contexts: SUCCESS (1.947s)
[375.659884] Starting dynamic subtest: gt_lrc
[380.062680] Dynamic subtest gt_lrc: SUCCESS (4.415s)
[380.078130] Starting dynamic subtest: gt_mocs
[382.038483] Dynamic subtest gt_mocs: SUCCESS (1.972s)
[382.054108] Starting dynamic subtest: gt_pm
[387.181619] Dynamic subtest gt_pm: SUCCESS (5.140s)
[387.196951] Starting dynamic subtest: gt_heartbeat
[389.148663] Dynamic subtest gt_heartbeat: SUCCESS (1.963s)
[389.163872] Starting dynamic subtest: gt_tlb
[392.326131] Dynamic subtest gt_tlb: SUCCESS (3.173s)
[392.343067] Starting dynamic subtest: requests
[399.947981] Dynamic subtest requests: SUCCESS (7.618s)
[399.963376] Starting dynamic subtest: migrate
[404.536437] Dynamic subtest migrate: SUCCESS (4.585s)
[404.551803] Starting dynamic subtest: active
[406.429527] Dynamic subtest active: SUCCESS (1.890s)
[406.445217] Starting dynamic subtest: objects
[409.875399] Dynamic subtest objects: SUCCESS (3.443s)
[409.891076] Starting dynamic subtest: mman
[416.858168] Dynamic subtest mman: SUCCESS (6.979s)
[416.874484] Starting dynamic subtest: dmabuf
[418.784742] Dynamic subtest dmabuf: SUCCESS (1.923s)
[418.800340] Starting dynamic subtest: vma
[420.627429] Dynamic subtest vma: SUCCESS (1.839s)
[420.643315] Starting dynamic subtest: coherency
[425.258595] Dynamic subtest coherency: SUCCESS (4.628s)
[425.273947] Starting dynamic subtest: gtt
[435.044441] Dynamic subtest gtt: SUCCESS (9.782s)
[435.059785] Starting dynamic subtest: gem
[437.097662] Dynamic subtest gem: SUCCESS (2.049s)
[437.113096] Starting dynamic subtest: evict
[441.043557] Dynamic subtest evict: SUCCESS (3.942s)
[441.059420] Starting dynamic subtest: hugepages
[448.802509] Dynamic subtest hugepages: SUCCESS (7.756s)
[448.818434] Starting dynamic subtest: gem_contexts
[464.466048] Dynamic subtest gem_contexts: SUCCESS (15.661s)
[464.482313] Starting dynamic subtest: client
[466.397063] Dynamic subtest client: SUCCESS (1.926s)
[466.413270] Starting dynamic subtest: gem_migrate
[468.238070] Dynamic subtest gem_migrate: SUCCESS (1.837s)
[468.255404] Starting dynamic subtest: reset
[473.684607] Dynamic subtest reset: SUCCESS (5.443s)
[473.700500] Starting dynamic subtest: memory_region
[475.550650] Dynamic subtest memory_region: SUCCESS (1.862s)
[475.566773] Starting dynamic subtest: hangcheck
[499.954936] Dynamic subtest hangcheck: SUCCESS (24.400s)
[499.970861] Starting dynamic subtest: execlists
[510.155630] Dynamic subtest execlists: SUCCESS (10.197s)
[510.171664] Starting dynamic subtest: ring_submission
[512.015666] Dynamic subtest ring_submission: SUCCESS (1.856s)
[512.031711] Starting dynamic subtest: perf
[514.000647] Dynamic subtest perf: SUCCESS (1.981s)
[514.016566] Starting dynamic subtest: slpc
[515.862747] Dynamic subtest slpc: SUCCESS (1.858s)
[515.878777] Starting dynamic subtest: guc
[517.704244] Dynamic subtest guc: SUCCESS (1.838s)
[517.720571] Starting dynamic subtest: guc_multi_lrc
[519.568638] Dynamic subtest guc_multi_lrc: SUCCESS (1.862s)
[519.584905] Starting dynamic subtest: guc_hang
[521.422491] Dynamic subtest guc_hang: SUCCESS (1.851s)
[521.438745] Starting dynamic subtest: late_gt_pm
[523.346665] Dynamic subtest late_gt_pm: SUCCESS (1.920s)
[523.347903] Subtest live: SUCCESS (171.402s)
[523.373997] [159/164] (483s left) dmabuf (all-tests)
[523.472088] Starting subtest: all-tests
[523.473734] Starting dynamic subtest: sanitycheck
[523.539285] Dynamic subtest sanitycheck: SUCCESS (0.065s)
[523.559679] Starting dynamic subtest: dma_fence
[523.729938] Dynamic subtest dma_fence: SUCCESS (0.187s)
[523.748323] Starting dynamic subtest: dma_fence_chain
[541.590048] Dynamic subtest dma_fence_chain: SUCCESS (17.848s)
[541.602978] Starting dynamic subtest: dma_fence_unwrap
[541.656572] Dynamic subtest dma_fence_unwrap: SUCCESS (0.071s)
[541.676718] Starting dynamic subtest: dma_resv
[541.723586] Dynamic subtest dma_resv: SUCCESS (0.063s)
[541.725596] Subtest all-tests: SUCCESS (18.253s)
[541.756861] [160/164] (465s left) i915_suspend (basic-s2idle-without-i915)
[541.846031] Starting subtest: basic-s2idle-without-i915
[564.903528] Subtest basic-s2idle-without-i915: SUCCESS (8.332s)
[565.254563] [161/164] (456s left) i915_suspend (basic-s3-without-i915)
[565.351168] Starting subtest: basic-s3-without-i915
[588.887413] Subtest basic-s3-without-i915: SUCCESS (6.857s)
[589.256924] [162/164] (449s left) gem_exec_suspend (basic-s0)
[589.383461] Starting subtest: basic-S0
[589.384654] Starting dynamic subtest: smem
[609.057845] Dynamic subtest smem: SUCCESS (3.932s)
[609.059724] Subtest basic-S0: SUCCESS (3.934s)
[609.281590] [163/164] (444s left) gem_exec_suspend (basic-s3)
[609.393253] Starting subtest: basic-S3
[609.394482] Starting dynamic subtest: smem
[628.088757] Dynamic subtest smem: SUCCESS (3.850s)
[628.090024] Subtest basic-S3: SUCCESS (3.851s)
[628.300811] [164/164] (440s left) kms_pipe_crc_basic (suspend-read-crc)
[628.426233] Starting subtest: suspend-read-crc
[628.429869] Starting dynamic subtest: pipe-A-eDP-1
[646.024207] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.561s)
[646.058371] Starting dynamic subtest: pipe-B-eDP-1
[664.946606] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.141s)
[664.991669] Starting dynamic subtest: pipe-C-eDP-1
[682.983659] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.140s)
[682.985760] Subtest suspend-read-crc: SUCCESS (8.866s)
[683.892118] Closing watchdogs
Done.