[45.741712] Initializing watchdogs
[45.741827]   /dev/watchdog0
[45.748672] [001/165] (960s left) i915_module_load (load)
[45.824280] Starting subtest: load
[46.904435] Subtest load: SUCCESS (1.073s)
[46.919809] [002/165] (959s left) core_auth (basic-auth)
[47.011573] Starting subtest: basic-auth
[47.015901] Subtest basic-auth: SUCCESS (0.001s)
[47.089812] [003/165] (959s left) debugfs_test (read_all_entries)
[47.197538] Starting subtest: read_all_entries
[47.284331] Subtest read_all_entries: SUCCESS (0.087s)
[47.388501] [004/165] (958s left) debugfs_test (basic-hwmon)
[47.464479] Starting subtest: basic-hwmon
[47.466378] Subtest basic-hwmon: SKIP (0.000s)
[47.602155] [005/165] (958s left) fbdev (eof)
[47.689048] Starting subtest: eof
[47.690142] Subtest eof: SUCCESS (0.001s)
[47.749417] [006/165] (958s left) fbdev (info)
[47.822969] Starting subtest: info
[47.824065] Subtest info: SUCCESS (0.000s)
[47.902584] [007/165] (958s left) fbdev (nullptr)
[48.004585] Starting subtest: nullptr
[48.005784] Subtest nullptr: SUCCESS (0.000s)
[48.067585] [008/165] (958s left) fbdev (read)
[48.154607] Starting subtest: read
[49.019168] Subtest read: SUCCESS (0.856s)
[49.060997] [009/165] (957s left) fbdev (write)
[49.138727] Starting subtest: write
[49.561162] Subtest write: SUCCESS (0.414s)
[49.647797] [010/165] (956s left) gem_basic (bad-close)
[49.714604] Starting subtest: bad-close
[49.717100] Subtest bad-close: SUCCESS (0.000s)
[49.794072] [011/165] (956s left) gem_basic (create-close)
[49.864321] Starting subtest: create-close
[49.866092] Subtest create-close: SUCCESS (0.000s)
[49.969907] [012/165] (956s left) gem_basic (create-fd-close)
[50.039813] Starting subtest: create-fd-close
[50.041726] Subtest create-fd-close: SUCCESS (0.000s)
[50.125947] [013/165] (956s left) gem_busy (busy@all-engines)
[50.229519] Starting subtest: busy
[50.231142] Starting dynamic subtest: all-engines
[50.286045] Dynamic subtest all-engines: SUCCESS (0.059s)
[50.286954] Subtest busy: SUCCESS (0.060s)
[50.676152] [014/165] (955s left) gem_close_race (basic-process)
[50.763303] Starting subtest: basic-process
[50.829501] Subtest basic-process: SUCCESS (0.066s)
[51.058338] [015/165] (955s left) gem_close_race (basic-threads)
[51.148396] Starting subtest: basic-threads
[52.334860] Subtest basic-threads: SUCCESS (1.188s)
[52.598742] [016/165] (953s left) gem_ctx_create (basic)
[52.686293] Starting subtest: basic
[52.687351] Subtest basic: SUCCESS (0.000s)
[52.915480] [017/165] (953s left) gem_ctx_create (basic-files)
[52.999288] Starting subtest: basic-files
[55.021056] Subtest basic-files: SUCCESS (2.023s)
[55.299156] [018/165] (951s left) gem_ctx_exec (basic)
[55.371530] Starting subtest: basic
[55.375627] Subtest basic: SUCCESS (0.004s)
[55.507743] [019/165] (951s left) gem_exec_basic (basic)
[55.590319] Starting subtest: basic
[55.591536] Starting dynamic subtest: rcs0-smem
[55.592653] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[55.600420] Starting dynamic subtest: bcs0-smem
[55.601560] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[55.609033] Starting dynamic subtest: vcs0-smem
[55.610668] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[55.618270] Starting dynamic subtest: vecs0-smem
[55.619682] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[55.621285] Subtest basic: SUCCESS (0.005s)
[55.851178] [020/165] (950s left) gem_exec_create (basic)
[55.936875] Starting subtest: basic
[55.937964] Starting dynamic subtest: smem
[57.983569] Dynamic subtest smem: SUCCESS (2.047s)
[57.984568] Subtest basic: SUCCESS (2.047s)
[58.226727] [021/165] (948s left) gem_exec_fence (basic-busy)
[58.483289] Starting subtest: basic-busy
[58.484476] Starting dynamic subtest: rcs0
[58.508266] Dynamic subtest rcs0: SUCCESS (0.025s)
[58.515603] Starting dynamic subtest: bcs0
[58.539414] Dynamic subtest bcs0: SUCCESS (0.031s)
[58.546811] Starting dynamic subtest: vcs0
[58.563283] Dynamic subtest vcs0: SUCCESS (0.024s)
[58.570727] Starting dynamic subtest: vecs0
[58.585193] Dynamic subtest vecs0: SUCCESS (0.022s)
[58.586271] Subtest basic-busy: SUCCESS (0.102s)
[59.317810] [022/165] (947s left) gem_exec_fence (basic-wait)
[59.551743] Starting subtest: basic-wait
[59.553421] Starting dynamic subtest: rcs0
[59.590265] Dynamic subtest rcs0: SUCCESS (0.039s)
[59.597844] Starting dynamic subtest: bcs0
[59.621257] Dynamic subtest bcs0: SUCCESS (0.030s)
[59.629968] Starting dynamic subtest: vcs0
[59.651345] Dynamic subtest vcs0: SUCCESS (0.031s)
[59.660884] Starting dynamic subtest: vecs0
[59.677470] Dynamic subtest vecs0: SUCCESS (0.026s)
[59.678756] Subtest basic-wait: SUCCESS (0.126s)
[60.346752] [023/165] (946s left) gem_exec_fence (basic-await)
[60.587422] Starting subtest: basic-await
[60.589736] Starting dynamic subtest: rcs0
[60.692423] Dynamic subtest rcs0: SUCCESS (0.106s)
[60.701229] Starting dynamic subtest: bcs0
[60.791439] Dynamic subtest bcs0: SUCCESS (0.098s)
[60.800130] Starting dynamic subtest: vcs0
[60.891722] Dynamic subtest vcs0: SUCCESS (0.101s)
[60.900443] Starting dynamic subtest: vecs0
[60.992941] Dynamic subtest vecs0: SUCCESS (0.101s)
[60.993928] Subtest basic-await: SUCCESS (0.407s)
[61.721391] [024/165] (945s left) gem_exec_fence (nb-await)
[61.980744] Starting subtest: nb-await
[61.983627] Starting dynamic subtest: rcs0
[62.070911] Dynamic subtest rcs0: SUCCESS (0.091s)
[62.090511] Starting dynamic subtest: bcs0
[62.150461] Dynamic subtest bcs0: SUCCESS (0.077s)
[62.196347] Starting dynamic subtest: vcs0
[62.261620] Dynamic subtest vcs0: SUCCESS (0.109s)
[62.306771] Starting dynamic subtest: vecs0
[62.377725] Dynamic subtest vecs0: SUCCESS (0.109s)
[62.384267] Subtest nb-await: SUCCESS (0.390s)
[63.094949] [025/165] (943s left) gem_exec_gttfill (basic)
[63.188192] Starting subtest: basic
[72.465964] Subtest basic: SUCCESS (9.269s)
[72.653132] [026/165] (934s left) gem_exec_parallel (engines)
[72.783562] Starting subtest: engines
[72.784711] Starting dynamic subtest: basic
[75.084804] Dynamic subtest basic: SUCCESS (2.300s)
[75.113809] Starting dynamic subtest: contexts
[79.551230] Dynamic subtest contexts: SUCCESS (4.457s)
[79.570617] Starting dynamic subtest: fds
[84.877655] Dynamic subtest fds: SUCCESS (5.336s)
[84.886886] Starting dynamic subtest: userptr
[87.690641] Dynamic subtest userptr: SUCCESS (2.790s)
[87.692229] Subtest engines: SUCCESS (14.884s)
[87.910118] [027/165] (918s left) gem_exec_store (basic)
[87.996843] Starting subtest: basic
[88.043553] Subtest basic: SUCCESS (0.048s)
[88.276796] [028/165] (918s left) gem_flink_basic (bad-flink)
[88.349475] Starting subtest: bad-flink
[88.350649] Subtest bad-flink: SUCCESS (0.000s)
[88.414168] [029/165] (918s left) gem_flink_basic (bad-open)
[88.485384] Starting subtest: bad-open
[88.486436] Subtest bad-open: SUCCESS (0.000s)
[88.549239] [030/165] (918s left) gem_flink_basic (basic)
[88.623390] Starting subtest: basic
[88.624502] Subtest basic: SUCCESS (0.000s)
[88.730197] [031/165] (918s left) gem_flink_basic (double-flink)
[88.811492] Starting subtest: double-flink
[88.812587] Subtest double-flink: SUCCESS (0.000s)
[88.947817] [032/165] (918s left) gem_flink_basic (flink-lifetime)
[89.018186] Starting subtest: flink-lifetime
[89.020385] Subtest flink-lifetime: SUCCESS (0.002s)
[89.146482] [033/165] (917s left) gem_huc_copy (huc-copy)
[89.234645] Starting subtest: huc-copy
[89.236775] Subtest huc-copy: SKIP (0.000s)
[89.363908] [034/165] (917s left) gem_linear_blits (basic)
[89.580359] Starting subtest: basic
[89.589751] Subtest basic: SUCCESS (0.009s)
[89.756620] [035/165] (917s left) gem_mmap (basic)
[89.879520] Starting subtest: basic
[89.884336] Subtest basic: SUCCESS (0.001s)
[89.957196] [036/165] (917s left) gem_mmap_gtt (basic)
[90.032804] Starting subtest: basic
[90.033968] Subtest basic: SUCCESS (0.001s)
[90.088823] [037/165] (917s left) gem_render_linear_blits (basic)
[90.167638] Starting subtest: basic
[90.208346] Subtest basic: SUCCESS (0.038s)
[90.255588] [038/165] (916s left) gem_render_tiled_blits (basic)
[90.335950] Starting subtest: basic
[90.381779] Subtest basic: SUCCESS (0.046s)
[90.422449] [039/165] (916s left) gem_ringfill (basic-all)
[90.708434] Starting subtest: basic-all
[91.878734] Subtest basic-all: SUCCESS (1.162s)
[91.999225] [040/165] (915s left) gem_softpin (allocator-basic)
[92.086648] Starting subtest: allocator-basic
[92.225536] Subtest allocator-basic: SUCCESS (0.130s)
[92.362893] [041/165] (914s left) gem_softpin (allocator-basic-reserve)
[92.439359] Starting subtest: allocator-basic-reserve
[92.583980] Subtest allocator-basic-reserve: SUCCESS (0.136s)
[92.729170] [042/165] (914s left) gem_softpin (safe-alignment)
[92.808365] Starting subtest: safe-alignment
[92.823437] Subtest safe-alignment: SUCCESS (0.015s)
[92.952616] [043/165] (914s left) gem_sync (basic-all)
[93.041223] Starting subtest: basic-all
[95.282437] Subtest basic-all: SUCCESS (2.240s)
[95.515402] [044/165] (911s left) gem_sync (basic-each)
[95.601260] Starting subtest: basic-each
[97.745530] Subtest basic-each: SUCCESS (2.144s)
[98.036100] [045/165] (909s left) gem_tiled_blits (basic)
[98.230451] Starting subtest: basic
[98.257616] Subtest basic: SUCCESS (0.028s)
[98.415371] [046/165] (908s left) gem_tiled_fence_blits (basic)
[98.675612] Starting subtest: basic
[98.690076] Subtest basic: SUCCESS (0.014s)
[98.854496] [047/165] (908s left) gem_tiled_pread_basic
[99.228809] [048/165] (908s left) gem_wait (busy@all-engines)
[99.326582] Starting subtest: busy
[99.327731] Starting dynamic subtest: all-engines
[99.856075] Dynamic subtest all-engines: SUCCESS (0.527s)
[99.857279] Subtest busy: SUCCESS (0.527s)
[100.118723] [049/165] (907s left) gem_wait (wait@all-engines)
[100.219187] Starting subtest: wait
[100.220313] Starting dynamic subtest: all-engines
[101.264245] Dynamic subtest all-engines: SUCCESS (1.042s)
[101.267465] Subtest wait: SUCCESS (1.043s)
[101.554895] [050/165] (905s left) i915_getparams_basic (basic-eu-total)
[101.635437] Starting subtest: basic-eu-total
[101.637224] Subtest basic-eu-total: SUCCESS (0.000s)
[101.706084] [051/165] (905s left) i915_getparams_basic (basic-subslice-total)
[101.778746] Starting subtest: basic-subslice-total
[101.780756] Subtest basic-subslice-total: SUCCESS (0.000s)
[101.859689] [052/165] (905s left) i915_hangman (error-state-basic)
[101.945547] Starting subtest: error-state-basic
[101.952209] Subtest error-state-basic: SUCCESS (0.007s)
[102.020418] [053/165] (905s left) i915_pciid
[102.156168] [054/165] (905s left) kms_addfb_basic (addfb25-4-tiled)
[102.277349] Starting subtest: addfb25-4-tiled
[102.278381] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[102.394265] [055/165] (905s left) kms_addfb_basic (addfb25-bad-modifier)
[102.466430] Starting subtest: addfb25-bad-modifier
[102.467374] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[102.601550] [056/165] (904s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[102.675393] Starting subtest: addfb25-framebuffer-vs-set-tiling
[102.676425] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[102.827793] [057/165] (904s left) kms_addfb_basic (addfb25-modifier-no-flag)
[102.913689] Starting subtest: addfb25-modifier-no-flag
[102.914807] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[103.041536] [058/165] (904s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[103.114422] Starting subtest: addfb25-x-tiled-legacy
[103.115376] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[103.256302] [059/165] (904s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[103.333301] Starting subtest: addfb25-x-tiled-mismatch-legacy
[103.334213] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[103.467852] [060/165] (904s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[103.581846] Starting subtest: addfb25-yf-tiled-legacy
[103.582898] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[103.682450] [061/165] (904s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[103.791778] Starting subtest: addfb25-y-tiled-legacy
[103.792907] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[103.913166] [062/165] (903s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[104.032822] Starting subtest: addfb25-y-tiled-small-legacy
[104.033950] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[104.130461] [063/165] (903s left) kms_addfb_basic (bad-pitch-0)
[104.216512] Starting subtest: bad-pitch-0
[104.217628] Subtest bad-pitch-0: SUCCESS (0.000s)
[104.382519] [064/165] (903s left) kms_addfb_basic (bad-pitch-1024)
[104.468780] Starting subtest: bad-pitch-1024
[104.469808] Subtest bad-pitch-1024: SUCCESS (0.000s)
[104.623764] [065/165] (903s left) kms_addfb_basic (bad-pitch-128)
[104.695723] Starting subtest: bad-pitch-128
[104.696766] Subtest bad-pitch-128: SUCCESS (0.000s)
[104.834728] [066/165] (902s left) kms_addfb_basic (bad-pitch-256)
[104.907439] Starting subtest: bad-pitch-256
[104.908399] Subtest bad-pitch-256: SUCCESS (0.000s)
[105.049481] [067/165] (902s left) kms_addfb_basic (bad-pitch-32)
[105.128315] Starting subtest: bad-pitch-32
[105.129405] Subtest bad-pitch-32: SUCCESS (0.000s)
[105.280417] [068/165] (902s left) kms_addfb_basic (bad-pitch-63)
[105.351664] Starting subtest: bad-pitch-63
[105.352695] Subtest bad-pitch-63: SUCCESS (0.000s)
[105.510572] [069/165] (902s left) kms_addfb_basic (bad-pitch-65536)
[105.593077] Starting subtest: bad-pitch-65536
[105.594195] Subtest bad-pitch-65536: SUCCESS (0.000s)
[105.730138] [070/165] (902s left) kms_addfb_basic (bad-pitch-999)
[105.801256] Starting subtest: bad-pitch-999
[105.802196] Subtest bad-pitch-999: SUCCESS (0.000s)
[105.952405] [071/165] (902s left) kms_addfb_basic (basic)
[106.023523] Starting subtest: basic
[106.024667] Subtest basic: SUCCESS (0.000s)
[106.167632] [072/165] (901s left) kms_addfb_basic (basic-x-tiled-legacy)
[106.241318] Starting subtest: basic-x-tiled-legacy
[106.242444] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[106.389654] [073/165] (901s left) kms_addfb_basic (basic-y-tiled-legacy)
[106.474377] Starting subtest: basic-y-tiled-legacy
[106.475316] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[106.620514] [074/165] (901s left) kms_addfb_basic (bo-too-small)
[106.740259] Starting subtest: bo-too-small
[106.741315] Subtest bo-too-small: SUCCESS (0.000s)
[106.830095] [075/165] (901s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[106.939762] Starting subtest: bo-too-small-due-to-tiling
[106.940906] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[107.044922] [076/165] (901s left) kms_addfb_basic (clobberred-modifier)
[107.124415] Starting subtest: clobberred-modifier
[107.125516] Subtest clobberred-modifier: SUCCESS (0.000s)
[107.277850] [077/165] (900s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[107.365659] Starting subtest: framebuffer-vs-set-tiling
[107.366624] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[107.523225] [078/165] (900s left) kms_addfb_basic (invalid-get-prop)
[107.608339] Starting subtest: invalid-get-prop
[107.609380] Subtest invalid-get-prop: SUCCESS (0.000s)
[107.744830] [079/165] (900s left) kms_addfb_basic (invalid-get-prop-any)
[107.818267] Starting subtest: invalid-get-prop-any
[107.819529] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[107.961197] [080/165] (900s left) kms_addfb_basic (invalid-set-prop)
[108.032406] Starting subtest: invalid-set-prop
[108.033503] Subtest invalid-set-prop: SUCCESS (0.000s)
[108.172867] [081/165] (900s left) kms_addfb_basic (invalid-set-prop-any)
[108.244530] Starting subtest: invalid-set-prop-any
[108.245638] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[108.383099] [082/165] (899s left) kms_addfb_basic (no-handle)
[108.466263] Starting subtest: no-handle
[108.467294] Subtest no-handle: SUCCESS (0.000s)
[108.622145] [083/165] (899s left) kms_addfb_basic (size-max)
[108.729513] Starting subtest: size-max
[108.730637] Subtest size-max: SUCCESS (0.000s)
[108.838905] [084/165] (899s left) kms_addfb_basic (small-bo)
[108.946090] Starting subtest: small-bo
[108.947164] Subtest small-bo: SUCCESS (0.000s)
[109.041227] [085/165] (899s left) kms_addfb_basic (tile-pitch-mismatch)
[109.113752] Starting subtest: tile-pitch-mismatch
[109.114711] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[109.253400] [086/165] (899s left) kms_addfb_basic (too-high)
[109.374430] Starting subtest: too-high
[109.375440] Subtest too-high: SUCCESS (0.001s)
[109.478984] [087/165] (898s left) kms_addfb_basic (too-wide)
[109.592259] Starting subtest: too-wide
[109.593257] Subtest too-wide: SUCCESS (0.000s)
[109.689711] [088/165] (898s left) kms_addfb_basic (unused-handle)
[109.771942] Starting subtest: unused-handle
[109.772949] Subtest unused-handle: SUCCESS (0.000s)
[109.907543] [089/165] (898s left) kms_addfb_basic (unused-modifier)
[109.980259] Starting subtest: unused-modifier
[109.981192] Subtest unused-modifier: SUCCESS (0.000s)
[110.129044] [090/165] (898s left) kms_addfb_basic (unused-offsets)
[110.209649] Starting subtest: unused-offsets
[110.210746] Subtest unused-offsets: SUCCESS (0.000s)
[110.342786] [091/165] (898s left) kms_addfb_basic (unused-pitches)
[110.415770] Starting subtest: unused-pitches
[110.417032] Subtest unused-pitches: SUCCESS (0.000s)
[110.575423] [092/165] (897s left) kms_busy (basic)
[110.702261] Starting subtest: basic
[110.703440] Starting dynamic subtest: flip
[111.626981] Dynamic subtest flip: SUCCESS (0.922s)
[111.646227] Starting dynamic subtest: modeset
[115.412755] Dynamic subtest modeset: SUCCESS (3.786s)
[115.414365] Subtest basic: SUCCESS (4.710s)
[116.311381] [093/165] (892s left) kms_prop_blob (basic)
[116.389827] Starting subtest: basic
[116.390925] Subtest basic: SUCCESS (0.000s)
[116.456914] [094/165] (892s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[116.565373] Starting subtest: basic-busy-flip-before-cursor-atomic
[116.567386] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[116.664165] [095/165] (891s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[116.774223] Starting subtest: basic-busy-flip-before-cursor-legacy
[116.776295] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[116.867834] [096/165] (891s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[116.975249] Starting subtest: basic-flip-after-cursor-atomic
[118.251979] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.271s)
[119.143207] [097/165] (889s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[119.256252] Starting subtest: basic-flip-after-cursor-legacy
[120.528440] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.271s)
[121.466903] [098/165] (887s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[121.575025] Starting subtest: basic-flip-after-cursor-varying-size
[122.865125] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.287s)
[123.755391] [099/165] (884s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[123.868186] Starting subtest: basic-flip-before-cursor-atomic
[125.201896] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.328s)
[126.122120] [100/165] (882s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[126.233335] Starting subtest: basic-flip-before-cursor-legacy
[127.520102] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.285s)
[128.445661] [101/165] (880s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[128.554023] Starting subtest: basic-flip-before-cursor-varying-size
[129.905622] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.345s)
[130.797277] [102/165] (877s left) kms_dsc (dsc-basic)
[130.911303] Subtest dsc-basic: SKIP (0.000s)
[130.950174] [103/165] (877s left) kms_flip (basic-flip-vs-dpms)
[131.061160] Starting subtest: basic-flip-vs-dpms
[131.065248] Starting dynamic subtest: A-eDP1
[133.949365] Dynamic subtest A-eDP1: SUCCESS (2.879s)
[133.993445] Starting dynamic subtest: B-eDP1
[136.331449] Dynamic subtest B-eDP1: SUCCESS (2.378s)
[136.398905] Starting dynamic subtest: C-eDP1
[138.692278] Dynamic subtest C-eDP1: SUCCESS (2.351s)
[138.729269] Subtest basic-flip-vs-dpms: SUCCESS (7.657s)
[139.586019] [104/165] (869s left) kms_flip (basic-flip-vs-modeset)
[139.700315] Starting subtest: basic-flip-vs-modeset
[139.703386] Starting dynamic subtest: A-eDP1
[142.564168] Dynamic subtest A-eDP1: SUCCESS (2.855s)
[142.621150] Starting dynamic subtest: B-eDP1
[144.943156] Dynamic subtest B-eDP1: SUCCESS (2.372s)
[145.001764] Starting dynamic subtest: C-eDP1
[147.343390] Dynamic subtest C-eDP1: SUCCESS (2.390s)
[147.371626] Subtest basic-flip-vs-modeset: SUCCESS (7.664s)
[148.290646] [105/165] (860s left) kms_flip (basic-flip-vs-wf_vblank)
[148.425738] Starting subtest: basic-flip-vs-wf_vblank
[148.428739] Starting dynamic subtest: A-eDP1
[150.743757] Dynamic subtest A-eDP1: SUCCESS (2.315s)
[150.756369] Starting dynamic subtest: B-eDP1
[153.046755] Dynamic subtest B-eDP1: SUCCESS (2.300s)
[153.058329] Starting dynamic subtest: C-eDP1
[155.350838] Dynamic subtest C-eDP1: SUCCESS (2.304s)
[155.357215] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.933s)
[156.667410] [106/165] (852s left) kms_flip (basic-plain-flip)
[156.784231] Starting subtest: basic-plain-flip
[156.789848] Starting dynamic subtest: A-eDP1
[158.790344] Dynamic subtest A-eDP1: SUCCESS (1.988s)
[158.853080] Starting dynamic subtest: B-eDP1
[160.842750] Dynamic subtest B-eDP1: SUCCESS (2.052s)
[160.912353] Starting dynamic subtest: C-eDP1
[162.929344] Dynamic subtest C-eDP1: SUCCESS (2.071s)
[162.953262] Subtest basic-plain-flip: SUCCESS (6.167s)
[164.328749] [107/165] (844s left) kms_force_connector_basic (force-connector-state)
[164.438946] Starting subtest: force-connector-state
[164.930842] Subtest force-connector-state: SUCCESS (0.493s)
[166.285849] [108/165] (842s left) kms_force_connector_basic (force-edid)
[166.386942] Starting subtest: force-edid
[166.423150] Subtest force-edid: SUCCESS (0.037s)
[166.598540] [109/165] (842s left) kms_force_connector_basic (force-load-detect)
[166.696323] Starting subtest: force-load-detect
[166.698393] Subtest force-load-detect: SKIP (0.000s)
[166.834405] [110/165] (842s left) kms_force_connector_basic (prune-stale-modes)
[166.936209] Starting subtest: prune-stale-modes
[166.971160] Subtest prune-stale-modes: SUCCESS (0.035s)
[167.131934] [111/165] (841s left) kms_frontbuffer_tracking (basic)
[167.252564] Starting subtest: basic
[170.211042] Subtest basic: SUCCESS (2.958s)
[170.332054] [112/165] (838s left) kms_hdmi_inject (inject-audio)
[170.750130] Starting subtest: inject-audio
[170.883821] Subtest inject-audio: SUCCESS (0.129s)
[171.700548] [113/165] (837s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[171.815106] Starting subtest: compare-crc-sanitycheck-xr24
[171.816191] Starting dynamic subtest: pipe-A-eDP-1
[172.525051] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.699s)
[172.549710] Starting dynamic subtest: pipe-B-eDP-1
[173.873432] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.334s)
[173.919353] Starting dynamic subtest: pipe-C-eDP-1
[175.225256] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.333s)
[175.229436] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.408s)
[176.144510] [114/165] (832s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[176.259458] Starting subtest: compare-crc-sanitycheck-nv12
[176.262362] Starting dynamic subtest: pipe-A-eDP-1
[176.264354] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[176.275403] Starting dynamic subtest: pipe-B-eDP-1
[176.277332] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[176.286218] Starting dynamic subtest: pipe-C-eDP-1
[176.288073] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[176.289959] Subtest compare-crc-sanitycheck-nv12: SKIP (0.010s)
[176.355236] [115/165] (832s left) kms_pipe_crc_basic (hang-read-crc)
[176.464022] Starting subtest: hang-read-crc
[176.465110] Starting dynamic subtest: pipe-A-eDP-1
[178.990387] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.521s)
[179.065209] Starting dynamic subtest: pipe-B-eDP-1
[182.230827] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.217s)
[182.280584] Starting dynamic subtest: pipe-C-eDP-1
[185.480665] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.228s)
[185.484531] Subtest hang-read-crc: SUCCESS (9.015s)
[186.413483] [116/165] (822s left) kms_pipe_crc_basic (nonblocking-crc)
[186.524849] Starting subtest: nonblocking-crc
[186.527566] Starting dynamic subtest: pipe-A-eDP-1
[187.586338] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.052s)
[187.654744] Starting dynamic subtest: pipe-B-eDP-1
[189.326578] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.720s)
[189.400532] Starting dynamic subtest: pipe-C-eDP-1
[191.077251] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.729s)
[191.080419] Subtest nonblocking-crc: SUCCESS (4.551s)
[192.035369] [117/165] (817s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[192.157195] Starting subtest: nonblocking-crc-frame-sequence
[192.158259] Starting dynamic subtest: pipe-A-eDP-1
[193.155936] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.986s)
[193.230404] Starting dynamic subtest: pipe-B-eDP-1
[194.872361] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.699s)
[194.950510] Starting dynamic subtest: pipe-C-eDP-1
[196.643569] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.748s)
[196.646578] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.488s)
[197.575530] [118/165] (811s left) kms_pipe_crc_basic (read-crc)
[197.688266] Starting subtest: read-crc
[197.689308] Starting dynamic subtest: pipe-A-eDP-1
[198.628473] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.935s)
[198.705923] Starting dynamic subtest: pipe-B-eDP-1
[200.285325] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.634s)
[200.357215] Starting dynamic subtest: pipe-C-eDP-1
[201.919765] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.615s)
[201.931467] Subtest read-crc: SUCCESS (4.237s)
[202.854890] [119/165] (806s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[202.973976] Starting subtest: read-crc-frame-sequence
[202.975830] Starting dynamic subtest: pipe-A-eDP-1
[203.933098] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.945s)
[204.004193] Starting dynamic subtest: pipe-B-eDP-1
[205.556766] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.600s)
[205.620125] Starting dynamic subtest: pipe-C-eDP-1
[207.173317] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.590s)
[207.178848] Subtest read-crc-frame-sequence: SUCCESS (4.192s)
[208.063805] [120/165] (801s left) kms_pm_backlight (basic-brightness)
[208.184454] Starting subtest: basic-brightness
[208.281929] Starting dynamic subtest: eDP-1
[208.867210] Dynamic subtest eDP-1: SUCCESS (0.583s)
[208.888090] Subtest basic-brightness: SUCCESS (0.687s)
[209.806866] [121/165] (799s left) kms_pm_rpm (basic-pci-d3-state)
[211.558860] Starting subtest: basic-pci-d3-state
[216.016075] Subtest basic-pci-d3-state: SUCCESS (4.458s)
[216.190514] [122/165] (793s left) kms_pm_rpm (basic-rte)
[216.262072] Starting subtest: basic-rte
[222.448318] Subtest basic-rte: SUCCESS (6.181s)
[223.942553] [123/165] (785s left) kms_psr (psr_primary_page_flip)
[224.179645] [124/165] (785s left) kms_psr (psr_cursor_plane_move)
[224.417493] [125/165] (785s left) kms_psr (psr_sprite_plane_onoff)
[224.660597] [126/165] (784s left) kms_psr (psr_primary_mmap_gtt)
[224.898909] [127/165] (784s left) kms_setmode (basic-clone-single-crtc)
[224.976300] Starting subtest: basic-clone-single-crtc
[224.978944] Subtest basic-clone-single-crtc: SKIP (0.001s)
[225.059614] [128/165] (784s left) i915_pm_rps (basic-api)
[225.142857] Starting subtest: basic-api
[225.147857] Subtest basic-api: SUCCESS (0.005s)
[225.286942] [129/165] (784s left) prime_self_import (basic-llseek-bad)
[225.357310] Starting subtest: basic-llseek-bad
[225.359471] Subtest basic-llseek-bad: SUCCESS (0.002s)
[225.489789] [130/165] (784s left) prime_self_import (basic-llseek-size)
[225.569219] Starting subtest: basic-llseek-size
[225.573956] Subtest basic-llseek-size: SUCCESS (0.003s)
[225.701679] [131/165] (783s left) prime_self_import (basic-with_fd_dup)
[225.782105] Starting subtest: basic-with_fd_dup
[225.787035] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[225.924086] [132/165] (783s left) prime_self_import (basic-with_one_bo)
[225.998114] Starting subtest: basic-with_one_bo
[226.003180] Subtest basic-with_one_bo: SUCCESS (0.003s)
[226.082338] [133/165] (783s left) prime_self_import (basic-with_one_bo_two_files)
[226.164948] Starting subtest: basic-with_one_bo_two_files
[226.167360] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[226.250040] [134/165] (783s left) prime_self_import (basic-with_two_bos)
[226.321262] Starting subtest: basic-with_two_bos
[226.326061] Subtest basic-with_two_bos: SUCCESS (0.003s)
[226.437888] [135/165] (783s left) prime_vgem (basic-fence-flip)
[226.524515] Starting subtest: basic-fence-flip
[227.000500] Subtest basic-fence-flip: SUCCESS (0.476s)
[227.116051] [136/165] (782s left) prime_vgem (basic-fence-mmap)
[227.217601] Starting subtest: basic-fence-mmap
[227.254920] Subtest basic-fence-mmap: SUCCESS (0.037s)
[227.333036] [137/165] (782s left) prime_vgem (basic-fence-read)
[227.425092] Starting subtest: basic-fence-read
[227.484160] Subtest basic-fence-read: SUCCESS (0.059s)
[227.567151] [138/165] (782s left) prime_vgem (basic-gtt)
[227.659509] Starting subtest: basic-gtt
[227.676349] Subtest basic-gtt: SUCCESS (0.017s)
[227.767692] [139/165] (782s left) prime_vgem (basic-read)
[227.858079] Starting subtest: basic-read
[227.884314] Subtest basic-read: SUCCESS (0.026s)
[227.984901] [140/165] (781s left) prime_vgem (basic-write)
[228.074859] Starting subtest: basic-write
[228.104368] Subtest basic-write: SUCCESS (0.030s)
[228.178779] [141/165] (781s left) vgem_basic (setversion)
[228.268867] Starting subtest: setversion
[228.270953] Subtest setversion: SUCCESS (0.000s)
[228.333333] [142/165] (781s left) vgem_basic (create)
[228.418634] Starting subtest: create
[228.419703] Subtest create: SUCCESS (0.000s)
[228.483804] [143/165] (781s left) vgem_basic (debugfs)
[228.569152] Starting subtest: debugfs
[228.571039] Subtest debugfs: SUCCESS (0.001s)
[228.627948] [144/165] (781s left) vgem_basic (dmabuf-export)
[228.704260] Starting subtest: dmabuf-export
[228.706369] Subtest dmabuf-export: SUCCESS (0.002s)
[228.804710] [145/165] (781s left) vgem_basic (dmabuf-fence)
[228.885422] Starting subtest: dmabuf-fence
[228.889075] Subtest dmabuf-fence: SUCCESS (0.001s)
[228.945483] [146/165] (781s left) vgem_basic (dmabuf-fence-before)
[229.022453] Starting subtest: dmabuf-fence-before
[229.023653] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[229.079189] [147/165] (780s left) vgem_basic (dmabuf-mmap)
[229.153063] Starting subtest: dmabuf-mmap
[229.170431] Subtest dmabuf-mmap: SUCCESS (0.018s)
[229.228417] [148/165] (780s left) vgem_basic (mmap)
[229.302701] Starting subtest: mmap
[229.314171] Subtest mmap: SUCCESS (0.012s)
[229.378399] [149/165] (780s left) vgem_basic (second-client)
[229.452524] Starting subtest: second-client
[229.485784] Subtest second-client: SUCCESS (0.033s)
[229.560696] [150/165] (780s left) vgem_basic (sysfs)
[229.635623] Starting subtest: sysfs
[229.636815] Subtest sysfs: SUCCESS (0.001s)
[229.694423] [151/165] (780s left) core_hotunplug (unbind-rebind)
[229.802300] Starting subtest: unbind-rebind
[234.110706] Subtest unbind-rebind: SUCCESS (4.308s)
[234.138821] [152/165] (775s left) vgem_basic (unload)
[234.217635] Starting subtest: unload
[263.340070] Subtest unload: SUCCESS (29.079s)
[263.454067] [153/165] (746s left) i915_module_load (reload)
[263.528033] Starting subtest: reload
[266.699538] Subtest reload: SUCCESS (3.172s)
[266.724682] [154/165] (743s left) gem_lmem_swapping (basic)
[266.862856] Subtest basic: SKIP (0.000s)
[266.904186] [155/165] (743s left) gem_lmem_swapping (parallel-random-engines)
[266.985223] Subtest parallel-random-engines: SKIP (0.000s)
[267.019539] [156/165] (743s left) gem_lmem_swapping (random-engines)
[267.099818] Subtest random-engines: SKIP (0.000s)
[267.133485] [157/165] (743s left) gem_lmem_swapping (verify-random)
[267.213073] Subtest verify-random: SKIP (0.000s)
[267.251247] [158/165] (743s left) i915_pm_rpm (module-reload)
[269.990238] Starting subtest: module-reload
[283.097248] Subtest module-reload: SUCCESS (13.113s)
[283.117618] [159/165] (727s left) i915_selftest (live)
[283.229110] Starting subtest: live
[283.230286] Starting dynamic subtest: sanitycheck
[284.925598] Dynamic subtest sanitycheck: SUCCESS (1.689s)
[284.932982] Starting dynamic subtest: uncore
[286.686805] Dynamic subtest uncore: SUCCESS (1.759s)
[286.697913] Starting dynamic subtest: workarounds
[288.656937] Dynamic subtest workarounds: SUCCESS (1.968s)
[288.668547] Starting dynamic subtest: gt_engines
[290.787761] Dynamic subtest gt_engines: SUCCESS (2.129s)
[290.798932] Starting dynamic subtest: gt_timelines
[300.633769] Dynamic subtest gt_timelines: SUCCESS (9.844s)
[300.644095] Starting dynamic subtest: gt_contexts
[302.413719] Dynamic subtest gt_contexts: SUCCESS (1.778s)
[302.424090] Starting dynamic subtest: gt_lrc
[306.619698] Dynamic subtest gt_lrc: SUCCESS (4.204s)
[306.630148] Starting dynamic subtest: gt_mocs
[308.510833] Dynamic subtest gt_mocs: SUCCESS (1.891s)
[308.522266] Starting dynamic subtest: gt_pm
[314.552826] Dynamic subtest gt_pm: SUCCESS (6.038s)
[314.560390] Starting dynamic subtest: gt_heartbeat
[316.418833] Dynamic subtest gt_heartbeat: SUCCESS (1.864s)
[316.427163] Starting dynamic subtest: gt_tlb
[319.287760] Dynamic subtest gt_tlb: SUCCESS (2.867s)
[319.296088] Starting dynamic subtest: requests
[326.728904] Dynamic subtest requests: SUCCESS (7.439s)
[326.737274] Starting dynamic subtest: migrate
[331.196669] Dynamic subtest migrate: SUCCESS (4.466s)
[331.206894] Starting dynamic subtest: active
[332.880767] Dynamic subtest active: SUCCESS (1.682s)
[332.890102] Starting dynamic subtest: objects
[335.640761] Dynamic subtest objects: SUCCESS (2.758s)
[335.649169] Starting dynamic subtest: mman
[340.796658] Dynamic subtest mman: SUCCESS (5.154s)
[340.803721] Starting dynamic subtest: dmabuf
[342.489700] Dynamic subtest dmabuf: SUCCESS (1.692s)
[342.497411] Starting dynamic subtest: vma
[344.201742] Dynamic subtest vma: SUCCESS (1.710s)
[344.210105] Starting dynamic subtest: coherency
[347.102633] Dynamic subtest coherency: SUCCESS (2.899s)
[347.110038] Starting dynamic subtest: gtt
[355.599932] Dynamic subtest gtt: SUCCESS (8.495s)
[355.614405] Starting dynamic subtest: gem
[357.525781] Dynamic subtest gem: SUCCESS (1.924s)
[357.538050] Starting dynamic subtest: evict
[360.670784] Dynamic subtest evict: SUCCESS (3.143s)
[360.682197] Starting dynamic subtest: hugepages
[367.704812] Dynamic subtest hugepages: SUCCESS (7.032s)
[367.713416] Starting dynamic subtest: gem_contexts
[382.256913] Dynamic subtest gem_contexts: SUCCESS (14.550s)
[382.269206] Starting dynamic subtest: client
[384.021929] Dynamic subtest client: SUCCESS (1.763s)
[384.031314] Starting dynamic subtest: gem_migrate
[385.734775] Dynamic subtest gem_migrate: SUCCESS (1.711s)
[385.742518] Starting dynamic subtest: reset
[388.128806] Dynamic subtest reset: SUCCESS (2.392s)
[388.137179] Starting dynamic subtest: memory_region
[389.851169] Dynamic subtest memory_region: SUCCESS (1.720s)
[389.866457] Starting dynamic subtest: hangcheck
[413.925223] Dynamic subtest hangcheck: SUCCESS (24.072s)
[413.939487] Starting dynamic subtest: execlists
[423.396944] Dynamic subtest execlists: SUCCESS (9.469s)
[423.406149] Starting dynamic subtest: ring_submission
[425.178968] Dynamic subtest ring_submission: SUCCESS (1.780s)
[425.188424] Starting dynamic subtest: perf
[427.084881] Dynamic subtest perf: SUCCESS (1.904s)
[427.097678] Starting dynamic subtest: slpc
[428.834454] Dynamic subtest slpc: SUCCESS (1.747s)
[428.847037] Starting dynamic subtest: guc
[430.634548] Dynamic subtest guc: SUCCESS (1.798s)
[430.642239] Starting dynamic subtest: guc_multi_lrc
[432.411696] Dynamic subtest guc_multi_lrc: SUCCESS (1.775s)
[432.422171] Starting dynamic subtest: guc_hang
[434.127757] Dynamic subtest guc_hang: SUCCESS (1.714s)
[434.135765] Starting dynamic subtest: late_gt_pm
[435.916896] Dynamic subtest late_gt_pm: SUCCESS (1.787s)
[435.918098] Subtest live: SUCCESS (152.683s)
[435.942278] [160/165] (574s left) dmabuf (all-tests)
[436.028862] Starting subtest: all-tests
[436.030587] Starting dynamic subtest: sanitycheck
[436.098618] Dynamic subtest sanitycheck: SUCCESS (0.068s)
[436.136403] Starting dynamic subtest: dma_fence
[436.295445] Dynamic subtest dma_fence: SUCCESS (0.191s)
[436.307574] Starting dynamic subtest: dma_fence_chain
[452.204346] Dynamic subtest dma_fence_chain: SUCCESS (15.898s)
[452.216810] Starting dynamic subtest: dma_fence_unwrap
[452.281304] Dynamic subtest dma_fence_unwrap: SUCCESS (0.082s)
[452.317627] Starting dynamic subtest: dma_resv
[452.364553] Dynamic subtest dma_resv: SUCCESS (0.075s)
[452.365977] Subtest all-tests: SUCCESS (16.337s)
[452.399950] [161/165] (557s left) i915_suspend (basic-s2idle-without-i915)
[452.479193] Starting subtest: basic-s2idle-without-i915
[471.719208] Subtest basic-s2idle-without-i915: SUCCESS (4.481s)
[474.854426] [162/165] (553s left) i915_suspend (basic-s3-without-i915)
[474.962576] Starting subtest: basic-s3-without-i915
[495.375791] Subtest basic-s3-without-i915: SUCCESS (4.188s)
[498.770583] [163/165] (548s left) gem_exec_suspend (basic-s0)
[498.880910] Starting subtest: basic-S0
[498.882037] Starting dynamic subtest: smem
[517.445893] Dynamic subtest smem: SUCCESS (3.239s)
[517.446868] Subtest basic-S0: SUCCESS (3.241s)
[519.735388] [164/165] (545s left) gem_exec_suspend (basic-s3)
[519.838054] Starting subtest: basic-S3
[519.839128] Starting dynamic subtest: smem
[540.619109] Dynamic subtest smem: SUCCESS (4.377s)
[540.623217] Subtest basic-S3: SUCCESS (4.379s)
[542.831345] [165/165] (540s left) kms_pipe_crc_basic (suspend-read-crc)
[542.951519] Starting subtest: suspend-read-crc
[542.954897] Starting dynamic subtest: pipe-A-eDP-1
[571.655492] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.698s)
[571.679828] Starting dynamic subtest: pipe-B-eDP-1
[592.605115] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.262s)
[592.631556] Starting dynamic subtest: pipe-C-eDP-1
[613.618367] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.287s)
[613.624533] Subtest suspend-read-crc: SUCCESS (21.282s)
[616.451183] Closing watchdogs
Done.