[42.478093] Initializing watchdogs
[42.478345]   /dev/watchdog0
[42.535201] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[42.568184] [001/161] (960s left) i915_module_load (load)
[43.236137] Starting subtest: load
[47.472529] Subtest load: SUCCESS (4.247s)
[47.619302] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[47.623352] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[47.667976] [002/161] (955s left) core_auth (basic-auth)
[47.972560] Starting subtest: basic-auth
[47.993802] Subtest basic-auth: SUCCESS (0.002s)
[48.145480] [003/161] (955s left) debugfs_test (read_all_entries)
[48.390432] Starting subtest: read_all_entries
[48.560525] Subtest read_all_entries: SUCCESS (0.157s)
[48.875639] [004/161] (954s left) debugfs_test (basic-hwmon)
[49.142701] Starting subtest: basic-hwmon
[49.185466] Subtest basic-hwmon: SKIP (0.000s)
[49.404537] [005/161] (954s left) debugfs_test (sysfs)
[49.680295] Starting subtest: sysfs
[49.820085] Subtest sysfs: SUCCESS (0.173s)
[50.118982] [006/161] (953s left) fbdev (eof)
[50.343755] Starting subtest: eof
[50.366037] Subtest eof: SUCCESS (0.002s)
[50.518925] [007/161] (953s left) fbdev (info)
[50.740850] Starting subtest: info
[50.763918] Subtest info: SUCCESS (0.000s)
[50.916399] [008/161] (953s left) fbdev (nullptr)
[51.159219] Starting subtest: nullptr
[51.179701] Subtest nullptr: SUCCESS (0.000s)
[51.336682] [009/161] (953s left) fbdev (read)
[51.562066] Starting subtest: read
[53.052443] Subtest read: SUCCESS (1.495s)
[53.217642] [010/161] (951s left) fbdev (write)
[53.441195] Starting subtest: write
[54.218337] Subtest write: SUCCESS (0.780s)
[54.388717] [011/161] (950s left) gem_basic (bad-close)
[54.646960] Starting subtest: bad-close
[54.690673] Subtest bad-close: SUCCESS (0.000s)
[54.849936] [012/161] (949s left) gem_basic (create-close)
[55.104225] Starting subtest: create-close
[55.155785] Subtest create-close: SUCCESS (0.000s)
[55.325685] [013/161] (949s left) gem_basic (create-fd-close)
[55.590482] Starting subtest: create-fd-close
[55.638168] Subtest create-fd-close: SUCCESS (0.000s)
[55.827427] [014/161] (949s left) gem_busy (busy@all-engines)
[56.100831] Starting subtest: busy
[56.125696] Starting dynamic subtest: all-engines
[56.197383] Dynamic subtest all-engines: SUCCESS (0.115s)
[56.221196] Subtest busy: SUCCESS (0.116s)
[56.698542] [015/161] (948s left) gem_close_race (basic-process)
[56.954195] Starting subtest: basic-process
[57.063830] Subtest basic-process: SUCCESS (0.118s)
[57.369672] [016/161] (948s left) gem_close_race (basic-threads)
[57.643657] Starting subtest: basic-threads
[58.890118] Subtest basic-threads: SUCCESS (1.232s)
[59.188599] [017/161] (946s left) gem_ctx_create (basic)
[59.444475] Starting subtest: basic
[59.465712] Subtest basic: SUCCESS (0.000s)
[59.744799] [018/161] (946s left) gem_ctx_create (basic-files)
[60.011290] Starting subtest: basic-files
[62.043481] Subtest basic-files: SUCCESS (2.018s)
[62.362740] [019/161] (943s left) gem_ctx_exec (basic)
[62.612996] Starting subtest: basic
[62.636721] Subtest basic: SUCCESS (0.009s)
[62.811933] [020/161] (943s left) gem_exec_basic (basic)
[63.059488] Starting subtest: basic
[63.080636] Starting dynamic subtest: rcs0-smem
[63.104531] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[63.147535] Starting dynamic subtest: bcs0-smem
[63.168339] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[63.212631] Starting dynamic subtest: vcs0-smem
[63.233592] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[63.288240] Starting dynamic subtest: vecs0-smem
[63.326133] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[63.348417] Subtest basic: SUCCESS (0.015s)
[63.564599] [021/161] (942s left) gem_exec_create (basic)
[63.848941] Starting subtest: basic
[63.874613] Starting dynamic subtest: smem
[65.925070] Dynamic subtest smem: SUCCESS (2.072s)
[65.946139] Subtest basic: SUCCESS (2.073s)
[66.210464] [022/161] (940s left) gem_exec_fence (basic-busy)
[66.593052] Starting subtest: basic-busy
[66.615720] Starting dynamic subtest: rcs0
[66.659085] Dynamic subtest rcs0: SUCCESS (0.040s)
[66.717981] Starting dynamic subtest: bcs0
[66.768700] Dynamic subtest bcs0: SUCCESS (0.031s)
[66.823392] Starting dynamic subtest: vcs0
[66.867490] Dynamic subtest vcs0: SUCCESS (0.064s)
[66.908807] Starting dynamic subtest: vecs0
[66.950248] Dynamic subtest vecs0: SUCCESS (0.028s)
[66.972970] Subtest basic-busy: SUCCESS (0.166s)
[67.731826] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[67.759210] [023/161] (939s left) gem_exec_fence (basic-wait)
[68.386172] Starting subtest: basic-wait
[68.408958] Starting dynamic subtest: rcs0
[68.455901] Dynamic subtest rcs0: SUCCESS (0.043s)
[68.498807] Starting dynamic subtest: bcs0
[68.520302] Dynamic subtest bcs0: SUCCESS (0.039s)
[68.567681] Starting dynamic subtest: vcs0
[68.589480] Dynamic subtest vcs0: SUCCESS (0.043s)
[68.631204] Starting dynamic subtest: vecs0
[68.651326] Dynamic subtest vecs0: SUCCESS (0.033s)
[68.674300] Subtest basic-wait: SUCCESS (0.164s)
[69.667241] [024/161] (937s left) gem_exec_fence (basic-await)
[70.170172] Starting subtest: basic-await
[70.193120] Starting dynamic subtest: rcs0
[70.379632] Dynamic subtest rcs0: SUCCESS (0.206s)
[70.422592] Starting dynamic subtest: bcs0
[70.571340] Dynamic subtest bcs0: SUCCESS (0.188s)
[70.614229] Starting dynamic subtest: vcs0
[70.751955] Dynamic subtest vcs0: SUCCESS (0.179s)
[70.796803] Starting dynamic subtest: vecs0
[70.933267] Dynamic subtest vecs0: SUCCESS (0.182s)
[70.961975] Subtest basic-await: SUCCESS (0.759s)
[71.777600] [025/161] (935s left) gem_exec_fence (nb-await)
[72.144914] Starting subtest: nb-await
[72.166543] Starting dynamic subtest: rcs0
[72.228640] Dynamic subtest rcs0: SUCCESS (0.082s)
[72.294173] Starting dynamic subtest: bcs0
[72.315604] Dynamic subtest bcs0: SUCCESS (0.075s)
[72.361533] Starting dynamic subtest: vcs0
[72.386592] Dynamic subtest vcs0: SUCCESS (0.073s)
[72.448247] Starting dynamic subtest: vecs0
[72.469603] Dynamic subtest vecs0: SUCCESS (0.072s)
[72.490241] Subtest nb-await: SUCCESS (0.305s)
[73.267516] [026/161] (934s left) gem_exec_gttfill (basic)
[73.539205] Starting subtest: basic
[104.100668] Subtest basic: SUCCESS (30.568s)
[104.430935] [027/161] (903s left) gem_exec_parallel (engines)
[104.837058] Starting subtest: engines
[104.857743] Starting dynamic subtest: basic
[109.056052] Dynamic subtest basic: SUCCESS (4.053s)
[109.101359] Starting dynamic subtest: contexts
[114.866579] Dynamic subtest contexts: SUCCESS (5.849s)
[114.910808] Starting dynamic subtest: fds
[120.471555] Dynamic subtest fds: SUCCESS (5.570s)
[120.516027] Starting dynamic subtest: userptr
[124.625416] Dynamic subtest userptr: SUCCESS (4.155s)
[124.648367] Subtest engines: SUCCESS (19.629s)
[124.829743] [028/161] (883s left) gem_exec_store (basic)
[125.166467] Starting subtest: basic
[125.186981] Subtest basic: SUCCESS (0.088s)
[125.475420] [029/161] (882s left) gem_flink_basic (bad-flink)
[125.743494] Starting subtest: bad-flink
[125.767878] Subtest bad-flink: SUCCESS (0.000s)
[125.941963] [030/161] (882s left) gem_flink_basic (bad-open)
[126.194526] Starting subtest: bad-open
[126.217132] Subtest bad-open: SUCCESS (0.000s)
[126.394852] [031/161] (882s left) gem_flink_basic (basic)
[126.650411] Starting subtest: basic
[126.677510] Subtest basic: SUCCESS (0.000s)
[126.852137] [032/161] (881s left) gem_flink_basic (double-flink)
[127.133103] Starting subtest: double-flink
[127.158057] Subtest double-flink: SUCCESS (0.000s)
[127.330198] [033/161] (881s left) gem_flink_basic (flink-lifetime)
[127.585223] Starting subtest: flink-lifetime
[127.607232] Subtest flink-lifetime: SUCCESS (0.003s)
[127.786284] [034/161] (881s left) gem_huc_copy (huc-copy)
[128.057257] Starting subtest: huc-copy
[128.099342] Subtest huc-copy: SKIP (0.000s)
[128.267158] [035/161] (881s left) gem_linear_blits (basic)
[128.667338] Starting subtest: basic
[128.715847] Subtest basic: SUCCESS (0.030s)
[128.994548] [036/161] (880s left) gem_mmap (basic)
[129.409413] Starting subtest: basic
[129.516289] Subtest basic: SUCCESS (0.001s)
[129.688838] [037/161] (879s left) gem_mmap_gtt (basic)
[129.964582] Starting subtest: basic
[129.987130] Subtest basic: SUCCESS (0.002s)
[130.154206] [038/161] (879s left) gem_render_linear_blits (basic)
[130.398824] Starting subtest: basic
[130.503897] Subtest basic: SUCCESS (0.105s)
[130.694405] [039/161] (879s left) gem_render_tiled_blits (basic)
[130.964288] Starting subtest: basic
[131.092792] Subtest basic: SUCCESS (0.137s)
[131.261519] [040/161] (878s left) gem_ringfill (basic-all)
[131.826101] Starting subtest: basic-all
[133.020886] Subtest basic-all: SUCCESS (1.222s)
[133.263963] [041/161] (877s left) gem_softpin (allocator-basic)
[133.531949] Starting subtest: allocator-basic
[133.771758] Subtest allocator-basic: SUCCESS (0.282s)
[133.996736] [042/161] (876s left) gem_softpin (allocator-basic-reserve)
[134.261070] Starting subtest: allocator-basic-reserve
[134.502175] Subtest allocator-basic-reserve: SUCCESS (0.283s)
[134.714332] [043/161] (875s left) gem_softpin (safe-alignment)
[134.939958] Starting subtest: safe-alignment
[134.961278] Subtest safe-alignment: SUCCESS (0.034s)
[135.192866] [044/161] (875s left) gem_sync (basic-all)
[135.613583] Starting subtest: basic-all
[137.987541] Subtest basic-all: SUCCESS (2.377s)
[138.157686] [045/161] (872s left) gem_sync (basic-each)
[138.594689] Starting subtest: basic-each
[140.798349] Subtest basic-each: SUCCESS (2.387s)
[141.099131] [046/161] (870s left) gem_tiled_blits (basic)
[141.447475] Starting subtest: basic
[141.515695] Subtest basic: SUCCESS (0.062s)
[141.815754] [047/161] (869s left) gem_tiled_fence_blits (basic)
[142.364853] Starting subtest: basic
[142.410273] Subtest basic: SUCCESS (0.047s)
[142.709743] [048/161] (868s left) gem_tiled_pread_basic
[143.592322] [049/161] (868s left) gem_wait (busy@all-engines)
[143.887272] Starting subtest: busy
[143.908248] Starting dynamic subtest: all-engines
[144.412603] Dynamic subtest all-engines: SUCCESS (0.533s)
[144.433713] Subtest busy: SUCCESS (0.534s)
[144.762143] [050/161] (867s left) gem_wait (wait@all-engines)
[145.010396] Starting subtest: wait
[145.030872] Starting dynamic subtest: all-engines
[146.064926] Dynamic subtest all-engines: SUCCESS (1.042s)
[146.085087] Subtest wait: SUCCESS (1.042s)
[146.413580] [051/161] (865s left) i915_getparams_basic (basic-eu-total)
[146.672654] Starting subtest: basic-eu-total
[146.718116] Subtest basic-eu-total: SUCCESS (0.000s)
[146.891634] [052/161] (865s left) i915_getparams_basic (basic-subslice-total)
[147.132217] Starting subtest: basic-subslice-total
[147.174952] Subtest basic-subslice-total: SUCCESS (0.000s)
[147.344357] [053/161] (865s left) i915_hangman (error-state-basic)
[147.587199] Starting subtest: error-state-basic
[147.608698] Subtest error-state-basic: SUCCESS (0.022s)
[147.812385] [054/161] (864s left) i915_pciid
[148.262530] [055/161] (864s left) kms_addfb_basic (addfb25-4-tiled)
[148.694603] Starting subtest: addfb25-4-tiled
[148.716865] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[148.884997] [056/161] (864s left) kms_addfb_basic (addfb25-bad-modifier)
[149.225604] Starting subtest: addfb25-bad-modifier
[149.245649] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[149.427678] [057/161] (863s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[149.795077] Starting subtest: addfb25-framebuffer-vs-set-tiling
[149.814352] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[149.984763] [058/161] (863s left) kms_addfb_basic (addfb25-modifier-no-flag)
[150.328490] Starting subtest: addfb25-modifier-no-flag
[150.348504] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[150.537938] [059/161] (862s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[150.887544] Starting subtest: addfb25-x-tiled-legacy
[150.910101] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[151.100887] [060/161] (862s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[151.457449] Starting subtest: addfb25-x-tiled-mismatch-legacy
[151.479918] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[151.666540] [061/161] (862s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[152.015887] Starting subtest: addfb25-yf-tiled-legacy
[152.038844] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[152.208084] [062/161] (861s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[152.555307] Starting subtest: addfb25-y-tiled-legacy
[152.575558] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[152.746335] [063/161] (861s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[153.100601] Starting subtest: addfb25-y-tiled-small-legacy
[153.123501] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[153.332724] [064/161] (861s left) kms_addfb_basic (bad-pitch-0)
[153.602219] Starting subtest: bad-pitch-0
[153.624655] Subtest bad-pitch-0: SUCCESS (0.000s)
[153.902665] [065/161] (860s left) kms_addfb_basic (bad-pitch-1024)
[154.174782] Starting subtest: bad-pitch-1024
[154.198914] Subtest bad-pitch-1024: SUCCESS (0.000s)
[154.480542] [066/161] (860s left) kms_addfb_basic (bad-pitch-128)
[154.753384] Starting subtest: bad-pitch-128
[154.777144] Subtest bad-pitch-128: SUCCESS (0.000s)
[155.035776] [067/161] (859s left) kms_addfb_basic (bad-pitch-256)
[155.311189] Starting subtest: bad-pitch-256
[155.334392] Subtest bad-pitch-256: SUCCESS (0.000s)
[155.608765] [068/161] (859s left) kms_addfb_basic (bad-pitch-32)
[155.844160] Starting subtest: bad-pitch-32
[155.866191] Subtest bad-pitch-32: SUCCESS (0.000s)
[156.146551] [069/161] (859s left) kms_addfb_basic (bad-pitch-63)
[156.403821] Starting subtest: bad-pitch-63
[156.426168] Subtest bad-pitch-63: SUCCESS (0.000s)
[156.696293] [070/161] (858s left) kms_addfb_basic (bad-pitch-65536)
[156.959399] Starting subtest: bad-pitch-65536
[156.979126] Subtest bad-pitch-65536: SUCCESS (0.001s)
[157.242543] [071/161] (858s left) kms_addfb_basic (bad-pitch-999)
[157.506287] Starting subtest: bad-pitch-999
[157.526384] Subtest bad-pitch-999: SUCCESS (0.000s)
[157.795877] [072/161] (857s left) kms_addfb_basic (basic)
[158.032621] Starting subtest: basic
[158.056469] Subtest basic: SUCCESS (0.000s)
[158.346330] [073/161] (857s left) kms_addfb_basic (basic-x-tiled-legacy)
[158.582849] Starting subtest: basic-x-tiled-legacy
[158.602759] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[158.926880] [074/161] (857s left) kms_addfb_basic (basic-y-tiled-legacy)
[159.188662] Starting subtest: basic-y-tiled-legacy
[159.208129] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[159.476729] [075/161] (856s left) kms_addfb_basic (bo-too-small)
[159.829651] Starting subtest: bo-too-small
[159.849984] Subtest bo-too-small: SUCCESS (0.001s)
[160.059636] [076/161] (856s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[160.391915] Starting subtest: bo-too-small-due-to-tiling
[160.418833] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[160.591441] [077/161] (855s left) kms_addfb_basic (clobberred-modifier)
[160.834039] Starting subtest: clobberred-modifier
[160.855115] Subtest clobberred-modifier: SUCCESS (0.000s)
[161.133725] [078/161] (855s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[161.375770] Starting subtest: framebuffer-vs-set-tiling
[161.395496] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[161.678586] [079/161] (855s left) kms_addfb_basic (invalid-get-prop)
[161.941532] Starting subtest: invalid-get-prop
[161.960918] Subtest invalid-get-prop: SUCCESS (0.000s)
[162.233688] [080/161] (854s left) kms_addfb_basic (invalid-get-prop-any)
[162.477886] Starting subtest: invalid-get-prop-any
[162.500443] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[162.795807] [081/161] (854s left) kms_addfb_basic (invalid-set-prop)
[163.064831] Starting subtest: invalid-set-prop
[163.090396] Subtest invalid-set-prop: SUCCESS (0.000s)
[163.351865] [082/161] (854s left) kms_addfb_basic (invalid-set-prop-any)
[163.619201] Starting subtest: invalid-set-prop-any
[163.639357] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[163.900338] [083/161] (853s left) kms_addfb_basic (no-handle)
[164.143040] Starting subtest: no-handle
[164.165057] Subtest no-handle: SUCCESS (0.000s)
[164.426773] [084/161] (853s left) kms_addfb_basic (size-max)
[164.778852] Starting subtest: size-max
[164.801226] Subtest size-max: SUCCESS (0.000s)
[164.978748] [085/161] (852s left) kms_addfb_basic (small-bo)
[165.331791] Starting subtest: small-bo
[165.353251] Subtest small-bo: SUCCESS (0.000s)
[165.525365] [086/161] (852s left) kms_addfb_basic (tile-pitch-mismatch)
[165.766976] Starting subtest: tile-pitch-mismatch
[165.787883] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[166.077748] [087/161] (852s left) kms_addfb_basic (too-high)
[166.432161] Starting subtest: too-high
[166.453653] Subtest too-high: SUCCESS (0.003s)
[166.629603] [088/161] (851s left) kms_addfb_basic (too-wide)
[166.974776] Starting subtest: too-wide
[166.997276] Subtest too-wide: SUCCESS (0.000s)
[167.179775] [089/161] (851s left) kms_addfb_basic (unused-handle)
[167.441311] Starting subtest: unused-handle
[167.462122] Subtest unused-handle: SUCCESS (0.000s)
[167.715325] [090/161] (851s left) kms_addfb_basic (unused-modifier)
[167.967081] Starting subtest: unused-modifier
[167.989326] Subtest unused-modifier: SUCCESS (0.000s)
[168.281082] [091/161] (850s left) kms_addfb_basic (unused-offsets)
[168.545591] Starting subtest: unused-offsets
[168.568198] Subtest unused-offsets: SUCCESS (0.000s)
[168.848737] [092/161] (850s left) kms_addfb_basic (unused-pitches)
[169.117442] Starting subtest: unused-pitches
[169.138552] Subtest unused-pitches: SUCCESS (0.000s)
[169.411075] [093/161] (849s left) kms_busy (basic)
[169.753896] Starting subtest: basic
[169.775098] Starting dynamic subtest: flip
[170.589279] Dynamic subtest flip: SUCCESS (0.816s)
[170.640327] Starting dynamic subtest: modeset
[172.066782] Dynamic subtest modeset: SUCCESS (1.459s)
[172.088647] Subtest basic: SUCCESS (2.280s)
[172.540661] [094/161] (846s left) kms_prop_blob (basic)
[172.792270] Starting subtest: basic
[172.814750] Subtest basic: SUCCESS (0.000s)
[172.988483] [095/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[173.347993] Starting subtest: basic-busy-flip-before-cursor-atomic
[173.392172] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[173.561426] [096/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[173.897371] Starting subtest: basic-busy-flip-before-cursor-legacy
[173.938408] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[174.110156] [097/161] (845s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[174.467503] Starting subtest: basic-flip-after-cursor-atomic
[175.449666] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.995s)
[175.903365] [098/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[176.261517] Starting subtest: basic-flip-after-cursor-legacy
[177.228173] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.988s)
[177.681209] [099/161] (842s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[178.042154] Starting subtest: basic-flip-after-cursor-varying-size
[179.023350] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.999s)
[179.473812] [100/161] (840s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[179.824630] Starting subtest: basic-flip-before-cursor-atomic
[180.798398] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.983s)
[181.237997] [101/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[181.588306] Starting subtest: basic-flip-before-cursor-legacy
[182.557039] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.983s)
[183.006613] [102/161] (837s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[183.358924] Starting subtest: basic-flip-before-cursor-varying-size
[184.345909] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.001s)
[184.801653] [103/161] (836s left) kms_dsc (dsc-basic)
[185.168059] Subtest dsc-basic: SKIP (0.000s)
[185.338761] [104/161] (835s left) kms_flip (basic-flip-vs-dpms)
[185.729055] Starting subtest: basic-flip-vs-dpms
[185.757126] Starting dynamic subtest: A-DP1
[186.983220] Dynamic subtest A-DP1: SUCCESS (1.275s)
[187.032197] Starting dynamic subtest: B-DP1
[188.147636] Dynamic subtest B-DP1: SUCCESS (1.178s)
[188.194505] Starting dynamic subtest: C-DP1
[189.332727] Dynamic subtest C-DP1: SUCCESS (1.170s)
[189.355222] Subtest basic-flip-vs-dpms: SUCCESS (3.633s)
[189.844643] [105/161] (831s left) kms_flip (basic-flip-vs-modeset)
[190.226738] Starting subtest: basic-flip-vs-modeset
[190.246945] Starting dynamic subtest: A-DP1
[191.486370] Dynamic subtest A-DP1: SUCCESS (1.278s)
[191.534538] Starting dynamic subtest: B-DP1
[192.649937] Dynamic subtest B-DP1: SUCCESS (1.168s)
[192.708530] Starting dynamic subtest: C-DP1
[193.815482] Dynamic subtest C-DP1: SUCCESS (1.159s)
[193.837102] Subtest basic-flip-vs-modeset: SUCCESS (3.616s)
[194.278472] [106/161] (827s left) kms_flip (basic-flip-vs-wf_vblank)
[194.661951] Starting subtest: basic-flip-vs-wf_vblank
[194.685167] Starting dynamic subtest: A-DP1
[196.079775] Dynamic subtest A-DP1: SUCCESS (1.441s)
[196.128079] Starting dynamic subtest: B-DP1
[197.505301] Dynamic subtest B-DP1: SUCCESS (1.419s)
[197.554731] Starting dynamic subtest: C-DP1
[198.926534] Dynamic subtest C-DP1: SUCCESS (1.424s)
[198.947051] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.296s)
[199.480088] [107/161] (822s left) kms_flip (basic-plain-flip)
[199.857895] Starting subtest: basic-plain-flip
[199.880193] Starting dynamic subtest: A-DP1
[200.997903] Dynamic subtest A-DP1: SUCCESS (1.168s)
[201.045250] Starting dynamic subtest: B-DP1
[202.137194] Dynamic subtest B-DP1: SUCCESS (1.134s)
[202.184973] Starting dynamic subtest: C-DP1
[203.276251] Dynamic subtest C-DP1: SUCCESS (1.139s)
[203.299234] Subtest basic-plain-flip: SUCCESS (3.454s)
[203.830263] [108/161] (818s left) kms_force_connector_basic (force-connector-state)
[204.134050] Subtest force-connector-state: SKIP (0.000s)
[204.315541] [109/161] (817s left) kms_force_connector_basic (force-edid)
[204.593487] Subtest force-edid: SKIP (0.000s)
[204.771890] [110/161] (817s left) kms_force_connector_basic (force-load-detect)
[205.065153] Subtest force-load-detect: SKIP (0.000s)
[205.243653] [111/161] (817s left) kms_force_connector_basic (prune-stale-modes)
[205.533099] Subtest prune-stale-modes: SKIP (0.000s)
[205.707693] [112/161] (816s left) kms_frontbuffer_tracking (basic)
[206.131244] Starting subtest: basic
[209.874347] Subtest basic: SUCCESS (3.804s)
[210.112772] [113/161] (812s left) kms_hdmi_inject (inject-audio)
[210.379137] Subtest inject-audio: SKIP (0.000s)
[210.551661] [114/161] (812s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[210.916247] Starting subtest: compare-crc-sanitycheck-xr24
[210.938244] Starting dynamic subtest: pipe-A-DP-1
[211.405842] Dynamic subtest pipe-A-DP-1: SUCCESS (0.474s)
[211.458106] Starting dynamic subtest: pipe-B-DP-1
[212.011667] Dynamic subtest pipe-B-DP-1: SUCCESS (0.629s)
[212.059932] Starting dynamic subtest: pipe-C-DP-1
[212.665170] Dynamic subtest pipe-C-DP-1: SUCCESS (0.644s)
[212.685714] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.762s)
[213.130234] [115/161] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[213.474433] Starting subtest: compare-crc-sanitycheck-nv12
[213.496710] Starting dynamic subtest: pipe-A-DP-1
[213.538151] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[213.601698] Starting dynamic subtest: pipe-B-DP-1
[213.645933] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[213.713133] Starting dynamic subtest: pipe-C-DP-1
[213.757968] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[213.800558] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[213.982241] [116/161] (809s left) kms_pipe_crc_basic (hang-read-crc)
[214.327967] Starting subtest: hang-read-crc
[214.350231] Starting dynamic subtest: pipe-A-DP-1
[215.640160] Dynamic subtest pipe-A-DP-1: SUCCESS (1.312s)
[215.719958] Starting dynamic subtest: pipe-B-DP-1
[217.105881] Dynamic subtest pipe-B-DP-1: SUCCESS (1.461s)
[217.158251] Starting dynamic subtest: pipe-C-DP-1
[218.586277] Dynamic subtest pipe-C-DP-1: SUCCESS (1.488s)
[218.614154] Subtest hang-read-crc: SUCCESS (4.276s)
[219.021543] [117/161] (804s left) kms_pipe_crc_basic (nonblocking-crc)
[219.392391] Starting subtest: nonblocking-crc
[219.412991] Starting dynamic subtest: pipe-A-DP-1
[220.011721] Dynamic subtest pipe-A-DP-1: SUCCESS (0.626s)
[220.062668] Starting dynamic subtest: pipe-B-DP-1
[220.819172] Dynamic subtest pipe-B-DP-1: SUCCESS (0.792s)
[220.867234] Starting dynamic subtest: pipe-C-DP-1
[221.669535] Dynamic subtest pipe-C-DP-1: SUCCESS (0.819s)
[221.693420] Subtest nonblocking-crc: SUCCESS (2.253s)
[222.076962] [118/161] (801s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[222.415576] Starting subtest: nonblocking-crc-frame-sequence
[222.438183] Starting dynamic subtest: pipe-A-DP-1
[223.084242] Dynamic subtest pipe-A-DP-1: SUCCESS (0.665s)
[223.133880] Starting dynamic subtest: pipe-B-DP-1
[223.872411] Dynamic subtest pipe-B-DP-1: SUCCESS (0.791s)
[223.932412] Starting dynamic subtest: pipe-C-DP-1
[224.679144] Dynamic subtest pipe-C-DP-1: SUCCESS (0.804s)
[224.732616] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.276s)
[225.119852] [119/161] (798s left) kms_pipe_crc_basic (read-crc)
[225.478720] Starting subtest: read-crc
[225.500561] Starting dynamic subtest: pipe-A-DP-1
[226.069039] Dynamic subtest pipe-A-DP-1: SUCCESS (0.585s)
[226.118708] Starting dynamic subtest: pipe-B-DP-1
[226.812432] Dynamic subtest pipe-B-DP-1: SUCCESS (0.735s)
[226.880929] Starting dynamic subtest: pipe-C-DP-1
[227.599887] Dynamic subtest pipe-C-DP-1: SUCCESS (0.754s)
[227.620375] Subtest read-crc: SUCCESS (2.090s)
[227.993167] [120/161] (796s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[228.350439] Starting subtest: read-crc-frame-sequence
[228.370702] Starting dynamic subtest: pipe-A-DP-1
[228.937062] Dynamic subtest pipe-A-DP-1: SUCCESS (0.582s)
[228.988521] Starting dynamic subtest: pipe-B-DP-1
[229.673067] Dynamic subtest pipe-B-DP-1: SUCCESS (0.730s)
[229.752843] Starting dynamic subtest: pipe-C-DP-1
[230.434156] Dynamic subtest pipe-C-DP-1: SUCCESS (0.749s)
[230.454821] Subtest read-crc-frame-sequence: SUCCESS (2.078s)
[230.853469] [121/161] (793s left) kms_pm_backlight (basic-brightness)
[231.221433] Subtest basic-brightness: SKIP (0.000s)
[231.396423] [122/161] (793s left) kms_pm_rpm (basic-pci-d3-state)
[233.239650] Starting subtest: basic-pci-d3-state
[233.925487] Subtest basic-pci-d3-state: SUCCESS (0.679s)
[234.258109] [123/161] (790s left) kms_pm_rpm (basic-rte)
[234.520113] Starting subtest: basic-rte
[236.719840] Subtest basic-rte: SUCCESS (2.240s)
[237.702493] [124/161] (787s left) kms_psr (psr-primary-page-flip)
[238.077868] Subtest psr-primary-page-flip: SKIP (0.000s)
[238.256928] [125/161] (786s left) kms_psr (psr-cursor-plane-move)
[238.617889] Subtest psr-cursor-plane-move: SKIP (0.000s)
[238.802253] [126/161] (786s left) kms_psr (psr-sprite-plane-onoff)
[239.169067] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[239.347984] [127/161] (785s left) kms_psr (psr-primary-mmap-gtt)
[239.738783] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[239.918527] [128/161] (785s left) kms_setmode (basic-clone-single-crtc)
[240.208954] Starting subtest: basic-clone-single-crtc
[240.277225] Subtest basic-clone-single-crtc: SKIP (0.000s)
[240.451647] [129/161] (785s left) i915_pm_rps (basic-api)
[240.728862] Starting subtest: basic-api
[240.751880] Subtest basic-api: SUCCESS (0.007s)
[240.963349] [130/161] (784s left) prime_self_import (basic-llseek-bad)
[241.218587] Starting subtest: basic-llseek-bad
[241.264529] Subtest basic-llseek-bad: SUCCESS (0.005s)
[241.444686] [131/161] (784s left) prime_self_import (basic-llseek-size)
[241.672163] Starting subtest: basic-llseek-size
[241.715651] Subtest basic-llseek-size: SUCCESS (0.008s)
[241.895123] [132/161] (784s left) prime_self_import (basic-with_fd_dup)
[242.134400] Starting subtest: basic-with_fd_dup
[242.179366] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[242.360110] [133/161] (784s left) prime_self_import (basic-with_one_bo)
[242.590674] Starting subtest: basic-with_one_bo
[242.633582] Subtest basic-with_one_bo: SUCCESS (0.009s)
[242.816476] [134/161] (783s left) prime_self_import (basic-with_one_bo_two_files)
[243.067921] Starting subtest: basic-with_one_bo_two_files
[243.114851] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[243.300773] [135/161] (783s left) prime_self_import (basic-with_two_bos)
[243.548959] Starting subtest: basic-with_two_bos
[243.599773] Subtest basic-with_two_bos: SUCCESS (0.008s)
[243.777652] [136/161] (783s left) prime_vgem (basic-fence-flip)
[244.075744] Starting subtest: basic-fence-flip
[244.722325] Subtest basic-fence-flip: SUCCESS (0.679s)
[244.940679] [137/161] (782s left) prime_vgem (basic-fence-mmap)
[245.263741] Starting subtest: basic-fence-mmap
[245.291493] Subtest basic-fence-mmap: SUCCESS (0.080s)
[245.512975] [138/161] (781s left) prime_vgem (basic-fence-read)
[245.799321] Starting subtest: basic-fence-read
[245.899461] Subtest basic-fence-read: SUCCESS (0.149s)
[246.140443] [139/161] (781s left) prime_vgem (basic-gtt)
[246.423234] Starting subtest: basic-gtt
[246.446545] Subtest basic-gtt: SUCCESS (0.034s)
[246.648659] [140/161] (781s left) prime_vgem (basic-read)
[246.939743] Starting subtest: basic-read
[246.983730] Subtest basic-read: SUCCESS (0.068s)
[247.181373] [141/161] (780s left) prime_vgem (basic-write)
[247.450579] Starting subtest: basic-write
[247.501535] Subtest basic-write: SUCCESS (0.094s)
[247.698865] [142/161] (780s left) vgem_basic (setversion)
[247.976898] Starting subtest: setversion
[248.021116] Subtest setversion: SUCCESS (0.000s)
[248.205962] [143/161] (780s left) vgem_basic (create)
[248.473328] Starting subtest: create
[248.496302] Subtest create: SUCCESS (0.001s)
[248.673752] [144/161] (779s left) vgem_basic (debugfs)
[248.942648] Starting subtest: debugfs
[248.967537] Subtest debugfs: SUCCESS (0.001s)
[249.145166] [145/161] (779s left) vgem_basic (dmabuf-export)
[249.418134] Starting subtest: dmabuf-export
[249.462926] Subtest dmabuf-export: SUCCESS (0.005s)
[249.645766] [146/161] (779s left) vgem_basic (dmabuf-fence)
[249.919501] Starting subtest: dmabuf-fence
[249.940987] Subtest dmabuf-fence: SUCCESS (0.002s)
[250.120277] [147/161] (779s left) vgem_basic (dmabuf-fence-before)
[250.394892] Starting subtest: dmabuf-fence-before
[250.418334] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[250.604088] [148/161] (778s left) vgem_basic (dmabuf-mmap)
[250.871349] Starting subtest: dmabuf-mmap
[250.894479] Subtest dmabuf-mmap: SUCCESS (0.041s)
[251.073976] [149/161] (778s left) vgem_basic (mmap)
[251.326577] Starting subtest: mmap
[251.350461] Subtest mmap: SUCCESS (0.024s)
[251.526715] [150/161] (778s left) vgem_basic (second-client)
[251.780325] Starting subtest: second-client
[251.822408] Subtest second-client: SUCCESS (0.032s)
[252.008963] [151/161] (777s left) vgem_basic (sysfs)
[252.262772] Starting subtest: sysfs
[252.284485] Subtest sysfs: SUCCESS (0.001s)
[252.466514] [152/161] (777s left) core_hotunplug (unbind-rebind)
[252.858362] Starting subtest: unbind-rebind
[257.570696] Subtest unbind-rebind: SUCCESS (4.710s)
[257.754834] [153/161] (772s left) vgem_basic (unload)
[258.004781] Starting subtest: unload
[287.359577] Subtest unload: SUCCESS (29.219s)
[287.535791] [154/161] (743s left) i915_module_load (reload)
[287.789518] Starting subtest: reload
[293.528946] Subtest reload: SUCCESS (5.755s)
[293.766168] [155/161] (737s left) gem_lmem_swapping (basic)
[294.100773] Subtest basic: SKIP (0.000s)
[294.276533] [156/161] (736s left) gem_lmem_swapping (parallel-random-engines)
[294.560268] Subtest parallel-random-engines: SKIP (0.000s)
[294.736270] [157/161] (736s left) gem_lmem_swapping (random-engines)
[295.025343] Subtest random-engines: SKIP (0.000s)
[295.203947] [158/161] (736s left) gem_lmem_swapping (verify-random)
[295.495112] Subtest verify-random: SKIP (0.000s)
[295.664922] [159/161] (735s left) i915_pm_rpm (module-reload)
[297.814858] Starting subtest: module-reload
[312.925622] Subtest module-reload: SUCCESS (15.131s)
[313.051064] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[313.055384] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[313.080272] [160/161] (718s left) i915_selftest (live)
[313.323365] Starting subtest: live
[313.344414] Starting dynamic subtest: sanitycheck
[317.244651] Dynamic subtest sanitycheck: SUCCESS (3.926s)
[317.306263] Starting dynamic subtest: uncore
[321.193500] Dynamic subtest uncore: SUCCESS (3.944s)
[321.252255] Starting dynamic subtest: workarounds
[325.447462] Dynamic subtest workarounds: SUCCESS (4.250s)
[325.508954] Starting dynamic subtest: gt_engines
[330.006051] Dynamic subtest gt_engines: SUCCESS (4.554s)
[330.050376] Starting dynamic subtest: gt_timelines
[354.025554] Dynamic subtest gt_timelines: SUCCESS (24.012s)
[354.087452] Starting dynamic subtest: gt_contexts
[358.276166] Dynamic subtest gt_contexts: SUCCESS (4.242s)
[358.320466] Starting dynamic subtest: gt_lrc
[364.727106] Dynamic subtest gt_lrc: SUCCESS (6.446s)
[364.787245] Starting dynamic subtest: gt_mocs
[368.676823] Dynamic subtest gt_mocs: SUCCESS (3.946s)
[368.725995] Starting dynamic subtest: gt_pm
[381.534147] Dynamic subtest gt_pm: SUCCESS (12.855s)
[381.594981] Starting dynamic subtest: gt_heartbeat
[385.339305] Dynamic subtest gt_heartbeat: SUCCESS (3.802s)
[385.380968] Starting dynamic subtest: gt_tlb
[390.078063] Dynamic subtest gt_tlb: SUCCESS (4.731s)
[390.139089] Starting dynamic subtest: requests
[400.140422] Dynamic subtest requests: SUCCESS (10.055s)
[400.199115] Starting dynamic subtest: migrate
[407.301372] Dynamic subtest migrate: SUCCESS (7.159s)
[407.384500] Starting dynamic subtest: active
[411.346328] Dynamic subtest active: SUCCESS (4.044s)
[411.388591] Starting dynamic subtest: objects
[417.732940] Dynamic subtest objects: SUCCESS (6.381s)
[417.791792] Starting dynamic subtest: mman
[428.717882] Dynamic subtest mman: SUCCESS (10.975s)
[428.774841] Starting dynamic subtest: dmabuf
[432.747101] Dynamic subtest dmabuf: SUCCESS (4.027s)
[432.807623] Starting dynamic subtest: vma
[436.791541] Dynamic subtest vma: SUCCESS (4.042s)
[436.843264] Starting dynamic subtest: coherency
[444.127230] Dynamic subtest coherency: SUCCESS (7.331s)
[444.170041] Starting dynamic subtest: gtt
[455.816573] Dynamic subtest gtt: SUCCESS (11.681s)
[455.860579] Starting dynamic subtest: gem
[461.001051] Dynamic subtest gem: SUCCESS (5.182s)
[461.045385] Starting dynamic subtest: evict
[470.198350] Dynamic subtest evict: SUCCESS (9.192s)
[470.241180] Starting dynamic subtest: hugepages
[480.830048] Dynamic subtest hugepages: SUCCESS (10.627s)
[480.892448] Starting dynamic subtest: gem_contexts
[507.964122] Dynamic subtest gem_contexts: SUCCESS (26.955s)
[508.015900] Starting dynamic subtest: client
[511.771895] Dynamic subtest client: SUCCESS (3.975s)
[511.832155] Starting dynamic subtest: gem_migrate
[515.466826] Dynamic subtest gem_migrate: SUCCESS (3.694s)
[515.508742] Starting dynamic subtest: reset
[529.478881] Dynamic subtest reset: SUCCESS (14.006s)
[529.540453] Starting dynamic subtest: memory_region
[533.379891] Dynamic subtest memory_region: SUCCESS (3.900s)
[533.424522] Starting dynamic subtest: hangcheck
[560.489570] Dynamic subtest hangcheck: SUCCESS (27.105s)
[560.533867] Starting dynamic subtest: execlists
[575.558397] Dynamic subtest execlists: SUCCESS (15.064s)
[575.602389] Starting dynamic subtest: ring_submission
[579.208350] Dynamic subtest ring_submission: SUCCESS (3.648s)
[579.253487] Starting dynamic subtest: perf
[583.492927] Dynamic subtest perf: SUCCESS (4.278s)
[583.553877] Starting dynamic subtest: slpc
[587.347953] Dynamic subtest slpc: SUCCESS (3.853s)
[587.411197] Starting dynamic subtest: guc
[591.183359] Dynamic subtest guc: SUCCESS (3.829s)
[591.225355] Starting dynamic subtest: guc_multi_lrc
[595.111662] Dynamic subtest guc_multi_lrc: SUCCESS (3.923s)
[595.195708] Starting dynamic subtest: guc_hang
[599.157116] Dynamic subtest guc_hang: SUCCESS (4.043s)
[599.198964] Starting dynamic subtest: late_gt_pm
[603.185170] Dynamic subtest late_gt_pm: SUCCESS (4.015s)
[603.211128] Subtest live: SUCCESS (289.860s)
[603.389701] [161/161] (428s left) dmabuf (all-tests)
[603.641997] Starting subtest: all-tests
[603.664680] Starting dynamic subtest: sanitycheck
[603.751140] Dynamic subtest sanitycheck: SUCCESS (0.109s)
[603.796691] Starting dynamic subtest: dma_fence
[604.017345] Dynamic subtest dma_fence: SUCCESS (0.259s)
[604.064421] Starting dynamic subtest: dma_fence_chain
[636.323862] Dynamic subtest dma_fence_chain: SUCCESS (32.302s)
[636.375802] Starting dynamic subtest: dma_fence_unwrap
[636.420603] Dynamic subtest dma_fence_unwrap: SUCCESS (0.089s)
[636.472071] Starting dynamic subtest: dma_resv
[636.535994] Dynamic subtest dma_resv: SUCCESS (0.115s)
[636.564609] Subtest all-tests: SUCCESS (32.899s)
[636.690727] Closing watchdogs
Done.