[40.181318] Initializing watchdogs
[40.181513]   /dev/watchdog0
[40.216503] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[40.229815] [001/162] (960s left) i915_module_load (load)
[40.345378] Starting subtest: load
[43.113646] Subtest load: SUCCESS (2.762s)
[43.251750] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[43.254659] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[43.262308] [002/162] (957s left) core_auth (basic-auth)
[43.438724] Starting subtest: basic-auth
[43.441873] Subtest basic-auth: SUCCESS (0.002s)
[43.603289] [003/162] (957s left) core_debugfs (read-all-entries)
[43.700809] Starting subtest: read-all-entries
[43.793233] Subtest read-all-entries: SUCCESS (0.093s)
[43.934143] [004/162] (957s left) core_sysfs (read-all-entries)
[44.035443] Starting subtest: read-all-entries
[44.041192] Subtest read-all-entries: SUCCESS (0.006s)
[44.189916] [005/162] (956s left) fbdev (eof)
[44.291531] Starting subtest: eof
[44.293047] Subtest eof: SUCCESS (0.002s)
[44.469443] [006/162] (956s left) fbdev (info)
[44.563193] Starting subtest: info
[44.564370] Subtest info: SUCCESS (0.000s)
[44.699122] [007/162] (956s left) fbdev (nullptr)
[44.809184] Starting subtest: nullptr
[44.810243] Subtest nullptr: SUCCESS (0.000s)
[44.933967] [008/162] (956s left) fbdev (read)
[45.042223] Starting subtest: read
[46.566602] Subtest read: SUCCESS (1.524s)
[46.723855] [009/162] (954s left) fbdev (write)
[46.826394] Starting subtest: write
[47.619945] Subtest write: SUCCESS (0.793s)
[47.748199] [010/162] (953s left) gem_basic (bad-close)
[47.843315] Starting subtest: bad-close
[47.845854] Subtest bad-close: SUCCESS (0.000s)
[48.000945] [011/162] (953s left) gem_basic (create-close)
[48.097534] Starting subtest: create-close
[48.100710] Subtest create-close: SUCCESS (0.000s)
[48.268557] [012/162] (953s left) gem_basic (create-fd-close)
[48.364389] Starting subtest: create-fd-close
[48.367732] Subtest create-fd-close: SUCCESS (0.001s)
[48.517349] [013/162] (953s left) gem_busy (busy@all-engines)
[48.653488] Starting subtest: busy
[48.657730] Starting dynamic subtest: all-engines
[48.729977] Dynamic subtest all-engines: SUCCESS (0.076s)
[48.731206] Subtest busy: SUCCESS (0.077s)
[49.152115] [014/162] (952s left) gem_close_race (basic-process)
[49.279296] Starting subtest: basic-process
[49.349700] Subtest basic-process: SUCCESS (0.070s)
[49.599313] [015/162] (952s left) gem_close_race (basic-threads)
[49.721267] Starting subtest: basic-threads
[50.908279] Subtest basic-threads: SUCCESS (1.186s)
[51.195838] [016/162] (950s left) gem_ctx_create (basic)
[51.321378] Starting subtest: basic
[51.322428] Subtest basic: SUCCESS (0.000s)
[51.586103] [017/162] (950s left) gem_ctx_create (basic-files)
[51.703959] Starting subtest: basic-files
[53.730874] Subtest basic-files: SUCCESS (2.025s)
[54.014731] [018/162] (948s left) gem_ctx_exec (basic)
[54.114979] Starting subtest: basic
[54.122352] Subtest basic: SUCCESS (0.007s)
[54.287455] [019/162] (947s left) gem_exec_basic (basic)
[54.400238] Starting subtest: basic
[54.401360] Starting dynamic subtest: rcs0-smem
[54.405459] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[54.422450] Starting dynamic subtest: bcs0-smem
[54.423800] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[54.439836] Starting dynamic subtest: vcs0-smem
[54.441827] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[54.463238] Starting dynamic subtest: vecs0-smem
[54.464900] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[54.467136] Subtest basic: SUCCESS (0.011s)
[54.720315] [020/162] (947s left) gem_exec_create (basic)
[54.839555] Starting subtest: basic
[54.842056] Starting dynamic subtest: smem
[56.890393] Dynamic subtest smem: SUCCESS (2.050s)
[56.891635] Subtest basic: SUCCESS (2.051s)
[57.173959] [021/162] (945s left) gem_exec_fence (basic-busy)
[57.423982] Starting subtest: basic-busy
[57.425686] Starting dynamic subtest: rcs0
[57.442703] Dynamic subtest rcs0: SUCCESS (0.020s)
[57.461151] Starting dynamic subtest: bcs0
[57.485051] Dynamic subtest bcs0: SUCCESS (0.040s)
[57.501104] Starting dynamic subtest: vcs0
[57.513766] Dynamic subtest vcs0: SUCCESS (0.028s)
[57.531370] Starting dynamic subtest: vecs0
[57.534708] Dynamic subtest vecs0: SUCCESS (0.021s)
[57.535853] Subtest basic-busy: SUCCESS (0.115s)
[58.290628] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[58.295879] [022/162] (944s left) gem_exec_fence (basic-wait)
[58.544082] Starting subtest: basic-wait
[58.545147] Starting dynamic subtest: rcs0
[58.568888] Dynamic subtest rcs0: SUCCESS (0.023s)
[58.584934] Starting dynamic subtest: bcs0
[58.594663] Dynamic subtest bcs0: SUCCESS (0.027s)
[58.612925] Starting dynamic subtest: vcs0
[58.617356] Dynamic subtest vcs0: SUCCESS (0.023s)
[58.633438] Starting dynamic subtest: vecs0
[58.644880] Dynamic subtest vecs0: SUCCESS (0.025s)
[58.646110] Subtest basic-wait: SUCCESS (0.102s)
[59.359785] [023/162] (943s left) gem_exec_fence (basic-await)
[59.602325] Starting subtest: basic-await
[59.603415] Starting dynamic subtest: rcs0
[59.721609] Dynamic subtest rcs0: SUCCESS (0.120s)
[59.737775] Starting dynamic subtest: bcs0
[59.830363] Dynamic subtest bcs0: SUCCESS (0.110s)
[59.849073] Starting dynamic subtest: vcs0
[59.945088] Dynamic subtest vcs0: SUCCESS (0.116s)
[59.964117] Starting dynamic subtest: vecs0
[60.054333] Dynamic subtest vecs0: SUCCESS (0.113s)
[60.055892] Subtest basic-await: SUCCESS (0.466s)
[60.751874] [024/162] (941s left) gem_exec_fence (nb-await)
[60.989220] Starting subtest: nb-await
[60.991137] Starting dynamic subtest: rcs0
[61.062238] Dynamic subtest rcs0: SUCCESS (0.073s)
[61.078088] Starting dynamic subtest: bcs0
[61.129451] Dynamic subtest bcs0: SUCCESS (0.069s)
[61.145487] Starting dynamic subtest: vcs0
[61.200896] Dynamic subtest vcs0: SUCCESS (0.071s)
[61.217038] Starting dynamic subtest: vecs0
[61.267752] Dynamic subtest vecs0: SUCCESS (0.068s)
[61.270208] Subtest nb-await: SUCCESS (0.286s)
[61.964519] [025/162] (940s left) gem_exec_gttfill (basic)
[62.088614] Starting subtest: basic
[72.715101] Subtest basic: SUCCESS (10.747s)
[72.992861] [026/162] (929s left) gem_exec_parallel (engines)
[73.345158] Starting subtest: engines
[73.346278] Starting dynamic subtest: basic
[76.698801] Dynamic subtest basic: SUCCESS (3.367s)
[76.716463] Starting dynamic subtest: contexts
[81.340039] Dynamic subtest contexts: SUCCESS (4.651s)
[81.358107] Starting dynamic subtest: fds
[91.176363] Dynamic subtest fds: SUCCESS (9.846s)
[91.201312] Starting dynamic subtest: userptr
[94.587519] Dynamic subtest userptr: SUCCESS (3.412s)
[94.588802] Subtest engines: SUCCESS (21.278s)
[94.872775] [027/162] (908s left) gem_exec_store (basic)
[95.023180] Starting subtest: basic
[95.066746] Subtest basic: SUCCESS (0.045s)
[95.339522] [028/162] (907s left) gem_flink_basic (bad-flink)
[95.433726] Starting subtest: bad-flink
[95.435083] Subtest bad-flink: SUCCESS (0.000s)
[95.599984] [029/162] (907s left) gem_flink_basic (bad-open)
[95.695451] Starting subtest: bad-open
[95.696561] Subtest bad-open: SUCCESS (0.000s)
[95.874019] [030/162] (907s left) gem_flink_basic (basic)
[95.971142] Starting subtest: basic
[95.972337] Subtest basic: SUCCESS (0.000s)
[96.154766] [031/162] (907s left) gem_flink_basic (double-flink)
[96.277202] Starting subtest: double-flink
[96.278393] Subtest double-flink: SUCCESS (0.001s)
[96.448804] [032/162] (907s left) gem_flink_basic (flink-lifetime)
[96.543360] Starting subtest: flink-lifetime
[96.545189] Subtest flink-lifetime: SUCCESS (0.003s)
[96.733157] [033/162] (906s left) gem_huc_copy (huc-copy)
[96.843099] Starting subtest: huc-copy
[96.847192] Subtest huc-copy: SKIP (0.000s)
[97.003043] [034/162] (906s left) gem_linear_blits (basic)
[97.247835] Starting subtest: basic
[97.263288] Subtest basic: SUCCESS (0.014s)
[97.471774] [035/162] (906s left) gem_mmap (basic)
[97.728192] Starting subtest: basic
[97.735279] Subtest basic: SUCCESS (0.001s)
[97.900937] [036/162] (905s left) gem_mmap_gtt (basic)
[97.996984] Starting subtest: basic
[97.998277] Subtest basic: SUCCESS (0.001s)
[98.158264] [037/162] (905s left) gem_render_linear_blits (basic)
[98.269452] Starting subtest: basic
[98.332255] Subtest basic: SUCCESS (0.062s)
[98.449517] [038/162] (905s left) gem_render_tiled_blits (basic)
[98.552334] Starting subtest: basic
[98.658460] Subtest basic: SUCCESS (0.098s)
[98.759081] [039/162] (905s left) gem_ringfill (basic-all)
[99.124616] Starting subtest: basic-all
[100.316556] Subtest basic-all: SUCCESS (1.191s)
[100.518487] [040/162] (903s left) gem_softpin (allocator-basic)
[100.624810] Starting subtest: allocator-basic
[100.848490] Subtest allocator-basic: SUCCESS (0.222s)
[101.034637] [041/162] (903s left) gem_softpin (allocator-basic-reserve)
[101.137308] Starting subtest: allocator-basic-reserve
[101.365808] Subtest allocator-basic-reserve: SUCCESS (0.220s)
[101.547944] [042/162] (902s left) gem_softpin (safe-alignment)
[101.660449] Starting subtest: safe-alignment
[101.685697] Subtest safe-alignment: SUCCESS (0.025s)
[101.866090] [043/162] (902s left) gem_sync (basic-all)
[101.988383] Starting subtest: basic-all
[104.250172] Subtest basic-all: SUCCESS (2.261s)
[104.538646] [044/162] (900s left) gem_sync (basic-each)
[104.667909] Starting subtest: basic-each
[107.122543] Subtest basic-each: SUCCESS (2.458s)
[107.379862] [045/162] (897s left) gem_tiled_blits (basic)
[107.589496] Starting subtest: basic
[107.626372] Subtest basic: SUCCESS (0.036s)
[107.836617] [046/162] (896s left) gem_tiled_fence_blits (basic)
[108.203761] Starting subtest: basic
[108.237349] Subtest basic: SUCCESS (0.036s)
[108.436138] [047/162] (896s left) gem_tiled_pread_basic
[109.082510] [048/162] (895s left) gem_wait (busy@all-engines)
[109.213325] Starting subtest: busy
[109.215064] Starting dynamic subtest: all-engines
[109.740450] Dynamic subtest all-engines: SUCCESS (0.526s)
[109.742521] Subtest busy: SUCCESS (0.528s)
[110.061222] [049/162] (895s left) gem_wait (wait@all-engines)
[110.187876] Starting subtest: wait
[110.189112] Starting dynamic subtest: all-engines
[111.224361] Dynamic subtest all-engines: SUCCESS (1.036s)
[111.227089] Subtest wait: SUCCESS (1.037s)
[111.535586] [050/162] (893s left) i915_getparams_basic (basic-eu-total)
[111.629254] Starting subtest: basic-eu-total
[111.631914] Subtest basic-eu-total: SUCCESS (0.000s)
[111.800815] [051/162] (893s left) i915_getparams_basic (basic-subslice-total)
[111.901346] Starting subtest: basic-subslice-total
[111.903614] Subtest basic-subslice-total: SUCCESS (0.000s)
[112.066972] [052/162] (893s left) i915_hangman (error-state-basic)
[112.192320] Starting subtest: error-state-basic
[112.208779] Subtest error-state-basic: SUCCESS (0.017s)
[112.380210] [053/162] (893s left) i915_pciid
[112.632137] [054/162] (892s left) intel_hwmon (hwmon-read)
[112.728533] Subtest hwmon-read: SKIP (0.000s)
[112.845768] [055/162] (892s left) intel_hwmon (hwmon-write)
[112.944072] Subtest hwmon-write: SKIP (0.000s)
[113.041243] [056/162] (892s left) kms_addfb_basic (addfb25-4-tiled)
[113.220338] Starting subtest: addfb25-4-tiled
[113.221488] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[113.410348] [057/162] (892s left) kms_addfb_basic (addfb25-bad-modifier)
[113.571870] Starting subtest: addfb25-bad-modifier
[113.573109] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[113.753658] [058/162] (892s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[113.909395] Starting subtest: addfb25-framebuffer-vs-set-tiling
[113.910685] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[114.081054] [059/162] (891s left) kms_addfb_basic (addfb25-modifier-no-flag)
[114.242433] Starting subtest: addfb25-modifier-no-flag
[114.243692] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[114.425060] [060/162] (891s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[114.586194] Starting subtest: addfb25-x-tiled-legacy
[114.587244] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[114.764954] [061/162] (891s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[114.927370] Starting subtest: addfb25-x-tiled-mismatch-legacy
[114.928521] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[115.095601] [062/162] (891s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[115.254501] Starting subtest: addfb25-yf-tiled-legacy
[115.256779] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[115.433379] [063/162] (891s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[115.602328] Starting subtest: addfb25-y-tiled-legacy
[115.603369] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[115.781140] [064/162] (890s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[115.946559] Starting subtest: addfb25-y-tiled-small-legacy
[115.949267] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[116.096910] [065/162] (890s left) kms_addfb_basic (bad-pitch-0)
[116.196683] Starting subtest: bad-pitch-0
[116.197782] Subtest bad-pitch-0: SUCCESS (0.000s)
[116.413729] [066/162] (890s left) kms_addfb_basic (bad-pitch-1024)
[116.509332] Starting subtest: bad-pitch-1024
[116.510545] Subtest bad-pitch-1024: SUCCESS (0.000s)
[116.751506] [067/162] (890s left) kms_addfb_basic (bad-pitch-128)
[116.848966] Starting subtest: bad-pitch-128
[116.850125] Subtest bad-pitch-128: SUCCESS (0.000s)
[117.099092] [068/162] (889s left) kms_addfb_basic (bad-pitch-256)
[117.197315] Starting subtest: bad-pitch-256
[117.198451] Subtest bad-pitch-256: SUCCESS (0.000s)
[117.435854] [069/162] (889s left) kms_addfb_basic (bad-pitch-32)
[117.536319] Starting subtest: bad-pitch-32
[117.537378] Subtest bad-pitch-32: SUCCESS (0.000s)
[117.780837] [070/162] (889s left) kms_addfb_basic (bad-pitch-63)
[117.878228] Starting subtest: bad-pitch-63
[117.879261] Subtest bad-pitch-63: SUCCESS (0.000s)
[118.113911] [071/162] (889s left) kms_addfb_basic (bad-pitch-65536)
[118.211753] Starting subtest: bad-pitch-65536
[118.213881] Subtest bad-pitch-65536: SUCCESS (0.001s)
[118.431626] [072/162] (888s left) kms_addfb_basic (bad-pitch-999)
[118.530623] Starting subtest: bad-pitch-999
[118.531856] Subtest bad-pitch-999: SUCCESS (0.000s)
[118.763037] [073/162] (888s left) kms_addfb_basic (basic)
[118.861470] Starting subtest: basic
[118.862647] Subtest basic: SUCCESS (0.000s)
[119.080770] [074/162] (888s left) kms_addfb_basic (basic-x-tiled-legacy)
[119.182645] Starting subtest: basic-x-tiled-legacy
[119.183839] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[119.414336] [075/162] (888s left) kms_addfb_basic (basic-y-tiled-legacy)
[119.516980] Starting subtest: basic-y-tiled-legacy
[119.519264] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[119.747398] [076/162] (887s left) kms_addfb_basic (bo-too-small)
[119.911409] Starting subtest: bo-too-small
[119.912449] Subtest bo-too-small: SUCCESS (0.001s)
[120.080842] [077/162] (887s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[120.239891] Starting subtest: bo-too-small-due-to-tiling
[120.241231] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[120.413054] [078/162] (887s left) kms_addfb_basic (clobberred-modifier)
[120.510843] Starting subtest: clobberred-modifier
[120.513154] Subtest clobberred-modifier: SUCCESS (0.000s)
[120.753769] [079/162] (887s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[120.848530] Starting subtest: framebuffer-vs-set-tiling
[120.850064] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[121.082487] [080/162] (886s left) kms_addfb_basic (invalid-get-prop)
[121.178740] Starting subtest: invalid-get-prop
[121.181064] Subtest invalid-get-prop: SUCCESS (0.000s)
[121.419092] [081/162] (886s left) kms_addfb_basic (invalid-get-prop-any)
[121.518108] Starting subtest: invalid-get-prop-any
[121.519143] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[121.766992] [082/162] (886s left) kms_addfb_basic (invalid-set-prop)
[121.865889] Starting subtest: invalid-set-prop
[121.866963] Subtest invalid-set-prop: SUCCESS (0.000s)
[122.114906] [083/162] (886s left) kms_addfb_basic (invalid-set-prop-any)
[122.211192] Starting subtest: invalid-set-prop-any
[122.213914] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[122.430766] [084/162] (885s left) kms_addfb_basic (no-handle)
[122.529445] Starting subtest: no-handle
[122.530654] Subtest no-handle: SUCCESS (0.000s)
[122.763283] [085/162] (885s left) kms_addfb_basic (size-max)
[122.925126] Starting subtest: size-max
[122.927118] Subtest size-max: SUCCESS (0.000s)
[123.097584] [086/162] (885s left) kms_addfb_basic (small-bo)
[123.260478] Starting subtest: small-bo
[123.261863] Subtest small-bo: SUCCESS (0.000s)
[123.431387] [087/162] (885s left) kms_addfb_basic (tile-pitch-mismatch)
[123.527903] Starting subtest: tile-pitch-mismatch
[123.529209] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[123.764277] [088/162] (885s left) kms_addfb_basic (too-high)
[123.920915] Starting subtest: too-high
[123.923171] Subtest too-high: SUCCESS (0.002s)
[124.098849] [089/162] (884s left) kms_addfb_basic (too-wide)
[124.253493] Starting subtest: too-wide
[124.254601] Subtest too-wide: SUCCESS (0.000s)
[124.413662] [090/162] (884s left) kms_addfb_basic (unused-handle)
[124.509967] Starting subtest: unused-handle
[124.511116] Subtest unused-handle: SUCCESS (0.000s)
[124.750262] [091/162] (884s left) kms_addfb_basic (unused-modifier)
[124.845353] Starting subtest: unused-modifier
[124.847066] Subtest unused-modifier: SUCCESS (0.000s)
[125.113091] [092/162] (884s left) kms_addfb_basic (unused-offsets)
[125.207220] Starting subtest: unused-offsets
[125.208279] Subtest unused-offsets: SUCCESS (0.000s)
[125.449753] [093/162] (883s left) kms_addfb_basic (unused-pitches)
[125.549218] Starting subtest: unused-pitches
[125.551168] Subtest unused-pitches: SUCCESS (0.000s)
[125.783064] [094/162] (883s left) kms_busy (basic)
[125.949514] Starting subtest: basic
[125.959712] Starting dynamic subtest: flip
[126.735365] Dynamic subtest flip: SUCCESS (0.779s)
[126.755148] Starting dynamic subtest: modeset
[128.033224] Dynamic subtest modeset: SUCCESS (1.297s)
[128.034278] Subtest basic: SUCCESS (2.084s)
[128.320429] [095/162] (881s left) kms_prop_blob (basic)
[128.421473] Starting subtest: basic
[128.422628] Subtest basic: SUCCESS (0.000s)
[128.588196] [096/162] (880s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[128.757662] Starting subtest: basic-busy-flip-before-cursor-atomic
[128.760641] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[128.917921] [097/162] (880s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[129.080915] Starting subtest: basic-busy-flip-before-cursor-legacy
[129.083906] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[129.260984] [098/162] (880s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[129.415853] Starting subtest: basic-flip-after-cursor-atomic
[130.570407] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.153s)
[130.819615] [099/162] (878s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[130.975221] Starting subtest: basic-flip-after-cursor-legacy
[132.099450] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.126s)
[132.350360] [100/162] (877s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[132.506458] Starting subtest: basic-flip-after-cursor-varying-size
[133.649427] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.139s)
[133.907209] [101/162] (876s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[134.062501] Starting subtest: basic-flip-before-cursor-atomic
[135.213272] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.156s)
[135.471981] [102/162] (874s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[135.634404] Starting subtest: basic-flip-before-cursor-legacy
[136.769732] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.135s)
[137.006411] [103/162] (873s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[137.163511] Starting subtest: basic-flip-before-cursor-varying-size
[138.321274] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.162s)
[138.584530] [104/162] (871s left) kms_dsc (dsc-basic)
[138.746075] Subtest dsc-basic: SKIP (0.000s)
[138.840786] [105/162] (871s left) kms_flip (basic-flip-vs-dpms)
[139.005791] Starting subtest: basic-flip-vs-dpms
[139.011023] Starting dynamic subtest: A-HDMI-A2
[140.247930] Dynamic subtest A-HDMI-A2: SUCCESS (1.235s)
[140.274036] Starting dynamic subtest: B-HDMI-A2
[141.210266] Dynamic subtest B-HDMI-A2: SUCCESS (0.958s)
[141.235691] Starting dynamic subtest: C-HDMI-A2
[142.141774] Dynamic subtest C-HDMI-A2: SUCCESS (0.930s)
[142.143597] Subtest basic-flip-vs-dpms: SUCCESS (3.135s)
[142.421411] [106/162] (868s left) kms_flip (basic-flip-vs-modeset)
[142.577054] Starting subtest: basic-flip-vs-modeset
[142.585692] Starting dynamic subtest: A-HDMI-A2
[143.815436] Dynamic subtest A-HDMI-A2: SUCCESS (1.233s)
[143.858513] Starting dynamic subtest: B-HDMI-A2
[144.809811] Dynamic subtest B-HDMI-A2: SUCCESS (0.988s)
[144.849951] Starting dynamic subtest: C-HDMI-A2
[145.802444] Dynamic subtest C-HDMI-A2: SUCCESS (0.988s)
[145.804743] Subtest basic-flip-vs-modeset: SUCCESS (3.222s)
[146.081179] [107/162] (864s left) kms_flip (basic-flip-vs-wf_vblank)
[146.252166] Starting subtest: basic-flip-vs-wf_vblank
[146.254484] Starting dynamic subtest: A-HDMI-A2
[147.659816] Dynamic subtest A-HDMI-A2: SUCCESS (1.403s)
[147.681508] Starting dynamic subtest: B-HDMI-A2
[148.991895] Dynamic subtest B-HDMI-A2: SUCCESS (1.327s)
[149.013498] Starting dynamic subtest: C-HDMI-A2
[150.314988] Dynamic subtest C-HDMI-A2: SUCCESS (1.319s)
[150.316303] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.065s)
[150.757638] [108/162] (859s left) kms_flip (basic-plain-flip)
[150.921221] Starting subtest: basic-plain-flip
[150.930288] Starting dynamic subtest: A-HDMI-A2
[152.030427] Dynamic subtest A-HDMI-A2: SUCCESS (1.101s)
[152.050517] Starting dynamic subtest: B-HDMI-A2
[153.073604] Dynamic subtest B-HDMI-A2: SUCCESS (1.038s)
[153.094170] Starting dynamic subtest: C-HDMI-A2
[154.111377] Dynamic subtest C-HDMI-A2: SUCCESS (1.036s)
[154.113459] Subtest basic-plain-flip: SUCCESS (3.191s)
[154.562698] [109/162] (856s left) kms_force_connector_basic (force-connector-state)
[154.759023] Starting subtest: force-connector-state
[155.206481] Subtest force-connector-state: SUCCESS (0.449s)
[155.682182] [110/162] (855s left) kms_force_connector_basic (force-edid)
[155.873121] Starting subtest: force-edid
[155.951738] Subtest force-edid: SUCCESS (0.079s)
[156.316103] [111/162] (854s left) kms_force_connector_basic (force-load-detect)
[156.511721] Starting subtest: force-load-detect
[156.515294] Subtest force-load-detect: SKIP (0.000s)
[156.814433] [112/162] (854s left) kms_force_connector_basic (prune-stale-modes)
[157.026825] Starting subtest: prune-stale-modes
[157.104204] Subtest prune-stale-modes: SUCCESS (0.080s)
[157.433392] [113/162] (853s left) kms_frontbuffer_tracking (basic)
[157.617057] Starting subtest: basic
[160.776978] Subtest basic: SUCCESS (3.162s)
[160.965695] [114/162] (850s left) kms_hdmi_inject (inject-audio)
[161.284231] Starting subtest: inject-audio
[161.474510] Subtest inject-audio: SUCCESS (0.187s)
[161.883844] [115/162] (849s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[162.053097] Starting subtest: compare-crc-sanitycheck-xr24
[162.054288] Starting dynamic subtest: pipe-A-HDMI-A-2
[162.634505] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.575s)
[162.678583] Starting dynamic subtest: pipe-B-HDMI-A-2
[163.182180] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.543s)
[163.203771] Starting dynamic subtest: pipe-C-HDMI-A-2
[163.743989] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.558s)
[163.745827] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.695s)
[164.006816] [116/162] (847s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[164.174759] Starting subtest: compare-crc-sanitycheck-nv12
[164.177751] Starting dynamic subtest: pipe-A-HDMI-A-2
[164.866064] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.685s)
[164.891302] Starting dynamic subtest: pipe-B-HDMI-A-2
[165.563085] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.689s)
[165.592544] Starting dynamic subtest: pipe-C-HDMI-A-2
[166.229181] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.658s)
[166.231155] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.055s)
[166.481634] [117/162] (845s left) kms_pipe_crc_basic (hang-read-crc)
[166.636131] Starting subtest: hang-read-crc
[166.637847] Starting dynamic subtest: pipe-A-HDMI-A-2
[167.984952] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.348s)
[168.028589] Starting dynamic subtest: pipe-B-HDMI-A-2
[169.314014] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.322s)
[169.340248] Starting dynamic subtest: pipe-C-HDMI-A-2
[170.690259] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.341s)
[170.693528] Subtest hang-read-crc: SUCCESS (4.030s)
[170.914648] [118/162] (840s left) kms_pipe_crc_basic (nonblocking-crc)
[171.084357] Starting subtest: nonblocking-crc
[171.093101] Starting dynamic subtest: pipe-A-HDMI-A-2
[171.857105] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.761s)
[171.880168] Starting dynamic subtest: pipe-B-HDMI-A-2
[172.600356] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.738s)
[172.647134] Starting dynamic subtest: pipe-C-HDMI-A-2
[173.333346] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.723s)
[173.335946] Subtest nonblocking-crc: SUCCESS (2.245s)
[173.588684] [119/162] (838s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[173.755795] Starting subtest: nonblocking-crc-frame-sequence
[173.762713] Starting dynamic subtest: pipe-A-HDMI-A-2
[174.497913] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.731s)
[174.557776] Starting dynamic subtest: pipe-B-HDMI-A-2
[175.209697] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.704s)
[175.254231] Starting dynamic subtest: pipe-C-HDMI-A-2
[175.918472] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.703s)
[175.919841] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.160s)
[176.166726] [120/162] (835s left) kms_pipe_crc_basic (read-crc)
[176.337587] Starting subtest: read-crc
[176.339221] Starting dynamic subtest: pipe-A-HDMI-A-2
[177.008722] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.667s)
[177.076219] Starting dynamic subtest: pipe-B-HDMI-A-2
[177.670611] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.655s)
[177.718325] Starting dynamic subtest: pipe-C-HDMI-A-2
[178.309709] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.628s)
[178.310959] Subtest read-crc: SUCCESS (1.972s)
[178.550159] [121/162] (833s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[178.720630] Starting subtest: read-crc-frame-sequence
[178.722898] Starting dynamic subtest: pipe-A-HDMI-A-2
[179.391943] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.671s)
[179.439170] Starting dynamic subtest: pipe-B-HDMI-A-2
[180.044989] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.645s)
[180.092106] Starting dynamic subtest: pipe-C-HDMI-A-2
[180.694279] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.645s)
[180.700944] Subtest read-crc-frame-sequence: SUCCESS (1.981s)
[180.945918] [122/162] (831s left) kms_pm_backlight (basic-brightness)
[181.112470] Subtest basic-brightness: SKIP (0.000s)
[181.223460] [123/162] (830s left) kms_pm_rpm (basic-pci-d3-state)
[182.886666] Starting subtest: basic-pci-d3-state
[183.249577] Subtest basic-pci-d3-state: SUCCESS (0.341s)
[183.442759] [124/162] (828s left) kms_pm_rpm (basic-rte)
[183.538695] Starting subtest: basic-rte
[185.510521] Subtest basic-rte: SUCCESS (1.968s)
[186.254498] [125/162] (825s left) kms_psr (psr-primary-page-flip)
[186.414064] Subtest psr-primary-page-flip: SKIP (0.000s)
[186.516454] [126/162] (825s left) kms_psr (psr-cursor-plane-move)
[186.697907] Subtest psr-cursor-plane-move: SKIP (0.000s)
[186.797989] [127/162] (825s left) kms_psr (psr-sprite-plane-onoff)
[186.965386] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[187.082455] [128/162] (825s left) kms_psr (psr-primary-mmap-gtt)
[187.254163] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[187.366275] [129/162] (825s left) kms_setmode (basic-clone-single-crtc)
[187.464280] Starting subtest: basic-clone-single-crtc
[187.468497] Subtest basic-clone-single-crtc: SKIP (0.000s)
[187.628524] [130/162] (825s left) i915_pm_rps (basic-api)
[187.746358] Starting subtest: basic-api
[187.754400] Subtest basic-api: SUCCESS (0.010s)
[187.928396] [131/162] (824s left) prime_self_import (basic-llseek-bad)
[188.024550] Starting subtest: basic-llseek-bad
[188.034332] Subtest basic-llseek-bad: SUCCESS (0.006s)
[188.210434] [132/162] (824s left) prime_self_import (basic-llseek-size)
[188.310332] Starting subtest: basic-llseek-size
[188.315870] Subtest basic-llseek-size: SUCCESS (0.010s)
[188.493830] [133/162] (824s left) prime_self_import (basic-with_fd_dup)
[188.585890] Starting subtest: basic-with_fd_dup
[188.593494] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[188.795106] [134/162] (824s left) prime_self_import (basic-with_one_bo)
[188.889420] Starting subtest: basic-with_one_bo
[188.896444] Subtest basic-with_one_bo: SUCCESS (0.006s)
[189.085140] [135/162] (824s left) prime_self_import (basic-with_one_bo_two_files)
[189.177600] Starting subtest: basic-with_one_bo_two_files
[189.188366] Subtest basic-with_one_bo_two_files: SUCCESS (0.008s)
[189.380662] [136/162] (823s left) prime_self_import (basic-with_two_bos)
[189.474349] Starting subtest: basic-with_two_bos
[189.492079] Subtest basic-with_two_bos: SUCCESS (0.013s)
[189.690058] [137/162] (823s left) prime_vgem (basic-fence-flip)
[189.821784] Starting subtest: basic-fence-flip
[190.319068] Subtest basic-fence-flip: SUCCESS (0.495s)
[190.511944] [138/162] (823s left) prime_vgem (basic-fence-mmap)
[190.632637] Starting subtest: basic-fence-mmap
[190.695097] Subtest basic-fence-mmap: SUCCESS (0.059s)
[190.858686] [139/162] (822s left) prime_vgem (basic-fence-read)
[190.987911] Starting subtest: basic-fence-read
[191.113390] Subtest basic-fence-read: SUCCESS (0.122s)
[191.286615] [140/162] (822s left) prime_vgem (basic-gtt)
[191.417959] Starting subtest: basic-gtt
[191.447340] Subtest basic-gtt: SUCCESS (0.028s)
[191.625091] [141/162] (822s left) prime_vgem (basic-read)
[191.750112] Starting subtest: basic-read
[191.813959] Subtest basic-read: SUCCESS (0.062s)
[191.996331] [142/162] (821s left) prime_vgem (basic-write)
[192.118429] Starting subtest: basic-write
[192.202067] Subtest basic-write: SUCCESS (0.084s)
[192.377096] [143/162] (821s left) vgem_basic (setversion)
[192.488385] Starting subtest: setversion
[192.491114] Subtest setversion: SUCCESS (0.000s)
[192.625713] [144/162] (821s left) vgem_basic (create)
[192.739504] Starting subtest: create
[192.741902] Subtest create: SUCCESS (0.001s)
[192.877112] [145/162] (821s left) vgem_basic (debugfs)
[192.988511] Starting subtest: debugfs
[192.989845] Subtest debugfs: SUCCESS (0.001s)
[193.125927] [146/162] (821s left) vgem_basic (dmabuf-export)
[193.241486] Starting subtest: dmabuf-export
[193.243952] Subtest dmabuf-export: SUCCESS (0.005s)
[193.407060] [147/162] (820s left) vgem_basic (dmabuf-fence)
[193.523249] Starting subtest: dmabuf-fence
[193.524325] Subtest dmabuf-fence: SUCCESS (0.001s)
[193.693252] [148/162] (820s left) vgem_basic (dmabuf-fence-before)
[193.806346] Starting subtest: dmabuf-fence-before
[193.808984] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[193.945041] [149/162] (820s left) vgem_basic (dmabuf-mmap)
[194.053116] Starting subtest: dmabuf-mmap
[194.080174] Subtest dmabuf-mmap: SUCCESS (0.028s)
[194.241152] [150/162] (820s left) vgem_basic (mmap)
[194.352216] Starting subtest: mmap
[194.369838] Subtest mmap: SUCCESS (0.018s)
[194.527498] [151/162] (820s left) vgem_basic (second-client)
[194.640244] Starting subtest: second-client
[194.673946] Subtest second-client: SUCCESS (0.034s)
[194.825805] [152/162] (820s left) vgem_basic (sysfs)
[194.938532] Starting subtest: sysfs
[194.939591] Subtest sysfs: SUCCESS (0.001s)
[195.082988] [153/162] (819s left) core_hotunplug (unbind-rebind)
[195.303340] Starting subtest: unbind-rebind
[199.182273] Subtest unbind-rebind: SUCCESS (3.878s)
[199.282765] [154/162] (815s left) vgem_basic (unload)
[199.385343] Starting subtest: unload
[228.349309] Subtest unload: SUCCESS (28.961s)
[228.557300] [155/162] (786s left) i915_module_load (reload)
[228.654804] Starting subtest: reload
[232.524088] Subtest reload: SUCCESS (3.870s)
[232.722590] [156/162] (782s left) gem_lmem_swapping (basic)
[232.868343] Subtest basic: SKIP (0.000s)
[232.973941] [157/162] (782s left) gem_lmem_swapping (parallel-random-engines)
[233.082915] Subtest parallel-random-engines: SKIP (0.000s)
[233.194185] [158/162] (782s left) gem_lmem_swapping (random-engines)
[233.304854] Subtest random-engines: SKIP (0.000s)
[233.424469] [159/162] (782s left) gem_lmem_swapping (verify-random)
[233.532608] Subtest verify-random: SKIP (0.000s)
[233.638835] [160/162] (782s left) i915_pm_rpm (module-reload)
[235.507890] Starting subtest: module-reload
[246.496288] Subtest module-reload: SUCCESS (11.012s)
[246.560412] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[246.563028] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[246.569256] [161/162] (769s left) i915_selftest (live)