[46.432486] Initializing watchdogs
[46.432713]   /dev/watchdog0
[46.469605] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[46.481125] [001/162] (960s left) i915_module_load (load)
[46.597534] Starting subtest: load
[49.483897] Subtest load: SUCCESS (2.887s)
[49.568962] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[49.572478] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[49.579240] [002/162] (957s left) core_auth (basic-auth)
[49.699612] Starting subtest: basic-auth
[49.700921] Subtest basic-auth: SUCCESS (0.003s)
[49.859761] [003/162] (957s left) core_debugfs (read-all-entries)
[49.959314] Starting subtest: read-all-entries
[50.049770] Subtest read-all-entries: SUCCESS (0.091s)
[50.207225] [004/162] (957s left) core_sysfs (read-all-entries)
[50.304336] Starting subtest: read-all-entries
[50.310212] Subtest read-all-entries: SUCCESS (0.005s)
[50.460892] [005/162] (956s left) fbdev (eof)
[50.570184] Starting subtest: eof
[50.571326] Subtest eof: SUCCESS (0.001s)
[50.744490] [006/162] (956s left) fbdev (info)
[50.836629] Starting subtest: info
[50.838265] Subtest info: SUCCESS (0.000s)
[50.974246] [007/162] (956s left) fbdev (nullptr)
[51.085094] Starting subtest: nullptr
[51.087097] Subtest nullptr: SUCCESS (0.001s)
[51.227850] [008/162] (956s left) fbdev (read)
[51.333357] Starting subtest: read
[52.908897] Subtest read: SUCCESS (1.574s)
[53.060901] [009/162] (954s left) fbdev (write)
[53.167388] Starting subtest: write
[53.957972] Subtest write: SUCCESS (0.790s)
[54.116258] [010/162] (953s left) gem_basic (bad-close)
[54.214472] Starting subtest: bad-close
[54.217955] Subtest bad-close: SUCCESS (0.000s)
[54.381992] [011/162] (953s left) gem_basic (create-close)
[54.481424] Starting subtest: create-close
[54.483822] Subtest create-close: SUCCESS (0.000s)
[54.653279] [012/162] (953s left) gem_basic (create-fd-close)
[54.751732] Starting subtest: create-fd-close
[54.755887] Subtest create-fd-close: SUCCESS (0.001s)
[54.910828] [013/162] (953s left) gem_busy (busy@all-engines)
[55.044906] Starting subtest: busy
[55.045958] Starting dynamic subtest: all-engines
[55.123940] Dynamic subtest all-engines: SUCCESS (0.079s)
[55.126297] Subtest busy: SUCCESS (0.080s)
[55.563127] [014/162] (952s left) gem_close_race (basic-process)
[55.692073] Starting subtest: basic-process
[55.775731] Subtest basic-process: SUCCESS (0.082s)
[56.051193] [015/162] (952s left) gem_close_race (basic-threads)
[56.178796] Starting subtest: basic-threads
[57.299887] Subtest basic-threads: SUCCESS (1.122s)
[57.559963] [016/162] (950s left) gem_ctx_create (basic)
[57.677455] Starting subtest: basic
[57.678677] Subtest basic: SUCCESS (0.000s)
[57.945510] [017/162] (950s left) gem_ctx_create (basic-files)
[58.062992] Starting subtest: basic-files
[60.092103] Subtest basic-files: SUCCESS (2.030s)
[60.343046] [018/162] (948s left) gem_ctx_exec (basic)
[60.443313] Starting subtest: basic
[60.451827] Subtest basic: SUCCESS (0.008s)
[60.623917] [019/162] (947s left) gem_exec_basic (basic)
[60.734954] Starting subtest: basic
[60.736019] Starting dynamic subtest: rcs0-smem
[60.739888] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[60.755920] Starting dynamic subtest: bcs0-smem
[60.757279] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[60.773675] Starting dynamic subtest: vcs0-smem
[60.775302] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[60.800321] Starting dynamic subtest: vecs0-smem
[60.801842] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[60.803587] Subtest basic: SUCCESS (0.010s)
[61.012874] [020/162] (947s left) gem_exec_create (basic)
[61.134123] Starting subtest: basic
[61.135408] Starting dynamic subtest: smem
[63.183962] Dynamic subtest smem: SUCCESS (2.047s)
[63.185933] Subtest basic: SUCCESS (2.049s)
[63.438854] [021/162] (945s left) gem_exec_fence (basic-busy)
[63.696832] Starting subtest: basic-busy
[63.697948] Starting dynamic subtest: rcs0
[63.719939] Dynamic subtest rcs0: SUCCESS (0.024s)
[63.737520] Starting dynamic subtest: bcs0
[63.743283] Dynamic subtest bcs0: SUCCESS (0.022s)
[63.759568] Starting dynamic subtest: vcs0
[63.770156] Dynamic subtest vcs0: SUCCESS (0.026s)
[63.788441] Starting dynamic subtest: vecs0
[63.796829] Dynamic subtest vecs0: SUCCESS (0.025s)
[63.798012] Subtest basic-busy: SUCCESS (0.102s)
[64.583675] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[64.589506] [022/162] (944s left) gem_exec_fence (basic-wait)
[64.844500] Starting subtest: basic-wait
[64.845708] Starting dynamic subtest: rcs0
[64.865341] Dynamic subtest rcs0: SUCCESS (0.020s)
[64.884667] Starting dynamic subtest: bcs0
[64.892296] Dynamic subtest bcs0: SUCCESS (0.025s)
[64.908534] Starting dynamic subtest: vcs0
[64.912701] Dynamic subtest vcs0: SUCCESS (0.019s)
[64.930501] Starting dynamic subtest: vecs0
[64.931608] Dynamic subtest vecs0: SUCCESS (0.020s)
[64.933009] Subtest basic-wait: SUCCESS (0.088s)
[65.614928] [023/162] (943s left) gem_exec_fence (basic-await)
[65.867258] Starting subtest: basic-await
[65.869830] Starting dynamic subtest: rcs0
[65.984217] Dynamic subtest rcs0: SUCCESS (0.114s)
[66.001551] Starting dynamic subtest: bcs0
[66.113324] Dynamic subtest bcs0: SUCCESS (0.126s)
[66.148406] Starting dynamic subtest: vcs0
[66.280528] Dynamic subtest vcs0: SUCCESS (0.163s)
[66.309394] Starting dynamic subtest: vecs0
[66.425075] Dynamic subtest vecs0: SUCCESS (0.144s)
[66.426304] Subtest basic-await: SUCCESS (0.554s)
[67.150186] [024/162] (941s left) gem_exec_fence (nb-await)
[67.681639] Starting subtest: nb-await
[67.683411] Starting dynamic subtest: rcs0
[67.774554] Dynamic subtest rcs0: SUCCESS (0.095s)
[67.807173] Starting dynamic subtest: bcs0
[67.878376] Dynamic subtest bcs0: SUCCESS (0.098s)
[67.896598] Starting dynamic subtest: vcs0
[67.943913] Dynamic subtest vcs0: SUCCESS (0.067s)
[67.962223] Starting dynamic subtest: vecs0
[68.010256] Dynamic subtest vecs0: SUCCESS (0.067s)
[68.020803] Subtest nb-await: SUCCESS (0.340s)
[68.973618] [025/162] (940s left) gem_exec_gttfill (basic)
[69.190821] Starting subtest: basic
[79.238409] Subtest basic: SUCCESS (10.012s)
[79.532917] [026/162] (929s left) gem_exec_parallel (engines)
[79.796264] Starting subtest: engines
[79.797874] Starting dynamic subtest: basic
[83.128208] Dynamic subtest basic: SUCCESS (3.326s)
[83.145670] Starting dynamic subtest: contexts
[90.892097] Dynamic subtest contexts: SUCCESS (7.758s)
[90.910218] Starting dynamic subtest: fds
[95.695615] Dynamic subtest fds: SUCCESS (4.801s)
[95.716290] Starting dynamic subtest: userptr
[99.116544] Dynamic subtest userptr: SUCCESS (3.421s)
[99.117673] Subtest engines: SUCCESS (19.310s)
[99.396114] [027/162] (909s left) gem_exec_store (basic)
[99.522195] Starting subtest: basic
[99.563301] Subtest basic: SUCCESS (0.042s)
[99.832763] [028/162] (909s left) gem_flink_basic (bad-flink)
[99.926678] Starting subtest: bad-flink
[99.927980] Subtest bad-flink: SUCCESS (0.000s)
[100.074807] [029/162] (909s left) gem_flink_basic (bad-open)
[100.168149] Starting subtest: bad-open
[100.170311] Subtest bad-open: SUCCESS (0.000s)
[100.323752] [030/162] (909s left) gem_flink_basic (basic)
[100.418560] Starting subtest: basic
[100.419822] Subtest basic: SUCCESS (0.000s)
[100.571875] [031/162] (909s left) gem_flink_basic (double-flink)
[100.664579] Starting subtest: double-flink
[100.666235] Subtest double-flink: SUCCESS (0.000s)
[100.828159] [032/162] (908s left) gem_flink_basic (flink-lifetime)
[100.927601] Starting subtest: flink-lifetime
[100.928732] Subtest flink-lifetime: SUCCESS (0.003s)
[101.096432] [033/162] (908s left) gem_huc_copy (huc-copy)
[101.204803] Starting subtest: huc-copy
[101.207359] Subtest huc-copy: SKIP (0.000s)
[101.378162] [034/162] (908s left) gem_linear_blits (basic)
[101.608944] Starting subtest: basic
[101.629077] Subtest basic: SUCCESS (0.020s)
[101.845281] [035/162] (908s left) gem_mmap (basic)
[102.102456] Starting subtest: basic
[102.109463] Subtest basic: SUCCESS (0.001s)
[102.257971] [036/162] (907s left) gem_mmap_gtt (basic)
[102.356892] Starting subtest: basic
[102.358498] Subtest basic: SUCCESS (0.002s)
[102.527651] [037/162] (907s left) gem_render_linear_blits (basic)
[102.634878] Starting subtest: basic
[102.706581] Subtest basic: SUCCESS (0.071s)
[102.816024] [038/162] (907s left) gem_render_tiled_blits (basic)
[102.921745] Starting subtest: basic
[103.019183] Subtest basic: SUCCESS (0.096s)
[103.129389] [039/162] (907s left) gem_ringfill (basic-all)
[103.517179] Starting subtest: basic-all
[104.705246] Subtest basic-all: SUCCESS (1.186s)
[104.893532] [040/162] (905s left) gem_softpin (allocator-basic)
[105.001593] Starting subtest: allocator-basic
[105.241601] Subtest allocator-basic: SUCCESS (0.221s)
[105.422501] [041/162] (905s left) gem_softpin (allocator-basic-reserve)
[105.526821] Starting subtest: allocator-basic-reserve
[105.747799] Subtest allocator-basic-reserve: SUCCESS (0.219s)
[105.945055] [042/162] (904s left) gem_softpin (safe-alignment)
[106.056287] Starting subtest: safe-alignment
[106.080736] Subtest safe-alignment: SUCCESS (0.026s)
[106.249447] [043/162] (904s left) gem_sync (basic-all)
[106.372632] Starting subtest: basic-all
[108.620242] Subtest basic-all: SUCCESS (2.248s)
[108.897830] [044/162] (901s left) gem_sync (basic-each)
[109.024636] Starting subtest: basic-each
[111.403258] Subtest basic-each: SUCCESS (2.382s)
[111.675660] [045/162] (899s left) gem_tiled_blits (basic)
[111.880887] Starting subtest: basic
[111.915762] Subtest basic: SUCCESS (0.035s)
[112.128607] [046/162] (898s left) gem_tiled_fence_blits (basic)
[112.499437] Starting subtest: basic
[112.533220] Subtest basic: SUCCESS (0.034s)
[112.747120] [047/162] (898s left) gem_tiled_pread_basic
[113.391637] [048/162] (897s left) gem_wait (busy@all-engines)
[113.524394] Starting subtest: busy
[113.526055] Starting dynamic subtest: all-engines
[114.047289] Dynamic subtest all-engines: SUCCESS (0.523s)
[114.050863] Subtest busy: SUCCESS (0.524s)
[114.362856] [049/162] (896s left) gem_wait (wait@all-engines)
[114.490434] Starting subtest: wait
[114.491742] Starting dynamic subtest: all-engines
[115.529945] Dynamic subtest all-engines: SUCCESS (1.038s)
[115.531059] Subtest wait: SUCCESS (1.039s)
[115.826081] [050/162] (895s left) i915_getparams_basic (basic-eu-total)
[115.919439] Starting subtest: basic-eu-total
[115.921750] Subtest basic-eu-total: SUCCESS (0.000s)
[116.074411] [051/162] (895s left) i915_getparams_basic (basic-subslice-total)
[116.169248] Starting subtest: basic-subslice-total
[116.173405] Subtest basic-subslice-total: SUCCESS (0.000s)
[116.328298] [052/162] (895s left) i915_hangman (error-state-basic)
[116.460775] Starting subtest: error-state-basic
[116.472058] Subtest error-state-basic: SUCCESS (0.014s)
[116.640985] [053/162] (895s left) i915_pciid
[116.907425] [054/162] (894s left) intel_hwmon (hwmon-read)
[117.002542] Subtest hwmon-read: SKIP (0.000s)
[117.113417] [055/162] (894s left) intel_hwmon (hwmon-write)
[117.211099] Subtest hwmon-write: SKIP (0.000s)
[117.321593] [056/162] (894s left) kms_addfb_basic (addfb25-4-tiled)
[117.500456] Starting subtest: addfb25-4-tiled
[117.501604] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[117.672055] [057/162] (894s left) kms_addfb_basic (addfb25-bad-modifier)
[117.834682] Starting subtest: addfb25-bad-modifier
[117.837069] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[118.006264] [058/162] (894s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[118.178567] Starting subtest: addfb25-framebuffer-vs-set-tiling
[118.180068] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[118.355517] [059/162] (893s left) kms_addfb_basic (addfb25-modifier-no-flag)
[118.515120] Starting subtest: addfb25-modifier-no-flag
[118.516226] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[118.699598] [060/162] (893s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[118.861411] Starting subtest: addfb25-x-tiled-legacy
[118.862608] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[119.038249] [061/162] (893s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[119.203414] Starting subtest: addfb25-x-tiled-mismatch-legacy
[119.205940] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[119.390890] [062/162] (893s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[119.552805] Starting subtest: addfb25-yf-tiled-legacy
[119.553903] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[119.724785] [063/162] (892s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[119.902767] Starting subtest: addfb25-y-tiled-legacy
[119.905467] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[120.074320] [064/162] (892s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[120.229797] Starting subtest: addfb25-y-tiled-small-legacy
[120.231184] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[120.407542] [065/162] (892s left) kms_addfb_basic (bad-pitch-0)
[120.505798] Starting subtest: bad-pitch-0
[120.506929] Subtest bad-pitch-0: SUCCESS (0.000s)
[120.740046] [066/162] (892s left) kms_addfb_basic (bad-pitch-1024)
[120.836099] Starting subtest: bad-pitch-1024
[120.837184] Subtest bad-pitch-1024: SUCCESS (0.000s)
[121.055544] [067/162] (891s left) kms_addfb_basic (bad-pitch-128)
[121.153122] Starting subtest: bad-pitch-128
[121.154205] Subtest bad-pitch-128: SUCCESS (0.000s)
[121.391319] [068/162] (891s left) kms_addfb_basic (bad-pitch-256)
[121.491964] Starting subtest: bad-pitch-256
[121.493059] Subtest bad-pitch-256: SUCCESS (0.000s)
[121.734628] [069/162] (891s left) kms_addfb_basic (bad-pitch-32)
[121.830506] Starting subtest: bad-pitch-32
[121.831637] Subtest bad-pitch-32: SUCCESS (0.000s)
[122.055319] [070/162] (891s left) kms_addfb_basic (bad-pitch-63)
[122.152920] Starting subtest: bad-pitch-63
[122.154040] Subtest bad-pitch-63: SUCCESS (0.000s)
[122.357617] [071/162] (891s left) kms_addfb_basic (bad-pitch-65536)
[122.453412] Starting subtest: bad-pitch-65536
[122.454506] Subtest bad-pitch-65536: SUCCESS (0.001s)
[122.689145] [072/162] (890s left) kms_addfb_basic (bad-pitch-999)
[122.784289] Starting subtest: bad-pitch-999
[122.785372] Subtest bad-pitch-999: SUCCESS (0.000s)
[123.024952] [073/162] (890s left) kms_addfb_basic (basic)
[123.124798] Starting subtest: basic
[123.125971] Subtest basic: SUCCESS (0.000s)
[123.341865] [074/162] (890s left) kms_addfb_basic (basic-x-tiled-legacy)
[123.437419] Starting subtest: basic-x-tiled-legacy
[123.438800] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[123.673034] [075/162] (890s left) kms_addfb_basic (basic-y-tiled-legacy)
[123.773322] Starting subtest: basic-y-tiled-legacy
[123.774858] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[123.990776] [076/162] (889s left) kms_addfb_basic (bo-too-small)
[124.151625] Starting subtest: bo-too-small
[124.153984] Subtest bo-too-small: SUCCESS (0.001s)
[124.341985] [077/162] (889s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[124.507917] Starting subtest: bo-too-small-due-to-tiling
[124.509211] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[124.673736] [078/162] (889s left) kms_addfb_basic (clobberred-modifier)
[124.772210] Starting subtest: clobberred-modifier
[124.773275] Subtest clobberred-modifier: SUCCESS (0.000s)
[124.989966] [079/162] (889s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[125.087995] Starting subtest: framebuffer-vs-set-tiling
[125.092056] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[125.306301] [080/162] (888s left) kms_addfb_basic (invalid-get-prop)
[125.406238] Starting subtest: invalid-get-prop
[125.407848] Subtest invalid-get-prop: SUCCESS (0.000s)
[125.641498] [081/162] (888s left) kms_addfb_basic (invalid-get-prop-any)
[125.738894] Starting subtest: invalid-get-prop-any
[125.739956] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[125.973351] [082/162] (888s left) kms_addfb_basic (invalid-set-prop)
[126.069172] Starting subtest: invalid-set-prop
[126.070234] Subtest invalid-set-prop: SUCCESS (0.000s)
[126.292771] [083/162] (888s left) kms_addfb_basic (invalid-set-prop-any)
[126.394222] Starting subtest: invalid-set-prop-any
[126.395824] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[126.642831] [084/162] (887s left) kms_addfb_basic (no-handle)
[126.738302] Starting subtest: no-handle
[126.740706] Subtest no-handle: SUCCESS (0.000s)
[126.955712] [085/162] (887s left) kms_addfb_basic (size-max)
[127.119569] Starting subtest: size-max
[127.120883] Subtest size-max: SUCCESS (0.000s)
[127.297997] [086/162] (887s left) kms_addfb_basic (small-bo)
[127.455214] Starting subtest: small-bo
[127.456343] Subtest small-bo: SUCCESS (0.000s)
[127.639989] [087/162] (887s left) kms_addfb_basic (tile-pitch-mismatch)
[127.740248] Starting subtest: tile-pitch-mismatch
[127.741804] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[127.977277] [088/162] (887s left) kms_addfb_basic (too-high)
[128.138697] Starting subtest: too-high
[128.141800] Subtest too-high: SUCCESS (0.004s)
[128.334311] [089/162] (886s left) kms_addfb_basic (too-wide)
[128.499219] Starting subtest: too-wide
[128.500256] Subtest too-wide: SUCCESS (0.000s)
[128.673120] [090/162] (886s left) kms_addfb_basic (unused-handle)
[128.771402] Starting subtest: unused-handle
[128.772608] Subtest unused-handle: SUCCESS (0.000s)
[129.025973] [091/162] (886s left) kms_addfb_basic (unused-modifier)
[129.122409] Starting subtest: unused-modifier
[129.123982] Subtest unused-modifier: SUCCESS (0.000s)
[129.373433] [092/162] (886s left) kms_addfb_basic (unused-offsets)
[129.468982] Starting subtest: unused-offsets
[129.470253] Subtest unused-offsets: SUCCESS (0.000s)
[129.689564] [093/162] (885s left) kms_addfb_basic (unused-pitches)
[129.786371] Starting subtest: unused-pitches
[129.787443] Subtest unused-pitches: SUCCESS (0.000s)
[130.041147] [094/162] (885s left) kms_busy (basic)
[130.206801] Starting subtest: basic
[130.208455] Starting dynamic subtest: flip
[130.993413] Dynamic subtest flip: SUCCESS (0.777s)
[131.036345] Starting dynamic subtest: modeset
[132.282107] Dynamic subtest modeset: SUCCESS (1.292s)
[132.283345] Subtest basic: SUCCESS (2.077s)
[132.574652] [095/162] (883s left) kms_prop_blob (basic)
[132.674059] Starting subtest: basic
[132.675244] Subtest basic: SUCCESS (0.000s)
[132.827597] [096/162] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[132.993156] Starting subtest: basic-busy-flip-before-cursor-atomic
[132.995419] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[133.163906] [097/162] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[133.325039] Starting subtest: basic-busy-flip-before-cursor-legacy
[133.327253] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[133.489355] [098/162] (882s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[133.645620] Starting subtest: basic-flip-after-cursor-atomic
[134.776765] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.134s)
[135.058944] [099/162] (880s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[135.214349] Starting subtest: basic-flip-after-cursor-legacy
[136.338878] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.127s)
[136.596058] [100/162] (879s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[136.755204] Starting subtest: basic-flip-after-cursor-varying-size
[137.932315] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.176s)
[138.185352] [101/162] (878s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[138.353273] Starting subtest: basic-flip-before-cursor-atomic
[139.525377] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.173s)
[139.795587] [102/162] (876s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[139.954791] Starting subtest: basic-flip-before-cursor-legacy
[141.084261] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.127s)
[141.339618] [103/162] (875s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[141.495085] Starting subtest: basic-flip-before-cursor-varying-size
[142.618404] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.124s)
[142.857981] [104/162] (873s left) kms_dsc (dsc-basic)
[143.011942] Subtest dsc-basic: SKIP (0.000s)
[143.114890] [105/162] (873s left) kms_flip (basic-flip-vs-dpms)
[143.268310] Starting subtest: basic-flip-vs-dpms
[143.275053] Starting dynamic subtest: A-HDMI-A2
[144.471846] Dynamic subtest A-HDMI-A2: SUCCESS (1.197s)
[144.491454] Starting dynamic subtest: B-HDMI-A2
[145.432794] Dynamic subtest B-HDMI-A2: SUCCESS (0.961s)
[145.471984] Starting dynamic subtest: C-HDMI-A2
[146.585198] Dynamic subtest C-HDMI-A2: SUCCESS (1.145s)
[146.586954] Subtest basic-flip-vs-dpms: SUCCESS (3.314s)
[147.087833] [106/162] (869s left) kms_flip (basic-flip-vs-modeset)
[147.327553] Starting subtest: basic-flip-vs-modeset
[147.333796] Starting dynamic subtest: A-HDMI-A2
[148.591368] Dynamic subtest A-HDMI-A2: SUCCESS (1.256s)
[148.616441] Starting dynamic subtest: B-HDMI-A2
[149.591985] Dynamic subtest B-HDMI-A2: SUCCESS (0.994s)
[149.611830] Starting dynamic subtest: C-HDMI-A2
[150.562245] Dynamic subtest C-HDMI-A2: SUCCESS (0.968s)
[150.564545] Subtest basic-flip-vs-modeset: SUCCESS (3.233s)
[150.843431] [107/162] (866s left) kms_flip (basic-flip-vs-wf_vblank)
[151.017486] Starting subtest: basic-flip-vs-wf_vblank
[151.022773] Starting dynamic subtest: A-HDMI-A2
[152.411084] Dynamic subtest A-HDMI-A2: SUCCESS (1.385s)
[152.433074] Starting dynamic subtest: B-HDMI-A2
[153.730905] Dynamic subtest B-HDMI-A2: SUCCESS (1.314s)
[153.750939] Starting dynamic subtest: C-HDMI-A2
[155.052360] Dynamic subtest C-HDMI-A2: SUCCESS (1.317s)
[155.053499] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.034s)
[155.493417] [108/162] (861s left) kms_flip (basic-plain-flip)
[155.653407] Starting subtest: basic-plain-flip
[155.657149] Starting dynamic subtest: A-HDMI-A2
[156.762600] Dynamic subtest A-HDMI-A2: SUCCESS (1.103s)
[156.782687] Starting dynamic subtest: B-HDMI-A2
[157.804822] Dynamic subtest B-HDMI-A2: SUCCESS (1.038s)
[157.826472] Starting dynamic subtest: C-HDMI-A2
[158.846941] Dynamic subtest C-HDMI-A2: SUCCESS (1.038s)
[158.848680] Subtest basic-plain-flip: SUCCESS (3.195s)
[159.284927] [109/162] (857s left) kms_force_connector_basic (force-connector-state)
[159.467419] Starting subtest: force-connector-state
[159.894617] Subtest force-connector-state: SUCCESS (0.421s)
[160.363150] [110/162] (856s left) kms_force_connector_basic (force-edid)
[160.560865] Starting subtest: force-edid
[160.616365] Subtest force-edid: SUCCESS (0.052s)
[160.950105] [111/162] (856s left) kms_force_connector_basic (force-load-detect)
[161.156311] Starting subtest: force-load-detect
[161.162059] Subtest force-load-detect: SKIP (0.000s)
[161.445215] [112/162] (855s left) kms_force_connector_basic (prune-stale-modes)
[161.629503] Starting subtest: prune-stale-modes
[161.699773] Subtest prune-stale-modes: SUCCESS (0.068s)
[162.012989] [113/162] (855s left) kms_frontbuffer_tracking (basic)
[162.208992] Starting subtest: basic
[165.375087] Subtest basic: SUCCESS (3.165s)
[165.547585] [114/162] (852s left) kms_hdmi_inject (inject-audio)
[165.863371] Starting subtest: inject-audio
[166.073720] Subtest inject-audio: SUCCESS (0.212s)
[166.525634] [115/162] (851s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[166.696587] Starting subtest: compare-crc-sanitycheck-xr24
[166.702782] Starting dynamic subtest: pipe-A-HDMI-A-2
[167.295231] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.587s)
[167.342118] Starting dynamic subtest: pipe-B-HDMI-A-2
[167.860110] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.561s)
[167.888296] Starting dynamic subtest: pipe-C-HDMI-A-2
[168.434442] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.566s)
[168.435507] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.734s)
[168.696481] [116/162] (849s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[168.849842] Starting subtest: compare-crc-sanitycheck-nv12
[168.857114] Starting dynamic subtest: pipe-A-HDMI-A-2
[169.544948] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.691s)
[169.580803] Starting dynamic subtest: pipe-B-HDMI-A-2
[170.226832] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.675s)
[170.252617] Starting dynamic subtest: pipe-C-HDMI-A-2
[170.896356] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.659s)
[170.898917] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.044s)
[171.157177] [117/162] (846s left) kms_pipe_crc_basic (hang-read-crc)
[171.314764] Starting subtest: hang-read-crc
[171.323777] Starting dynamic subtest: pipe-A-HDMI-A-2
[172.676053] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.357s)
[172.698471] Starting dynamic subtest: pipe-B-HDMI-A-2
[173.993913] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.312s)
[174.020099] Starting dynamic subtest: pipe-C-HDMI-A-2
[175.325529] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.321s)
[175.327071] Subtest hang-read-crc: SUCCESS (4.010s)
[175.575539] [118/162] (842s left) kms_pipe_crc_basic (nonblocking-crc)
[175.751305] Starting subtest: nonblocking-crc
[175.755223] Starting dynamic subtest: pipe-A-HDMI-A-2
[176.489127] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.731s)
[176.556530] Starting dynamic subtest: pipe-B-HDMI-A-2
[177.216821] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.722s)
[177.241326] Starting dynamic subtest: pipe-C-HDMI-A-2
[177.920145] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.696s)
[177.922390] Subtest nonblocking-crc: SUCCESS (2.168s)
[178.189928] [119/162] (839s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[178.360109] Starting subtest: nonblocking-crc-frame-sequence
[178.361336] Starting dynamic subtest: pipe-A-HDMI-A-2
[179.094915] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.736s)
[179.120292] Starting dynamic subtest: pipe-B-HDMI-A-2
[179.816704] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.708s)
[179.840165] Starting dynamic subtest: pipe-C-HDMI-A-2
[180.537603] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.716s)
[180.539481] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.182s)
[180.791173] [120/162] (837s left) kms_pipe_crc_basic (read-crc)
[180.962735] Starting subtest: read-crc
[180.963799] Starting dynamic subtest: pipe-A-HDMI-A-2
[181.634075] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.670s)
[181.659272] Starting dynamic subtest: pipe-B-HDMI-A-2
[182.287475] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.646s)
[182.335363] Starting dynamic subtest: pipe-C-HDMI-A-2
[182.925639] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.629s)
[182.928069] Subtest read-crc: SUCCESS (1.966s)
[183.157810] [121/162] (835s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[183.332066] Starting subtest: read-crc-frame-sequence
[183.335837] Starting dynamic subtest: pipe-A-HDMI-A-2
[184.025885] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.688s)
[184.071282] Starting dynamic subtest: pipe-B-HDMI-A-2
[184.692248] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.657s)
[184.715364] Starting dynamic subtest: pipe-C-HDMI-A-2
[185.350834] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.653s)
[185.351989] Subtest read-crc-frame-sequence: SUCCESS (2.022s)
[185.607819] [122/162] (832s left) kms_pm_backlight (basic-brightness)
[185.768961] Subtest basic-brightness: SKIP (0.000s)
[185.882410] [123/162] (832s left) kms_pm_rpm (basic-pci-d3-state)
[187.555570] Starting subtest: basic-pci-d3-state
[187.889403] Subtest basic-pci-d3-state: SUCCESS (0.302s)
[188.091839] [124/162] (830s left) kms_pm_rpm (basic-rte)
[188.189165] Starting subtest: basic-rte
[190.090448] Subtest basic-rte: SUCCESS (1.902s)
[190.893741] [125/162] (827s left) kms_psr (psr-primary-page-flip)
[191.062363] Subtest psr-primary-page-flip: SKIP (0.000s)
[191.181721] [126/162] (827s left) kms_psr (psr-cursor-plane-move)
[191.334625] Subtest psr-cursor-plane-move: SKIP (0.000s)
[191.447665] [127/162] (827s left) kms_psr (psr-sprite-plane-onoff)
[191.608731] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[191.713809] [128/162] (827s left) kms_psr (psr-primary-mmap-gtt)
[191.891350] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[191.997260] [129/162] (826s left) kms_setmode (basic-clone-single-crtc)
[192.101451] Starting subtest: basic-clone-single-crtc
[192.106220] Subtest basic-clone-single-crtc: SKIP (0.000s)
[192.256662] [130/162] (826s left) i915_pm_rps (basic-api)
[192.372047] Starting subtest: basic-api
[192.381917] Subtest basic-api: SUCCESS (0.011s)
[192.557127] [131/162] (826s left) prime_self_import (basic-llseek-bad)
[192.659431] Starting subtest: basic-llseek-bad
[192.663542] Subtest basic-llseek-bad: SUCCESS (0.006s)
[192.841765] [132/162] (826s left) prime_self_import (basic-llseek-size)
[192.934133] Starting subtest: basic-llseek-size
[192.941970] Subtest basic-llseek-size: SUCCESS (0.007s)
[193.126283] [133/162] (826s left) prime_self_import (basic-with_fd_dup)
[193.223281] Starting subtest: basic-with_fd_dup
[193.232827] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[193.392324] [134/162] (826s left) prime_self_import (basic-with_one_bo)
[193.487199] Starting subtest: basic-with_one_bo
[193.494192] Subtest basic-with_one_bo: SUCCESS (0.007s)
[193.668850] [135/162] (825s left) prime_self_import (basic-with_one_bo_two_files)
[193.763272] Starting subtest: basic-with_one_bo_two_files
[193.770883] Subtest basic-with_one_bo_two_files: SUCCESS (0.007s)
[193.922959] [136/162] (825s left) prime_self_import (basic-with_two_bos)
[194.018780] Starting subtest: basic-with_two_bos
[194.030249] Subtest basic-with_two_bos: SUCCESS (0.010s)
[194.190857] [137/162] (825s left) prime_vgem (basic-fence-flip)
[194.317096] Starting subtest: basic-fence-flip
[194.817626] Subtest basic-fence-flip: SUCCESS (0.497s)
[195.011360] [138/162] (824s left) prime_vgem (basic-fence-mmap)
[195.132244] Starting subtest: basic-fence-mmap
[195.184643] Subtest basic-fence-mmap: SUCCESS (0.054s)
[195.376429] [139/162] (824s left) prime_vgem (basic-fence-read)
[195.500798] Starting subtest: basic-fence-read
[195.622472] Subtest basic-fence-read: SUCCESS (0.120s)
[195.790737] [140/162] (824s left) prime_vgem (basic-gtt)
[195.915071] Starting subtest: basic-gtt
[195.943649] Subtest basic-gtt: SUCCESS (0.029s)
[196.146162] [141/162] (824s left) prime_vgem (basic-read)
[196.272589] Starting subtest: basic-read
[196.333599] Subtest basic-read: SUCCESS (0.061s)
[196.509328] [142/162] (823s left) prime_vgem (basic-write)
[196.634332] Starting subtest: basic-write
[196.719406] Subtest basic-write: SUCCESS (0.084s)
[196.892970] [143/162] (823s left) vgem_basic (setversion)
[197.004777] Starting subtest: setversion
[197.007219] Subtest setversion: SUCCESS (0.000s)
[197.158893] [144/162] (823s left) vgem_basic (create)
[197.273437] Starting subtest: create
[197.274520] Subtest create: SUCCESS (0.001s)
[197.426988] [145/162] (823s left) vgem_basic (debugfs)
[197.535349] Starting subtest: debugfs
[197.536489] Subtest debugfs: SUCCESS (0.001s)
[197.674975] [146/162] (822s left) vgem_basic (dmabuf-export)
[197.786292] Starting subtest: dmabuf-export
[197.788528] Subtest dmabuf-export: SUCCESS (0.004s)
[197.978602] [147/162] (822s left) vgem_basic (dmabuf-fence)
[198.084128] Starting subtest: dmabuf-fence
[198.085210] Subtest dmabuf-fence: SUCCESS (0.001s)
[198.241888] [148/162] (822s left) vgem_basic (dmabuf-fence-before)
[198.356409] Starting subtest: dmabuf-fence-before
[198.357802] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[198.491951] [149/162] (822s left) vgem_basic (dmabuf-mmap)
[198.605085] Starting subtest: dmabuf-mmap
[198.630626] Subtest dmabuf-mmap: SUCCESS (0.029s)
[198.793444] [150/162] (822s left) vgem_basic (mmap)
[198.903303] Starting subtest: mmap
[198.921749] Subtest mmap: SUCCESS (0.019s)
[199.064595] [151/162] (822s left) vgem_basic (second-client)
[199.172765] Starting subtest: second-client
[199.203132] Subtest second-client: SUCCESS (0.031s)
[199.342358] [152/162] (821s left) vgem_basic (sysfs)
[199.455927] Starting subtest: sysfs
[199.457083] Subtest sysfs: SUCCESS (0.002s)
[199.612435] [153/162] (821s left) core_hotunplug (unbind-rebind)
[199.836340] Starting subtest: unbind-rebind
[203.656868] Subtest unbind-rebind: SUCCESS (3.820s)
[203.755684] [154/162] (817s left) vgem_basic (unload)
[203.857311] Starting subtest: unload
[232.811488] Subtest unload: SUCCESS (28.953s)
[233.041696] [155/162] (788s left) i915_module_load (reload)
[233.135956] Starting subtest: reload
[237.393782] Subtest reload: SUCCESS (4.256s)
[237.619591] [156/162] (784s left) gem_lmem_swapping (basic)
[237.749044] Subtest basic: SKIP (0.000s)
[237.855493] [157/162] (783s left) gem_lmem_swapping (parallel-random-engines)
[237.966250] Subtest parallel-random-engines: SKIP (0.000s)
[238.081969] [158/162] (783s left) gem_lmem_swapping (random-engines)
[238.189938] Subtest random-engines: SKIP (0.000s)
[238.290846] [159/162] (783s left) gem_lmem_swapping (verify-random)
[238.399083] Subtest verify-random: SKIP (0.000s)
[238.505385] [160/162] (783s left) i915_pm_rpm (module-reload)