[42.270874] Initializing watchdogs
[42.270986]   /dev/watchdog0
[42.313413] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5927 Intel Kabylake (Gen9) Iris Plus Graphics 650
[42.320220] [001/162] (960s left) i915_module_load (load)
[42.388583] Starting subtest: load
[45.409811] Subtest load: SUCCESS (3.019s)
[45.503572] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[45.505594] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[45.509110] [002/162] (957s left) core_auth (basic-auth)
[45.578746] Starting subtest: basic-auth
[45.579885] Subtest basic-auth: SUCCESS (0.001s)
[45.725330] [003/162] (957s left) core_debugfs (read-all-entries)
[45.776959] Starting subtest: read-all-entries
[45.857023] Subtest read-all-entries: SUCCESS (0.080s)
[46.011786] [004/162] (957s left) core_sysfs (read-all-entries)
[46.066619] Starting subtest: read-all-entries
[46.068516] Subtest read-all-entries: SUCCESS (0.005s)
[46.179941] [005/162] (956s left) fbdev (eof)
[46.241270] Starting subtest: eof
[46.242015] Subtest eof: SUCCESS (0.001s)
[46.345972] [006/162] (956s left) fbdev (info)
[46.394145] Starting subtest: info
[46.394769] Subtest info: SUCCESS (0.000s)
[46.482287] [007/162] (956s left) fbdev (nullptr)
[46.539469] Starting subtest: nullptr
[46.540215] Subtest nullptr: SUCCESS (0.000s)
[46.677545] [008/162] (956s left) fbdev (read)
[46.740662] Starting subtest: read
[46.971944] Subtest read: SUCCESS (0.230s)
[47.048248] [009/162] (956s left) fbdev (write)
[47.110061] Starting subtest: write
[47.237363] Subtest write: SUCCESS (0.124s)
[47.302166] [010/162] (956s left) gem_basic (bad-close)
[47.355441] Starting subtest: bad-close
[47.356741] Subtest bad-close: SUCCESS (0.000s)
[47.447084] [011/162] (955s left) gem_basic (create-close)
[47.498744] Starting subtest: create-close
[47.499843] Subtest create-close: SUCCESS (0.000s)
[47.647069] [012/162] (955s left) gem_basic (create-fd-close)
[47.698292] Starting subtest: create-fd-close
[47.699535] Subtest create-fd-close: SUCCESS (0.000s)
[47.801882] [013/162] (955s left) gem_busy (busy@all-engines)
[47.876554] Starting subtest: busy
[47.877505] Starting dynamic subtest: all-engines
[47.939010] Dynamic subtest all-engines: SUCCESS (0.063s)
[47.939874] Subtest busy: SUCCESS (0.063s)
[48.419973] [014/162] (955s left) gem_close_race (basic-process)
[48.488986] Starting subtest: basic-process
[48.548388] Subtest basic-process: SUCCESS (0.060s)
[48.793445] [015/162] (954s left) gem_close_race (basic-threads)
[48.863322] Starting subtest: basic-threads
[50.075949] Subtest basic-threads: SUCCESS (1.200s)
[50.318683] [016/162] (953s left) gem_ctx_create (basic)
[50.383260] Starting subtest: basic
[50.384077] Subtest basic: SUCCESS (0.000s)
[50.634929] [017/162] (953s left) gem_ctx_create (basic-files)
[50.702302] Starting subtest: basic-files
[52.718722] Subtest basic-files: SUCCESS (2.000s)
[53.020563] [018/162] (950s left) gem_ctx_exec (basic)
[53.077593] Starting subtest: basic
[53.081870] Subtest basic: SUCCESS (0.004s)
[53.213714] [019/162] (950s left) gem_exec_basic (basic)
[53.274614] Starting subtest: basic
[53.276559] Starting dynamic subtest: rcs0-smem
[53.277743] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[53.286750] Starting dynamic subtest: bcs0-smem
[53.287744] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[53.296216] Starting dynamic subtest: vcs0-smem
[53.297068] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[53.305496] Starting dynamic subtest: vcs1-smem
[53.306234] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[53.314657] Starting dynamic subtest: vecs0-smem
[53.315288] Dynamic subtest vecs0-smem: SUCCESS (0.000s)
[53.316202] Subtest basic: SUCCESS (0.008s)
[53.552450] [020/162] (950s left) gem_exec_create (basic)
[53.617947] Starting subtest: basic
[53.618825] Starting dynamic subtest: smem
[55.661745] Dynamic subtest smem: SUCCESS (2.032s)
[55.662696] Subtest basic: SUCCESS (2.033s)
[55.895040] [021/162] (948s left) gem_exec_fence (basic-busy)
[56.109159] Starting subtest: basic-busy
[56.110704] Starting dynamic subtest: rcs0
[56.146305] Dynamic subtest rcs0: SUCCESS (0.037s)
[56.161957] Starting dynamic subtest: bcs0
[56.169087] Dynamic subtest bcs0: SUCCESS (0.022s)
[56.181257] Starting dynamic subtest: vcs0
[56.196726] Dynamic subtest vcs0: SUCCESS (0.026s)
[56.206822] Starting dynamic subtest: vcs1
[56.219925] Dynamic subtest vcs1: SUCCESS (0.023s)
[56.229887] Starting dynamic subtest: vecs0
[56.244932] Dynamic subtest vecs0: SUCCESS (0.024s)
[56.245694] Subtest basic-busy: SUCCESS (0.136s)
[56.973658] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[56.976245] [022/162] (947s left) gem_exec_fence (basic-wait)
[57.163539] Starting subtest: basic-wait
[57.165591] Starting dynamic subtest: rcs0
[57.189491] Dynamic subtest rcs0: SUCCESS (0.026s)
[57.207398] Starting dynamic subtest: bcs0
[57.225091] Dynamic subtest bcs0: SUCCESS (0.031s)
[57.243072] Starting dynamic subtest: vcs0
[57.253979] Dynamic subtest vcs0: SUCCESS (0.032s)
[57.266610] Starting dynamic subtest: vcs1
[57.279532] Dynamic subtest vcs1: SUCCESS (0.025s)
[57.293155] Starting dynamic subtest: vecs0
[57.304089] Dynamic subtest vecs0: SUCCESS (0.024s)
[57.305112] Subtest basic-wait: SUCCESS (0.142s)
[58.014630] [023/162] (946s left) gem_exec_fence (basic-await)
[58.225262] Starting subtest: basic-await
[58.226464] Starting dynamic subtest: rcs0
[58.332579] Dynamic subtest rcs0: SUCCESS (0.107s)
[58.344841] Starting dynamic subtest: bcs0
[58.435628] Dynamic subtest bcs0: SUCCESS (0.100s)
[58.447937] Starting dynamic subtest: vcs0
[58.540911] Dynamic subtest vcs0: SUCCESS (0.107s)
[58.550747] Starting dynamic subtest: vcs1
[58.638736] Dynamic subtest vcs1: SUCCESS (0.097s)
[58.652173] Starting dynamic subtest: vecs0
[58.758132] Dynamic subtest vecs0: SUCCESS (0.119s)
[58.759113] Subtest basic-await: SUCCESS (0.532s)
[59.429815] [024/162] (944s left) gem_exec_fence (nb-await)
[59.616851] Starting subtest: nb-await
[59.617649] Starting dynamic subtest: rcs0
[59.684784] Dynamic subtest rcs0: SUCCESS (0.067s)
[59.709647] Starting dynamic subtest: bcs0
[59.771242] Dynamic subtest bcs0: SUCCESS (0.082s)
[59.803679] Starting dynamic subtest: vcs0
[59.855401] Dynamic subtest vcs0: SUCCESS (0.084s)
[59.888597] Starting dynamic subtest: vcs1
[59.953439] Dynamic subtest vcs1: SUCCESS (0.090s)
[59.992047] Starting dynamic subtest: vecs0
[60.047777] Dynamic subtest vecs0: SUCCESS (0.096s)
[60.051511] Subtest nb-await: SUCCESS (0.430s)
[60.816874] [025/162] (943s left) gem_exec_gttfill (basic)
[60.886181] Starting subtest: basic
[69.665365] Subtest basic: SUCCESS (8.766s)
[69.885194] [026/162] (934s left) gem_exec_parallel (engines)
[70.052671] Starting subtest: engines
[70.054535] Starting dynamic subtest: basic
[72.679317] Dynamic subtest basic: SUCCESS (2.623s)
[72.688977] Starting dynamic subtest: contexts
[75.355971] Dynamic subtest contexts: SUCCESS (2.677s)
[75.366891] Starting dynamic subtest: fds
[84.990126] Dynamic subtest fds: SUCCESS (9.631s)
[85.000274] Starting dynamic subtest: userptr
[87.226828] Dynamic subtest userptr: SUCCESS (2.235s)
[87.227866] Subtest engines: SUCCESS (17.168s)
[87.478290] [027/162] (916s left) gem_exec_store (basic)
[87.548957] Starting subtest: basic
[87.606154] Subtest basic: SUCCESS (0.057s)
[87.843514] [028/162] (916s left) gem_flink_basic (bad-flink)
[87.894891] Starting subtest: bad-flink
[87.895463] Subtest bad-flink: SUCCESS (0.000s)
[87.986187] [029/162] (916s left) gem_flink_basic (bad-open)
[88.036499] Starting subtest: bad-open
[88.037164] Subtest bad-open: SUCCESS (0.000s)
[88.144505] [030/162] (916s left) gem_flink_basic (basic)
[88.197780] Starting subtest: basic
[88.198354] Subtest basic: SUCCESS (0.000s)
[88.311211] [031/162] (916s left) gem_flink_basic (double-flink)
[88.362893] Starting subtest: double-flink
[88.363549] Subtest double-flink: SUCCESS (0.000s)
[88.480490] [032/162] (916s left) gem_flink_basic (flink-lifetime)
[88.532687] Starting subtest: flink-lifetime
[88.534050] Subtest flink-lifetime: SUCCESS (0.002s)
[88.669629] [033/162] (916s left) gem_huc_copy (huc-copy)
[88.732015] Starting subtest: huc-copy
[88.733795] Subtest huc-copy: SKIP (0.000s)
[88.874053] [034/162] (916s left) gem_linear_blits (basic)
[89.040231] Starting subtest: basic
[89.048987] Subtest basic: SUCCESS (0.009s)
[89.223735] [035/162] (915s left) gem_mmap (basic)
[89.366167] Starting subtest: basic
[89.369704] Subtest basic: SUCCESS (0.000s)
[89.490994] [036/162] (915s left) gem_mmap_gtt (basic)
[89.547297] Starting subtest: basic
[89.548175] Subtest basic: SUCCESS (0.001s)
[89.671053] [037/162] (915s left) gem_render_linear_blits (basic)
[89.729726] Starting subtest: basic
[89.767134] Subtest basic: SUCCESS (0.034s)
[89.829140] [038/162] (915s left) gem_render_tiled_blits (basic)
[89.886955] Starting subtest: basic
[89.929656] Subtest basic: SUCCESS (0.041s)
[89.995647] [039/162] (915s left) gem_ringfill (basic-all)
[90.268053] Starting subtest: basic-all
[91.427063] Subtest basic-all: SUCCESS (1.159s)
[91.577764] [040/162] (913s left) gem_softpin (allocator-basic)
[91.638405] Starting subtest: allocator-basic
[91.781096] Subtest allocator-basic: SUCCESS (0.142s)
[91.915996] [041/162] (913s left) gem_softpin (allocator-basic-reserve)
[91.972990] Starting subtest: allocator-basic-reserve
[92.111450] Subtest allocator-basic-reserve: SUCCESS (0.138s)
[92.245117] [042/162] (913s left) gem_softpin (safe-alignment)
[92.305724] Starting subtest: safe-alignment
[92.322317] Subtest safe-alignment: SUCCESS (0.017s)
[92.506850] [043/162] (912s left) gem_sync (basic-all)
[92.577258] Starting subtest: basic-all
[94.714105] Subtest basic-all: SUCCESS (2.137s)
[94.958990] [044/162] (910s left) gem_sync (basic-each)
[95.033158] Starting subtest: basic-each
[97.286037] Subtest basic-each: SUCCESS (2.257s)
[97.567904] [045/162] (908s left) gem_tiled_blits (basic)
[97.688150] Starting subtest: basic
[97.711859] Subtest basic: SUCCESS (0.026s)
[97.873819] [046/162] (907s left) gem_tiled_fence_blits (basic)
[98.098434] Starting subtest: basic
[98.109104] Subtest basic: SUCCESS (0.011s)
[98.303111] [047/162] (907s left) gem_tiled_pread_basic (basic)
[98.435894] Starting subtest: basic
[98.559937] Subtest basic: SUCCESS (0.123s)
[98.675203] [048/162] (907s left) gem_wait (busy@all-engines)
[98.748240] Starting subtest: busy
[98.748969] Starting dynamic subtest: all-engines
[99.280946] Dynamic subtest all-engines: SUCCESS (0.533s)
[99.281500] Subtest busy: SUCCESS (0.533s)
[99.540987] [049/162] (906s left) gem_wait (wait@all-engines)
[99.610834] Starting subtest: wait
[99.611650] Starting dynamic subtest: all-engines
[100.662369] Dynamic subtest all-engines: SUCCESS (1.050s)
[100.664910] Subtest wait: SUCCESS (1.052s)
[100.953746] [050/162] (905s left) i915_getparams_basic (basic-eu-total)
[101.005388] Starting subtest: basic-eu-total
[101.006532] Subtest basic-eu-total: SUCCESS (0.000s)
[101.143200] [051/162] (904s left) i915_getparams_basic (basic-subslice-total)
[101.193878] Starting subtest: basic-subslice-total
[101.195316] Subtest basic-subslice-total: SUCCESS (0.000s)
[101.290935] [052/162] (904s left) i915_hangman (error-state-basic)
[101.359932] Starting subtest: error-state-basic
[101.367582] Subtest error-state-basic: SUCCESS (0.007s)
[101.467973] [053/162] (904s left) i915_pciid
[101.601624] [054/162] (904s left) intel_hwmon (hwmon-read)
[101.656410] Subtest hwmon-read: SKIP (0.000s)
[101.713230] [055/162] (904s left) intel_hwmon (hwmon-write)
[101.764966] Subtest hwmon-write: SKIP (0.000s)
[101.829660] [056/162] (904s left) kms_addfb_basic (addfb25-4-tiled)
[102.145653] Starting subtest: addfb25-4-tiled
[102.146315] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[102.289627] [057/162] (904s left) kms_addfb_basic (addfb25-bad-modifier)
[102.592628] Starting subtest: addfb25-bad-modifier
[102.593290] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[102.740092] [058/162] (903s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[103.040649] Starting subtest: addfb25-framebuffer-vs-set-tiling
[103.041322] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[103.205773] [059/162] (903s left) kms_addfb_basic (addfb25-modifier-no-flag)
[103.513521] Starting subtest: addfb25-modifier-no-flag
[103.514194] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[103.671228] [060/162] (902s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[103.972978] Starting subtest: addfb25-x-tiled-legacy
[103.974034] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[104.134758] [061/162] (902s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[104.449900] Starting subtest: addfb25-x-tiled-mismatch-legacy
[104.450767] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[104.613464] [062/162] (902s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[104.965483] Starting subtest: addfb25-yf-tiled-legacy
[104.966658] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[105.119621] [063/162] (901s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[105.420666] Starting subtest: addfb25-y-tiled-legacy
[105.421394] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[105.574481] [064/162] (901s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[105.880338] Starting subtest: addfb25-y-tiled-small-legacy
[105.881219] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[106.015100] [065/162] (900s left) kms_addfb_basic (bad-pitch-0)
[106.067583] Starting subtest: bad-pitch-0
[106.071135] Subtest bad-pitch-0: SUCCESS (0.000s)
[106.482374] [066/162] (900s left) kms_addfb_basic (bad-pitch-1024)
[106.534665] Starting subtest: bad-pitch-1024
[106.535351] Subtest bad-pitch-1024: SUCCESS (0.000s)
[106.934960] [067/162] (900s left) kms_addfb_basic (bad-pitch-128)
[106.987178] Starting subtest: bad-pitch-128
[106.987914] Subtest bad-pitch-128: SUCCESS (0.000s)
[107.350951] [068/162] (899s left) kms_addfb_basic (bad-pitch-256)
[107.402952] Starting subtest: bad-pitch-256
[107.403627] Subtest bad-pitch-256: SUCCESS (0.000s)
[107.823256] [069/162] (899s left) kms_addfb_basic (bad-pitch-32)
[107.875416] Starting subtest: bad-pitch-32
[107.876167] Subtest bad-pitch-32: SUCCESS (0.000s)
[108.257127] [070/162] (899s left) kms_addfb_basic (bad-pitch-63)
[108.309880] Starting subtest: bad-pitch-63
[108.310574] Subtest bad-pitch-63: SUCCESS (0.000s)
[108.717138] [071/162] (898s left) kms_addfb_basic (bad-pitch-65536)
[108.768613] Starting subtest: bad-pitch-65536
[108.769430] Subtest bad-pitch-65536: SUCCESS (0.000s)
[109.153523] [072/162] (898s left) kms_addfb_basic (bad-pitch-999)
[109.208121] Starting subtest: bad-pitch-999
[109.208868] Subtest bad-pitch-999: SUCCESS (0.000s)
[109.593846] [073/162] (897s left) kms_addfb_basic (basic)
[109.646723] Starting subtest: basic
[109.647314] Subtest basic: SUCCESS (0.000s)
[110.053250] [074/162] (897s left) kms_addfb_basic (basic-x-tiled-legacy)
[110.104872] Starting subtest: basic-x-tiled-legacy
[110.105466] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[110.498710] [075/162] (897s left) kms_addfb_basic (basic-y-tiled-legacy)
[110.552134] Starting subtest: basic-y-tiled-legacy
[110.552828] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[110.940071] [076/162] (896s left) kms_addfb_basic (bo-too-small)
[111.246652] Starting subtest: bo-too-small
[111.247339] Subtest bo-too-small: SUCCESS (0.000s)
[111.401676] [077/162] (896s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[111.707607] Starting subtest: bo-too-small-due-to-tiling
[111.708284] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[111.873470] [078/162] (896s left) kms_addfb_basic (clobberred-modifier)
[111.925058] Starting subtest: clobberred-modifier
[111.925812] Subtest clobberred-modifier: SUCCESS (0.000s)
[112.331215] [079/162] (895s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[112.383719] Starting subtest: framebuffer-vs-set-tiling
[112.384479] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[112.753406] [080/162] (895s left) kms_addfb_basic (invalid-get-prop)
[112.805939] Starting subtest: invalid-get-prop
[112.806591] Subtest invalid-get-prop: SUCCESS (0.000s)
[113.220804] [081/162] (894s left) kms_addfb_basic (invalid-get-prop-any)
[113.274132] Starting subtest: invalid-get-prop-any
[113.274859] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[113.682739] [082/162] (894s left) kms_addfb_basic (invalid-set-prop)
[113.735843] Starting subtest: invalid-set-prop
[113.736444] Subtest invalid-set-prop: SUCCESS (0.000s)
[114.106856] [083/162] (894s left) kms_addfb_basic (invalid-set-prop-any)
[114.160945] Starting subtest: invalid-set-prop-any
[114.161511] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[114.530491] [084/162] (893s left) kms_addfb_basic (no-handle)
[114.582818] Starting subtest: no-handle
[114.583521] Subtest no-handle: SUCCESS (0.000s)
[114.983916] [085/162] (893s left) kms_addfb_basic (size-max)
[115.287505] Starting subtest: size-max
[115.288048] Subtest size-max: SUCCESS (0.000s)
[115.454963] [086/162] (893s left) kms_addfb_basic (small-bo)
[115.759545] Starting subtest: small-bo
[115.760242] Subtest small-bo: SUCCESS (0.000s)
[115.923741] [087/162] (892s left) kms_addfb_basic (tile-pitch-mismatch)
[115.975632] Starting subtest: tile-pitch-mismatch
[115.976226] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[116.362549] [088/162] (892s left) kms_addfb_basic (too-high)
[116.670578] Starting subtest: too-high
[116.671410] Subtest too-high: SUCCESS (0.001s)
[116.833391] [089/162] (891s left) kms_addfb_basic (too-wide)
[117.140617] Starting subtest: too-wide
[117.141321] Subtest too-wide: SUCCESS (0.000s)
[117.284197] [090/162] (891s left) kms_addfb_basic (unused-handle)
[117.335943] Starting subtest: unused-handle
[117.336853] Subtest unused-handle: SUCCESS (0.000s)
[117.747269] [091/162] (891s left) kms_addfb_basic (unused-modifier)
[117.799928] Starting subtest: unused-modifier
[117.800742] Subtest unused-modifier: SUCCESS (0.000s)
[118.191283] [092/162] (890s left) kms_addfb_basic (unused-offsets)
[118.244392] Starting subtest: unused-offsets
[118.245722] Subtest unused-offsets: SUCCESS (0.000s)
[118.671154] [093/162] (890s left) kms_addfb_basic (unused-pitches)
[118.722206] Starting subtest: unused-pitches
[118.722802] Subtest unused-pitches: SUCCESS (0.000s)
[119.102456] [094/162] (889s left) kms_busy (basic)
[119.415693] Starting subtest: basic
[119.416833] Starting dynamic subtest: flip
[120.031114] Dynamic subtest flip: SUCCESS (0.611s)
[120.041798] Starting dynamic subtest: modeset
[121.186153] Dynamic subtest modeset: SUCCESS (1.155s)
[121.187344] Subtest basic: SUCCESS (1.770s)
[121.586452] [095/162] (887s left) kms_prop_blob (basic)
[121.641847] Starting subtest: basic
[121.642482] Subtest basic: SUCCESS (0.000s)
[121.732225] [096/162] (887s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[122.038730] Starting subtest: basic-busy-flip-before-cursor-atomic
[122.040178] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[122.192285] [097/162] (887s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[122.494824] Starting subtest: basic-busy-flip-before-cursor-legacy
[122.496126] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[122.589481] [098/162] (886s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[122.890089] Starting subtest: basic-flip-after-cursor-atomic
[123.887893] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.997s)
[124.196107] [099/162] (885s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[124.495245] Starting subtest: basic-flip-after-cursor-legacy
[125.487755] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.992s)
[125.802239] [100/162] (883s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[126.105712] Starting subtest: basic-flip-after-cursor-varying-size
[127.095334] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.989s)
[127.396561] [101/162] (882s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[127.702825] Starting subtest: basic-flip-before-cursor-atomic
[128.667710] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.965s)
[128.980877] [102/162] (880s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[129.285196] Starting subtest: basic-flip-before-cursor-legacy
[130.325680] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.035s)
[130.633136] [103/162] (879s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[130.932835] Starting subtest: basic-flip-before-cursor-varying-size
[131.899275] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.966s)
[132.207403] [104/162] (877s left) kms_dsc (dsc-basic)
[132.514656] Subtest dsc-basic: SKIP (0.000s)
[132.581507] [105/162] (877s left) kms_flip (basic-flip-vs-dpms)
[132.903442] Starting subtest: basic-flip-vs-dpms
[132.905055] Starting dynamic subtest: A-DP1
[133.974174] Dynamic subtest A-DP1: SUCCESS (1.066s)
[133.989553] Starting dynamic subtest: B-DP1
[134.920802] Dynamic subtest B-DP1: SUCCESS (0.942s)
[134.943478] Starting dynamic subtest: C-DP1
[136.164861] Dynamic subtest C-DP1: SUCCESS (1.243s)
[136.173111] Subtest basic-flip-vs-dpms: SUCCESS (3.262s)
[136.527809] [106/162] (873s left) kms_flip (basic-flip-vs-modeset)
[136.837192] Starting subtest: basic-flip-vs-modeset
[136.839071] Starting dynamic subtest: A-DP1
[138.120798] Dynamic subtest A-DP1: SUCCESS (1.280s)
[138.145078] Starting dynamic subtest: B-DP1
[139.358153] Dynamic subtest B-DP1: SUCCESS (1.234s)
[139.382839] Starting dynamic subtest: C-DP1
[140.444432] Dynamic subtest C-DP1: SUCCESS (1.083s)
[140.450124] Subtest basic-flip-vs-modeset: SUCCESS (3.610s)
[140.824770] [107/162] (869s left) kms_flip (basic-flip-vs-wf_vblank)
[141.126712] Starting subtest: basic-flip-vs-wf_vblank
[141.128624] Starting dynamic subtest: A-DP1
[142.425819] Dynamic subtest A-DP1: SUCCESS (1.297s)
[142.444598] Starting dynamic subtest: B-DP1
[143.703540] Dynamic subtest B-DP1: SUCCESS (1.275s)
[143.720788] Starting dynamic subtest: C-DP1
[145.044296] Dynamic subtest C-DP1: SUCCESS (1.338s)
[145.046833] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.920s)
[145.461305] [108/162] (864s left) kms_flip (basic-plain-flip)
[145.773683] Starting subtest: basic-plain-flip
[145.775768] Starting dynamic subtest: A-DP1
[146.821307] Dynamic subtest A-DP1: SUCCESS (1.043s)
[146.884235] Starting dynamic subtest: B-DP1
[147.948214] Dynamic subtest B-DP1: SUCCESS (1.111s)
[148.017294] Starting dynamic subtest: C-DP1
[149.059775] Dynamic subtest C-DP1: SUCCESS (1.097s)
[149.069456] Subtest basic-plain-flip: SUCCESS (3.294s)
[149.641965] [109/162] (860s left) kms_force_connector_basic (force-connector-state)
[149.959743] Starting subtest: force-connector-state
[150.854415] Subtest force-connector-state: SUCCESS (0.899s)
[151.768440] [110/162] (858s left) kms_force_connector_basic (force-edid)
[152.073151] Starting subtest: force-edid
[152.587377] Subtest force-edid: SUCCESS (0.510s)
[153.318684] [111/162] (857s left) kms_force_connector_basic (force-load-detect)
[153.622535] Starting subtest: force-load-detect
[153.624988] Subtest force-load-detect: SKIP (0.000s)
[154.083669] [112/162] (856s left) kms_force_connector_basic (prune-stale-modes)
[154.389044] Starting subtest: prune-stale-modes
[155.016436] Subtest prune-stale-modes: SUCCESS (0.621s)
[155.751668] [113/162] (854s left) kms_frontbuffer_tracking (basic)
[156.070579] Starting subtest: basic
[158.964153] Subtest basic: SUCCESS (2.885s)
[159.119808] [114/162] (851s left) kms_hdmi_inject (inject-audio)
[159.242285] Starting subtest: inject-audio
[159.864155] Subtest inject-audio: SUCCESS (0.618s)
[160.444496] [115/162] (850s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[160.754844] Starting subtest: compare-crc-sanitycheck-xr24
[160.757082] Starting dynamic subtest: pipe-A-DP-1
[161.254381] Dynamic subtest pipe-A-DP-1: SUCCESS (0.494s)
[161.268363] Starting dynamic subtest: pipe-B-DP-1
[161.840227] Dynamic subtest pipe-B-DP-1: SUCCESS (0.581s)
[161.861168] Starting dynamic subtest: pipe-C-DP-1
[162.454619] Dynamic subtest pipe-C-DP-1: SUCCESS (0.604s)
[162.455409] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.695s)
[162.823949] [116/162] (847s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[163.130478] Starting subtest: compare-crc-sanitycheck-nv12
[163.131224] Starting dynamic subtest: pipe-A-DP-1
[163.689703] Dynamic subtest pipe-A-DP-1: SUCCESS (0.556s)
[163.704275] Starting dynamic subtest: pipe-B-DP-1
[164.329534] Dynamic subtest pipe-B-DP-1: SUCCESS (0.635s)
[164.347536] Starting dynamic subtest: pipe-C-DP-1
[164.348832] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[164.349542] Subtest compare-crc-sanitycheck-nv12: SUCCESS (1.203s)
[164.635295] [117/162] (846s left) kms_pipe_crc_basic (hang-read-crc)
[164.936408] Starting subtest: hang-read-crc
[164.937678] Starting dynamic subtest: pipe-A-DP-1
[166.359827] Dynamic subtest pipe-A-DP-1: SUCCESS (1.421s)
[166.379594] Starting dynamic subtest: pipe-B-DP-1
[167.856940] Dynamic subtest pipe-B-DP-1: SUCCESS (1.493s)
[167.875022] Starting dynamic subtest: pipe-C-DP-1
[169.364764] Dynamic subtest pipe-C-DP-1: SUCCESS (1.502s)
[169.365674] Subtest hang-read-crc: SUCCESS (4.428s)
[169.719667] [118/162] (841s left) kms_pipe_crc_basic (nonblocking-crc)
[170.026528] Starting subtest: nonblocking-crc
[170.027117] Starting dynamic subtest: pipe-A-DP-1
[170.712687] Dynamic subtest pipe-A-DP-1: SUCCESS (0.685s)
[170.729604] Starting dynamic subtest: pipe-B-DP-1
[171.515909] Dynamic subtest pipe-B-DP-1: SUCCESS (0.799s)
[171.533883] Starting dynamic subtest: pipe-C-DP-1
[172.319557] Dynamic subtest pipe-C-DP-1: SUCCESS (0.799s)
[172.323242] Subtest nonblocking-crc: SUCCESS (2.296s)
[172.652933] [119/162] (838s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[172.955075] Starting subtest: nonblocking-crc-frame-sequence
[172.955861] Starting dynamic subtest: pipe-A-DP-1
[173.646658] Dynamic subtest pipe-A-DP-1: SUCCESS (0.689s)
[173.667246] Starting dynamic subtest: pipe-B-DP-1
[174.475698] Dynamic subtest pipe-B-DP-1: SUCCESS (0.826s)
[174.496833] Starting dynamic subtest: pipe-C-DP-1
[175.303409] Dynamic subtest pipe-C-DP-1: SUCCESS (0.819s)
[175.304350] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.349s)
[175.631661] [120/162] (835s left) kms_pipe_crc_basic (read-crc)
[175.932630] Starting subtest: read-crc
[175.933582] Starting dynamic subtest: pipe-A-DP-1
[176.595919] Dynamic subtest pipe-A-DP-1: SUCCESS (0.661s)
[176.615392] Starting dynamic subtest: pipe-B-DP-1
[177.388377] Dynamic subtest pipe-B-DP-1: SUCCESS (0.788s)
[177.406034] Starting dynamic subtest: pipe-C-DP-1
[178.126297] Dynamic subtest pipe-C-DP-1: SUCCESS (0.732s)
[178.127244] Subtest read-crc: SUCCESS (2.193s)
[178.447702] [121/162] (832s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[178.750928] Starting subtest: read-crc-frame-sequence
[178.752981] Starting dynamic subtest: pipe-A-DP-1
[179.418860] Dynamic subtest pipe-A-DP-1: SUCCESS (0.662s)
[179.436571] Starting dynamic subtest: pipe-B-DP-1
[180.152639] Dynamic subtest pipe-B-DP-1: SUCCESS (0.733s)
[180.173526] Starting dynamic subtest: pipe-C-DP-1
[180.913264] Dynamic subtest pipe-C-DP-1: SUCCESS (0.754s)
[180.914365] Subtest read-crc-frame-sequence: SUCCESS (2.161s)
[181.234620] [122/162] (830s left) kms_pm_backlight (basic-brightness)
[181.537131] Subtest basic-brightness: SKIP (0.000s)
[181.607729] [123/162] (829s left) kms_pm_rpm (basic-pci-d3-state)
[183.387552] Starting subtest: basic-pci-d3-state
[184.032707] Subtest basic-pci-d3-state: SUCCESS (0.652s)
[184.458768] [124/162] (827s left) kms_pm_rpm (basic-rte)
[184.510036] Starting subtest: basic-rte
[186.847484] Subtest basic-rte: SUCCESS (2.337s)
[188.069245] [125/162] (823s left) kms_psr (psr-primary-page-flip)
[188.376409] Subtest psr-primary-page-flip: SKIP (0.000s)
[188.434116] [126/162] (823s left) kms_psr (psr-cursor-plane-move)
[188.737596] Subtest psr-cursor-plane-move: SKIP (0.000s)
[188.798077] [127/162] (822s left) kms_psr (psr-sprite-plane-onoff)
[189.100868] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[189.164973] [128/162] (822s left) kms_psr (psr-primary-mmap-gtt)
[189.473497] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[189.533823] [129/162] (822s left) kms_setmode (basic-clone-single-crtc)
[189.588803] Starting subtest: basic-clone-single-crtc
[189.590815] Subtest basic-clone-single-crtc: SKIP (0.000s)
[189.691493] [130/162] (822s left) i915_pm_rps (basic-api)
[189.755923] Starting subtest: basic-api
[189.759762] Subtest basic-api: SUCCESS (0.004s)
[189.893307] [131/162] (822s left) prime_self_import (basic-llseek-bad)
[189.945159] Starting subtest: basic-llseek-bad
[189.947619] Subtest basic-llseek-bad: SUCCESS (0.003s)
[190.060673] [132/162] (821s left) prime_self_import (basic-llseek-size)
[190.110942] Starting subtest: basic-llseek-size
[190.115944] Subtest basic-llseek-size: SUCCESS (0.004s)
[190.228710] [133/162] (821s left) prime_self_import (basic-with_fd_dup)
[190.282167] Starting subtest: basic-with_fd_dup
[190.283579] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[190.390696] [134/162] (821s left) prime_self_import (basic-with_one_bo)
[190.440798] Starting subtest: basic-with_one_bo
[190.445705] Subtest basic-with_one_bo: SUCCESS (0.004s)
[190.589404] [135/162] (821s left) prime_self_import (basic-with_one_bo_two_files)
[190.642827] Starting subtest: basic-with_one_bo_two_files
[190.644114] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[190.768251] [136/162] (821s left) prime_self_import (basic-with_two_bos)
[190.822051] Starting subtest: basic-with_two_bos
[190.823312] Subtest basic-with_two_bos: SUCCESS (0.004s)
[190.924126] [137/162] (821s left) prime_vgem (basic-fence-flip)
[191.010568] Starting subtest: basic-fence-flip
[191.724318] Subtest basic-fence-flip: SUCCESS (0.712s)
[191.839127] [138/162] (820s left) prime_vgem (basic-fence-mmap)
[191.915646] Starting subtest: basic-fence-mmap
[191.942257] Subtest basic-fence-mmap: SUCCESS (0.028s)
[192.037852] [139/162] (820s left) prime_vgem (basic-fence-read)
[192.117065] Starting subtest: basic-fence-read
[192.177291] Subtest basic-fence-read: SUCCESS (0.063s)
[192.304186] [140/162] (820s left) prime_vgem (basic-gtt)
[192.390222] Starting subtest: basic-gtt
[192.406012] Subtest basic-gtt: SUCCESS (0.014s)
[192.508063] [141/162] (820s left) prime_vgem (basic-read)
[192.587843] Starting subtest: basic-read
[192.619222] Subtest basic-read: SUCCESS (0.031s)
[192.755516] [142/162] (819s left) prime_vgem (basic-write)
[192.841872] Starting subtest: basic-write
[192.870827] Subtest basic-write: SUCCESS (0.029s)
[193.005504] [143/162] (819s left) vgem_basic (setversion)
[193.073197] Starting subtest: setversion
[193.074565] Subtest setversion: SUCCESS (0.000s)
[193.155743] [144/162] (819s left) vgem_basic (create)
[193.224789] Starting subtest: create
[193.226347] Subtest create: SUCCESS (0.000s)
[193.305315] [145/162] (819s left) vgem_basic (debugfs)
[193.373177] Starting subtest: debugfs
[193.373833] Subtest debugfs: SUCCESS (0.000s)
[193.455270] [146/162] (819s left) vgem_basic (dmabuf-export)
[193.525168] Starting subtest: dmabuf-export
[193.526814] Subtest dmabuf-export: SUCCESS (0.003s)
[193.656881] [147/162] (819s left) vgem_basic (dmabuf-fence)
[193.723205] Starting subtest: dmabuf-fence
[193.723922] Subtest dmabuf-fence: SUCCESS (0.001s)
[193.802469] [148/162] (819s left) vgem_basic (dmabuf-fence-before)
[193.856062] Starting subtest: dmabuf-fence-before
[193.856691] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[193.936722] [149/162] (819s left) vgem_basic (dmabuf-mmap)
[194.006655] Starting subtest: dmabuf-mmap
[194.015675] Subtest dmabuf-mmap: SUCCESS (0.010s)
[194.085830] [150/162] (818s left) vgem_basic (mmap)
[194.139935] Starting subtest: mmap
[194.146396] Subtest mmap: SUCCESS (0.008s)
[194.220148] [151/162] (818s left) vgem_basic (second-client)
[194.291838] Starting subtest: second-client
[194.324822] Subtest second-client: SUCCESS (0.032s)
[194.429795] [152/162] (818s left) vgem_basic (sysfs)
[194.490514] Starting subtest: sysfs
[194.491208] Subtest sysfs: SUCCESS (0.001s)
[194.580089] [153/162] (818s left) core_hotunplug (unbind-rebind)
[194.722235] Starting subtest: unbind-rebind
[199.371972] Subtest unbind-rebind: SUCCESS (4.650s)
[199.426369] [154/162] (813s left) vgem_basic (unload)
[199.483646] Starting subtest: unload
[228.500629] Subtest unload: SUCCESS (29.017s)
[228.639832] [155/162] (784s left) i915_module_load (reload)
[228.690871] Starting subtest: reload
[232.626797] Subtest reload: SUCCESS (3.934s)
[232.711882] [156/162] (780s left) gem_lmem_swapping (basic)
[232.780803] Subtest basic: SKIP (0.000s)
[232.833856] [157/162] (780s left) gem_lmem_swapping (parallel-random-engines)
[232.892049] Subtest parallel-random-engines: SKIP (0.000s)
[232.950851] [158/162] (780s left) gem_lmem_swapping (random-engines)
[233.009596] Subtest random-engines: SKIP (0.000s)
[233.069246] [159/162] (780s left) gem_lmem_swapping (verify-random)
[233.128579] Subtest verify-random: SKIP (0.000s)
[233.185242] [160/162] (780s left) i915_pm_rpm (module-reload)
[236.811577] Starting subtest: module-reload
[251.750166] Subtest module-reload: SUCCESS (14.945s)
[251.785972] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[251.788014] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[251.790476] [161/162] (761s left) i915_selftest (live)