[42.538836] Initializing watchdogs
[42.539162]   /dev/watchdog0
[42.590844] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[42.643619] [001/161] (960s left) i915_module_load (load)
[43.281327] Starting subtest: load
[47.494392] Subtest load: SUCCESS (4.212s)
[47.635704] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[47.640927] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[47.685973] [002/161] (955s left) core_auth (basic-auth)
[47.999780] Starting subtest: basic-auth
[48.022729] Subtest basic-auth: SUCCESS (0.002s)
[48.176298] [003/161] (955s left) debugfs_test (read_all_entries)
[48.443835] Starting subtest: read_all_entries
[48.600943] Subtest read_all_entries: SUCCESS (0.164s)
[48.903129] [004/161] (954s left) debugfs_test (basic-hwmon)
[49.173457] Starting subtest: basic-hwmon
[49.213650] Subtest basic-hwmon: SKIP (0.000s)
[49.437897] [005/161] (954s left) debugfs_test (sysfs)
[49.711775] Starting subtest: sysfs
[49.851657] Subtest sysfs: SUCCESS (0.167s)
[50.163305] [006/161] (953s left) fbdev (eof)
[50.389723] Starting subtest: eof
[50.411945] Subtest eof: SUCCESS (0.002s)
[50.575837] [007/161] (953s left) fbdev (info)
[50.799494] Starting subtest: info
[50.820278] Subtest info: SUCCESS (0.000s)
[50.976077] [008/161] (953s left) fbdev (nullptr)
[51.218937] Starting subtest: nullptr
[51.244208] Subtest nullptr: SUCCESS (0.000s)
[51.417158] [009/161] (953s left) fbdev (read)
[51.640696] Starting subtest: read
[53.126446] Subtest read: SUCCESS (1.497s)
[53.289512] [010/161] (951s left) fbdev (write)
[53.532686] Starting subtest: write
[54.286596] Subtest write: SUCCESS (0.776s)
[54.466230] [011/161] (950s left) gem_basic (bad-close)
[54.725845] Starting subtest: bad-close
[54.767797] Subtest bad-close: SUCCESS (0.000s)
[54.937927] [012/161] (950s left) gem_basic (create-close)
[55.196215] Starting subtest: create-close
[55.242976] Subtest create-close: SUCCESS (0.000s)
[55.408617] [013/161] (949s left) gem_basic (create-fd-close)
[55.673524] Starting subtest: create-fd-close
[55.722890] Subtest create-fd-close: SUCCESS (0.000s)
[55.891750] [014/161] (949s left) gem_busy (busy@all-engines)
[56.151121] Starting subtest: busy
[56.178320] Starting dynamic subtest: all-engines
[56.249415] Dynamic subtest all-engines: SUCCESS (0.115s)
[56.272583] Subtest busy: SUCCESS (0.115s)
[56.761120] [015/161] (948s left) gem_close_race (basic-process)
[57.078926] Starting subtest: basic-process
[57.174154] Subtest basic-process: SUCCESS (0.122s)
[57.467743] [016/161] (948s left) gem_close_race (basic-threads)
[57.737905] Starting subtest: basic-threads
[58.996199] Subtest basic-threads: SUCCESS (1.237s)
[59.283773] [017/161] (946s left) gem_ctx_create (basic)
[59.557643] Starting subtest: basic
[59.579176] Subtest basic: SUCCESS (0.000s)
[59.841358] [018/161] (946s left) gem_ctx_create (basic-files)
[60.082503] Starting subtest: basic-files
[62.128815] Subtest basic-files: SUCCESS (2.019s)
[62.440669] [019/161] (943s left) gem_ctx_exec (basic)
[62.686656] Starting subtest: basic
[62.707048] Subtest basic: SUCCESS (0.009s)
[62.864453] [020/161] (943s left) gem_exec_basic (basic)
[63.138717] Starting subtest: basic
[63.167515] Starting dynamic subtest: rcs0-smem
[63.188359] Dynamic subtest rcs0-smem: SUCCESS (0.005s)
[63.232501] Starting dynamic subtest: bcs0-smem
[63.254133] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[63.316339] Starting dynamic subtest: vcs0-smem
[63.341720] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[63.387487] Starting dynamic subtest: vecs0-smem
[63.413525] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[63.438078] Subtest basic: SUCCESS (0.016s)
[63.611293] [021/161] (942s left) gem_exec_create (basic)
[63.879527] Starting subtest: basic
[63.907665] Starting dynamic subtest: smem
[65.979415] Dynamic subtest smem: SUCCESS (2.078s)
[66.006178] Subtest basic: SUCCESS (2.079s)
[66.280676] [022/161] (940s left) gem_exec_fence (basic-busy)
[66.663728] Starting subtest: basic-busy
[66.685370] Starting dynamic subtest: rcs0
[66.731051] Dynamic subtest rcs0: SUCCESS (0.032s)
[66.775586] Starting dynamic subtest: bcs0
[66.824676] Dynamic subtest bcs0: SUCCESS (0.039s)
[66.866322] Starting dynamic subtest: vcs0
[66.910610] Dynamic subtest vcs0: SUCCESS (0.041s)
[66.953300] Starting dynamic subtest: vecs0
[66.994444] Dynamic subtest vecs0: SUCCESS (0.024s)
[67.017643] Subtest basic-busy: SUCCESS (0.137s)
[67.850868] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[67.883440] [023/161] (939s left) gem_exec_fence (basic-wait)
[68.413536] Starting subtest: basic-wait
[68.436745] Starting dynamic subtest: rcs0
[68.459654] Dynamic subtest rcs0: SUCCESS (0.037s)
[68.507079] Starting dynamic subtest: bcs0
[68.528116] Dynamic subtest bcs0: SUCCESS (0.034s)
[68.574245] Starting dynamic subtest: vcs0
[68.596484] Dynamic subtest vcs0: SUCCESS (0.040s)
[68.638941] Starting dynamic subtest: vecs0
[68.659305] Dynamic subtest vecs0: SUCCESS (0.034s)
[68.682440] Subtest basic-wait: SUCCESS (0.148s)
[69.680505] [024/161] (937s left) gem_exec_fence (basic-await)
[70.175814] Starting subtest: basic-await
[70.199091] Starting dynamic subtest: rcs0
[70.384870] Dynamic subtest rcs0: SUCCESS (0.207s)
[70.427765] Starting dynamic subtest: bcs0
[70.563975] Dynamic subtest bcs0: SUCCESS (0.171s)
[70.607465] Starting dynamic subtest: vcs0
[70.754371] Dynamic subtest vcs0: SUCCESS (0.192s)
[70.799070] Starting dynamic subtest: vecs0
[70.927560] Dynamic subtest vecs0: SUCCESS (0.171s)
[70.950278] Subtest basic-await: SUCCESS (0.745s)
[71.736796] [025/161] (935s left) gem_exec_fence (nb-await)
[72.132413] Starting subtest: nb-await
[72.155737] Starting dynamic subtest: rcs0
[72.208689] Dynamic subtest rcs0: SUCCESS (0.078s)
[72.263494] Starting dynamic subtest: bcs0
[72.307900] Dynamic subtest bcs0: SUCCESS (0.097s)
[72.358572] Starting dynamic subtest: vcs0
[72.408518] Dynamic subtest vcs0: SUCCESS (0.099s)
[72.455489] Starting dynamic subtest: vecs0
[72.512818] Dynamic subtest vecs0: SUCCESS (0.098s)
[72.533451] Subtest nb-await: SUCCESS (0.377s)
[73.337214] [026/161] (934s left) gem_exec_gttfill (basic)
[73.605076] Starting subtest: basic
[104.230382] Subtest basic: SUCCESS (30.632s)
[104.562747] [027/161] (903s left) gem_exec_parallel (engines)
[104.965703] Starting subtest: engines
[104.987206] Starting dynamic subtest: basic
[109.972391] Dynamic subtest basic: SUCCESS (4.846s)
[110.018128] Starting dynamic subtest: contexts
[115.983467] Dynamic subtest contexts: SUCCESS (6.036s)
[116.027061] Starting dynamic subtest: fds
[121.674425] Dynamic subtest fds: SUCCESS (5.662s)
[121.723046] Starting dynamic subtest: userptr
[125.806558] Dynamic subtest userptr: SUCCESS (4.132s)
[125.826782] Subtest engines: SUCCESS (20.679s)
[126.000752] [028/161] (882s left) gem_exec_store (basic)
[126.341591] Starting subtest: basic
[126.365050] Subtest basic: SUCCESS (0.078s)
[126.643512] [029/161] (881s left) gem_flink_basic (bad-flink)
[126.905309] Starting subtest: bad-flink
[126.926077] Subtest bad-flink: SUCCESS (0.000s)
[127.097521] [030/161] (881s left) gem_flink_basic (bad-open)
[127.340553] Starting subtest: bad-open
[127.363605] Subtest bad-open: SUCCESS (0.000s)
[127.531053] [031/161] (881s left) gem_flink_basic (basic)
[127.796049] Starting subtest: basic
[127.816108] Subtest basic: SUCCESS (0.000s)
[127.983669] [032/161] (880s left) gem_flink_basic (double-flink)
[128.249200] Starting subtest: double-flink
[128.269717] Subtest double-flink: SUCCESS (0.000s)
[128.438820] [033/161] (880s left) gem_flink_basic (flink-lifetime)
[128.704802] Starting subtest: flink-lifetime
[128.727078] Subtest flink-lifetime: SUCCESS (0.004s)
[128.898871] [034/161] (880s left) gem_huc_copy (huc-copy)
[129.158364] Starting subtest: huc-copy
[129.203295] Subtest huc-copy: SKIP (0.000s)
[129.372803] [035/161] (879s left) gem_linear_blits (basic)
[129.798834] Starting subtest: basic
[129.842772] Subtest basic: SUCCESS (0.029s)
[130.111769] [036/161] (879s left) gem_mmap (basic)
[130.550844] Starting subtest: basic
[130.657354] Subtest basic: SUCCESS (0.001s)
[130.832800] [037/161] (878s left) gem_mmap_gtt (basic)
[131.115474] Starting subtest: basic
[131.136469] Subtest basic: SUCCESS (0.003s)
[131.305998] [038/161] (878s left) gem_render_linear_blits (basic)
[131.569712] Starting subtest: basic
[131.678715] Subtest basic: SUCCESS (0.107s)
[131.875851] [039/161] (878s left) gem_render_tiled_blits (basic)
[132.141498] Starting subtest: basic
[132.272248] Subtest basic: SUCCESS (0.147s)
[132.466450] [040/161] (877s left) gem_ringfill (basic-all)
[133.048648] Starting subtest: basic-all
[134.255562] Subtest basic-all: SUCCESS (1.230s)
[134.484600] [041/161] (875s left) gem_softpin (allocator-basic)
[134.761358] Starting subtest: allocator-basic
[135.007817] Subtest allocator-basic: SUCCESS (0.289s)
[135.243799] [042/161] (875s left) gem_softpin (allocator-basic-reserve)
[135.484586] Starting subtest: allocator-basic-reserve
[135.749652] Subtest allocator-basic-reserve: SUCCESS (0.287s)
[135.997102] [043/161] (874s left) gem_softpin (safe-alignment)
[136.234211] Starting subtest: safe-alignment
[136.257325] Subtest safe-alignment: SUCCESS (0.034s)
[136.481924] [044/161] (874s left) gem_sync (basic-all)
[136.893796] Starting subtest: basic-all
[139.273256] Subtest basic-all: SUCCESS (2.384s)
[139.443342] [045/161] (871s left) gem_sync (basic-each)
[139.858568] Starting subtest: basic-each
[142.026341] Subtest basic-each: SUCCESS (2.342s)
[142.341869] [046/161] (868s left) gem_tiled_blits (basic)
[142.711221] Starting subtest: basic
[142.750908] Subtest basic: SUCCESS (0.062s)
[143.052138] [047/161] (868s left) gem_tiled_fence_blits (basic)
[143.571371] Starting subtest: basic
[143.621293] Subtest basic: SUCCESS (0.046s)
[143.920414] [048/161] (867s left) gem_tiled_pread_basic
[144.800711] [049/161] (867s left) gem_wait (busy@all-engines)
[145.062795] Starting subtest: busy
[145.084190] Starting dynamic subtest: all-engines
[145.607049] Dynamic subtest all-engines: SUCCESS (0.537s)
[145.628872] Subtest busy: SUCCESS (0.538s)
[145.943695] [050/161] (866s left) gem_wait (wait@all-engines)
[146.191297] Starting subtest: wait
[146.213747] Starting dynamic subtest: all-engines
[147.245166] Dynamic subtest all-engines: SUCCESS (1.052s)
[147.266775] Subtest wait: SUCCESS (1.053s)
[147.598946] [051/161] (864s left) i915_getparams_basic (basic-eu-total)
[147.861605] Starting subtest: basic-eu-total
[147.910926] Subtest basic-eu-total: SUCCESS (0.000s)
[148.079753] [052/161] (864s left) i915_getparams_basic (basic-subslice-total)
[148.346637] Starting subtest: basic-subslice-total
[148.390551] Subtest basic-subslice-total: SUCCESS (0.000s)
[148.557922] [053/161] (864s left) i915_hangman (error-state-basic)
[148.827756] Starting subtest: error-state-basic
[148.849687] Subtest error-state-basic: SUCCESS (0.023s)
[149.034499] [054/161] (863s left) i915_pciid
[149.465090] [055/161] (863s left) kms_addfb_basic (addfb25-4-tiled)
[149.899820] Starting subtest: addfb25-4-tiled
[149.922062] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[150.095089] [056/161] (862s left) kms_addfb_basic (addfb25-bad-modifier)
[150.449303] Starting subtest: addfb25-bad-modifier
[150.471606] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[150.644111] [057/161] (862s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[150.998523] Starting subtest: addfb25-framebuffer-vs-set-tiling
[151.020353] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[151.196453] [058/161] (862s left) kms_addfb_basic (addfb25-modifier-no-flag)
[151.554024] Starting subtest: addfb25-modifier-no-flag
[151.574856] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[151.750672] [059/161] (861s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[152.102270] Starting subtest: addfb25-x-tiled-legacy
[152.123539] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[152.318409] [060/161] (861s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[152.672187] Starting subtest: addfb25-x-tiled-mismatch-legacy
[152.692295] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[152.885972] [061/161] (861s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[153.242097] Starting subtest: addfb25-yf-tiled-legacy
[153.263388] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[153.435873] [062/161] (860s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[153.786539] Starting subtest: addfb25-y-tiled-legacy
[153.806859] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[154.007710] [063/161] (860s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[154.383644] Starting subtest: addfb25-y-tiled-small-legacy
[154.410939] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[154.602206] [064/161] (859s left) kms_addfb_basic (bad-pitch-0)
[154.890361] Starting subtest: bad-pitch-0
[154.919573] Subtest bad-pitch-0: SUCCESS (0.000s)
[155.163505] [065/161] (859s left) kms_addfb_basic (bad-pitch-1024)
[155.428323] Starting subtest: bad-pitch-1024
[155.448175] Subtest bad-pitch-1024: SUCCESS (0.000s)
[155.712393] [066/161] (859s left) kms_addfb_basic (bad-pitch-128)
[155.940094] Starting subtest: bad-pitch-128
[155.962559] Subtest bad-pitch-128: SUCCESS (0.000s)
[156.246251] [067/161] (858s left) kms_addfb_basic (bad-pitch-256)
[156.484857] Starting subtest: bad-pitch-256
[156.504472] Subtest bad-pitch-256: SUCCESS (0.000s)
[156.812760] [068/161] (858s left) kms_addfb_basic (bad-pitch-32)
[157.072741] Starting subtest: bad-pitch-32
[157.092488] Subtest bad-pitch-32: SUCCESS (0.000s)
[157.362141] [069/161] (857s left) kms_addfb_basic (bad-pitch-63)
[157.630938] Starting subtest: bad-pitch-63
[157.650711] Subtest bad-pitch-63: SUCCESS (0.000s)
[157.914361] [070/161] (857s left) kms_addfb_basic (bad-pitch-65536)
[158.155186] Starting subtest: bad-pitch-65536
[158.177399] Subtest bad-pitch-65536: SUCCESS (0.001s)
[158.465956] [071/161] (857s left) kms_addfb_basic (bad-pitch-999)
[158.706177] Starting subtest: bad-pitch-999
[158.728867] Subtest bad-pitch-999: SUCCESS (0.000s)
[159.011938] [072/161] (856s left) kms_addfb_basic (basic)
[159.276539] Starting subtest: basic
[159.298881] Subtest basic: SUCCESS (0.000s)
[159.559853] [073/161] (856s left) kms_addfb_basic (basic-x-tiled-legacy)
[159.827377] Starting subtest: basic-x-tiled-legacy
[159.847162] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[160.112489] [074/161] (856s left) kms_addfb_basic (basic-y-tiled-legacy)
[160.377268] Starting subtest: basic-y-tiled-legacy
[160.397276] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[160.664394] [075/161] (855s left) kms_addfb_basic (bo-too-small)
[161.014737] Starting subtest: bo-too-small
[161.034931] Subtest bo-too-small: SUCCESS (0.001s)
[161.217496] [076/161] (855s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[161.565122] Starting subtest: bo-too-small-due-to-tiling
[161.586914] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[161.765353] [077/161] (854s left) kms_addfb_basic (clobberred-modifier)
[162.009475] Starting subtest: clobberred-modifier
[162.028932] Subtest clobberred-modifier: SUCCESS (0.001s)
[162.316498] [078/161] (854s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[162.578722] Starting subtest: framebuffer-vs-set-tiling
[162.598236] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[162.862140] [079/161] (854s left) kms_addfb_basic (invalid-get-prop)
[163.104630] Starting subtest: invalid-get-prop
[163.126656] Subtest invalid-get-prop: SUCCESS (0.000s)
[163.416709] [080/161] (853s left) kms_addfb_basic (invalid-get-prop-any)
[163.705470] Starting subtest: invalid-get-prop-any
[163.730124] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[163.988232] [081/161] (853s left) kms_addfb_basic (invalid-set-prop)
[164.247698] Starting subtest: invalid-set-prop
[164.271776] Subtest invalid-set-prop: SUCCESS (0.000s)
[164.559524] [082/161] (852s left) kms_addfb_basic (invalid-set-prop-any)
[164.815465] Starting subtest: invalid-set-prop-any
[164.841196] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[165.137091] [083/161] (852s left) kms_addfb_basic (no-handle)
[165.401190] Starting subtest: no-handle
[165.423717] Subtest no-handle: SUCCESS (0.000s)
[165.686358] [084/161] (852s left) kms_addfb_basic (size-max)
[166.025724] Starting subtest: size-max
[166.048678] Subtest size-max: SUCCESS (0.000s)
[166.217518] [085/161] (851s left) kms_addfb_basic (small-bo)
[166.574865] Starting subtest: small-bo
[166.595359] Subtest small-bo: SUCCESS (0.000s)
[166.765866] [086/161] (851s left) kms_addfb_basic (tile-pitch-mismatch)
[167.016902] Starting subtest: tile-pitch-mismatch
[167.039553] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[167.313951] [087/161] (851s left) kms_addfb_basic (too-high)
[167.668751] Starting subtest: too-high
[167.691937] Subtest too-high: SUCCESS (0.003s)
[167.861713] [088/161] (850s left) kms_addfb_basic (too-wide)
[168.222405] Starting subtest: too-wide
[168.243077] Subtest too-wide: SUCCESS (0.000s)
[168.420727] [089/161] (850s left) kms_addfb_basic (unused-handle)
[168.673048] Starting subtest: unused-handle
[168.695218] Subtest unused-handle: SUCCESS (0.000s)
[168.962311] [090/161] (849s left) kms_addfb_basic (unused-modifier)
[169.227290] Starting subtest: unused-modifier
[169.249966] Subtest unused-modifier: SUCCESS (0.000s)
[169.517100] [091/161] (849s left) kms_addfb_basic (unused-offsets)
[169.761718] Starting subtest: unused-offsets
[169.781336] Subtest unused-offsets: SUCCESS (0.000s)
[170.045592] [092/161] (849s left) kms_addfb_basic (unused-pitches)
[170.276219] Starting subtest: unused-pitches
[170.299089] Subtest unused-pitches: SUCCESS (0.000s)
[170.610394] [093/161] (848s left) kms_busy (basic)
[170.972719] Starting subtest: basic
[170.995552] Starting dynamic subtest: flip
[171.809136] Dynamic subtest flip: SUCCESS (0.837s)
[171.855416] Starting dynamic subtest: modeset
[173.309346] Dynamic subtest modeset: SUCCESS (1.493s)
[173.337075] Subtest basic: SUCCESS (2.335s)
[173.812898] [094/161] (845s left) kms_prop_blob (basic)
[174.085377] Starting subtest: basic
[174.109222] Subtest basic: SUCCESS (0.000s)
[174.280958] [095/161] (845s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[174.652750] Starting subtest: basic-busy-flip-before-cursor-atomic
[174.695426] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[174.864407] [096/161] (845s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[175.224346] Starting subtest: basic-busy-flip-before-cursor-legacy
[175.269735] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[175.440249] [097/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[175.795635] Starting subtest: basic-flip-after-cursor-atomic
[176.794618] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.014s)
[177.254740] [098/161] (843s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[177.612848] Starting subtest: basic-flip-after-cursor-legacy
[178.588611] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.994s)
[179.046387] [099/161] (841s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[179.405826] Starting subtest: basic-flip-after-cursor-varying-size
[180.378871] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.985s)
[180.822989] [100/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[181.161755] Starting subtest: basic-flip-before-cursor-atomic
[182.142198] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.000s)
[182.598110] [101/161] (838s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[182.951336] Starting subtest: basic-flip-before-cursor-legacy
[183.915089] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.993s)
[184.388214] [102/161] (836s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[184.747237] Starting subtest: basic-flip-before-cursor-varying-size
[185.714903] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.988s)
[186.180206] [103/161] (834s left) kms_dsc (dsc-basic)
[186.568202] Subtest dsc-basic: SKIP (0.000s)
[186.752116] [104/161] (834s left) kms_flip (basic-flip-vs-dpms)
[187.144761] Starting subtest: basic-flip-vs-dpms
[187.166169] Starting dynamic subtest: A-DP1
[188.392222] Dynamic subtest A-DP1: SUCCESS (1.271s)
[188.438697] Starting dynamic subtest: B-DP1
[189.552937] Dynamic subtest B-DP1: SUCCESS (1.163s)
[189.601949] Starting dynamic subtest: C-DP1
[190.743633] Dynamic subtest C-DP1: SUCCESS (1.173s)
[190.805598] Subtest basic-flip-vs-dpms: SUCCESS (3.618s)
[191.224584] [105/161] (830s left) kms_flip (basic-flip-vs-modeset)
[191.611795] Starting subtest: basic-flip-vs-modeset
[191.632047] Starting dynamic subtest: A-DP1
[192.862885] Dynamic subtest A-DP1: SUCCESS (1.277s)
[192.909978] Starting dynamic subtest: B-DP1
[194.042977] Dynamic subtest B-DP1: SUCCESS (1.162s)
[194.090093] Starting dynamic subtest: C-DP1
[195.192269] Dynamic subtest C-DP1: SUCCESS (1.159s)
[195.213968] Subtest basic-flip-vs-modeset: SUCCESS (3.609s)
[195.665438] [106/161] (826s left) kms_flip (basic-flip-vs-wf_vblank)
[196.050837] Starting subtest: basic-flip-vs-wf_vblank
[196.072369] Starting dynamic subtest: A-DP1
[197.467156] Dynamic subtest A-DP1: SUCCESS (1.440s)
[197.514172] Starting dynamic subtest: B-DP1
[198.890926] Dynamic subtest B-DP1: SUCCESS (1.420s)
[198.937542] Starting dynamic subtest: C-DP1
[200.312872] Dynamic subtest C-DP1: SUCCESS (1.420s)
[200.336093] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.293s)
[200.856608] [107/161] (820s left) kms_flip (basic-plain-flip)
[201.242035] Starting subtest: basic-plain-flip
[201.264533] Starting dynamic subtest: A-DP1
[202.385985] Dynamic subtest A-DP1: SUCCESS (1.173s)
[202.433936] Starting dynamic subtest: B-DP1
[203.531212] Dynamic subtest B-DP1: SUCCESS (1.139s)
[203.579159] Starting dynamic subtest: C-DP1
[204.685988] Dynamic subtest C-DP1: SUCCESS (1.141s)
[204.710100] Subtest basic-plain-flip: SUCCESS (3.466s)
[205.222546] [108/161] (816s left) kms_force_connector_basic (force-connector-state)
[205.526405] Subtest force-connector-state: SKIP (0.000s)
[205.701193] [109/161] (816s left) kms_force_connector_basic (force-edid)
[206.005239] Subtest force-edid: SKIP (0.000s)
[206.200534] [110/161] (816s left) kms_force_connector_basic (force-load-detect)
[206.476468] Subtest force-load-detect: SKIP (0.000s)
[206.650309] [111/161] (815s left) kms_force_connector_basic (prune-stale-modes)
[206.936154] Subtest prune-stale-modes: SKIP (0.000s)
[207.119996] [112/161] (815s left) kms_frontbuffer_tracking (basic)
[207.554636] Starting subtest: basic
[211.354391] Subtest basic: SUCCESS (3.860s)
[211.576348] [113/161] (811s left) kms_hdmi_inject (inject-audio)
[211.873636] Subtest inject-audio: SKIP (0.000s)
[212.052157] [114/161] (811s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[212.422583] Starting subtest: compare-crc-sanitycheck-xr24
[212.444037] Starting dynamic subtest: pipe-A-DP-1
[212.873866] Dynamic subtest pipe-A-DP-1: SUCCESS (0.465s)
[212.920608] Starting dynamic subtest: pipe-B-DP-1
[213.517631] Dynamic subtest pipe-B-DP-1: SUCCESS (0.637s)
[213.565043] Starting dynamic subtest: pipe-C-DP-1
[214.159917] Dynamic subtest pipe-C-DP-1: SUCCESS (0.640s)
[214.181387] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.757s)
[214.623203] [115/161] (808s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[214.977616] Starting subtest: compare-crc-sanitycheck-nv12
[214.998064] Starting dynamic subtest: pipe-A-DP-1
[215.040746] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[215.089371] Starting dynamic subtest: pipe-B-DP-1
[215.132245] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[215.198480] Starting dynamic subtest: pipe-C-DP-1
[215.259198] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[215.303117] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[215.491229] [116/161] (808s left) kms_pipe_crc_basic (hang-read-crc)
[215.849671] Starting subtest: hang-read-crc
[215.872073] Starting dynamic subtest: pipe-A-DP-1
[217.185799] Dynamic subtest pipe-A-DP-1: SUCCESS (1.330s)
[217.233677] Starting dynamic subtest: pipe-B-DP-1
[218.688413] Dynamic subtest pipe-B-DP-1: SUCCESS (1.494s)
[218.753503] Starting dynamic subtest: pipe-C-DP-1
[220.181581] Dynamic subtest pipe-C-DP-1: SUCCESS (1.491s)
[220.204095] Subtest hang-read-crc: SUCCESS (4.330s)
[220.613404] [117/161] (803s left) kms_pipe_crc_basic (nonblocking-crc)
[220.963843] Starting subtest: nonblocking-crc
[220.986326] Starting dynamic subtest: pipe-A-DP-1
[221.601329] Dynamic subtest pipe-A-DP-1: SUCCESS (0.637s)
[221.661448] Starting dynamic subtest: pipe-B-DP-1
[222.397622] Dynamic subtest pipe-B-DP-1: SUCCESS (0.805s)
[222.445121] Starting dynamic subtest: pipe-C-DP-1
[223.218919] Dynamic subtest pipe-C-DP-1: SUCCESS (0.800s)
[223.239856] Subtest nonblocking-crc: SUCCESS (2.256s)
[223.639278] [118/161] (800s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[223.992806] Starting subtest: nonblocking-crc-frame-sequence
[224.014104] Starting dynamic subtest: pipe-A-DP-1
[224.674699] Dynamic subtest pipe-A-DP-1: SUCCESS (0.648s)
[224.732885] Starting dynamic subtest: pipe-B-DP-1
[225.475069] Dynamic subtest pipe-B-DP-1: SUCCESS (0.805s)
[225.536309] Starting dynamic subtest: pipe-C-DP-1
[226.281879] Dynamic subtest pipe-C-DP-1: SUCCESS (0.802s)
[226.304844] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.271s)
[226.702754] [119/161] (797s left) kms_pipe_crc_basic (read-crc)
[227.077200] Starting subtest: read-crc
[227.099835] Starting dynamic subtest: pipe-A-DP-1
[227.667959] Dynamic subtest pipe-A-DP-1: SUCCESS (0.585s)
[227.717187] Starting dynamic subtest: pipe-B-DP-1
[228.399190] Dynamic subtest pipe-B-DP-1: SUCCESS (0.724s)
[228.452609] Starting dynamic subtest: pipe-C-DP-1
[229.178774] Dynamic subtest pipe-C-DP-1: SUCCESS (0.751s)
[229.203697] Subtest read-crc: SUCCESS (2.076s)
[229.571840] [120/161] (794s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[229.938117] Starting subtest: read-crc-frame-sequence
[229.959073] Starting dynamic subtest: pipe-A-DP-1
[230.538990] Dynamic subtest pipe-A-DP-1: SUCCESS (0.601s)
[230.590808] Starting dynamic subtest: pipe-B-DP-1
[231.284616] Dynamic subtest pipe-B-DP-1: SUCCESS (0.731s)
[231.353502] Starting dynamic subtest: pipe-C-DP-1
[232.031474] Dynamic subtest pipe-C-DP-1: SUCCESS (0.750s)
[232.051912] Subtest read-crc-frame-sequence: SUCCESS (2.098s)
[232.466459] [121/161] (791s left) kms_pm_backlight (basic-brightness)
[232.867782] Subtest basic-brightness: SKIP (0.000s)
[233.055340] [122/161] (791s left) kms_pm_rpm (basic-pci-d3-state)
[234.901984] Starting subtest: basic-pci-d3-state
[235.579153] Subtest basic-pci-d3-state: SUCCESS (0.679s)
[235.898087] [123/161] (788s left) kms_pm_rpm (basic-rte)
[236.151239] Starting subtest: basic-rte
[238.360906] Subtest basic-rte: SUCCESS (2.248s)
[239.375619] [124/161] (785s left) kms_psr (psr-primary-page-flip)
[239.774304] Subtest psr-primary-page-flip: SKIP (0.000s)
[239.952436] [125/161] (785s left) kms_psr (psr-cursor-plane-move)
[240.313620] Subtest psr-cursor-plane-move: SKIP (0.000s)
[240.495622] [126/161] (784s left) kms_psr (psr-sprite-plane-onoff)
[240.879421] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[241.056240] [127/161] (784s left) kms_psr (psr-primary-mmap-gtt)
[241.443095] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[241.619528] [128/161] (784s left) kms_setmode (basic-clone-single-crtc)
[241.897535] Starting subtest: basic-clone-single-crtc
[241.963745] Subtest basic-clone-single-crtc: SKIP (0.000s)
[242.142612] [129/161] (783s left) i915_pm_rps (basic-api)
[242.417854] Starting subtest: basic-api
[242.439472] Subtest basic-api: SUCCESS (0.007s)
[242.636626] [130/161] (783s left) prime_self_import (basic-llseek-bad)
[242.891491] Starting subtest: basic-llseek-bad
[242.936747] Subtest basic-llseek-bad: SUCCESS (0.005s)
[243.117878] [131/161] (783s left) prime_self_import (basic-llseek-size)
[243.362174] Starting subtest: basic-llseek-size
[243.407448] Subtest basic-llseek-size: SUCCESS (0.008s)
[243.583811] [132/161] (782s left) prime_self_import (basic-with_fd_dup)
[243.826426] Starting subtest: basic-with_fd_dup
[243.870755] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[244.045785] [133/161] (782s left) prime_self_import (basic-with_one_bo)
[244.294739] Starting subtest: basic-with_one_bo
[244.348564] Subtest basic-with_one_bo: SUCCESS (0.008s)
[244.538760] [134/161] (782s left) prime_self_import (basic-with_one_bo_two_files)
[244.813975] Starting subtest: basic-with_one_bo_two_files
[244.863112] Subtest basic-with_one_bo_two_files: SUCCESS (0.007s)
[245.053142] [135/161] (781s left) prime_self_import (basic-with_two_bos)
[245.295422] Starting subtest: basic-with_two_bos
[245.348593] Subtest basic-with_two_bos: SUCCESS (0.008s)
[245.545810] [136/161] (781s left) prime_vgem (basic-fence-flip)
[245.848723] Starting subtest: basic-fence-flip
[246.493178] Subtest basic-fence-flip: SUCCESS (0.681s)
[246.715735] [137/161] (780s left) prime_vgem (basic-fence-mmap)
[247.008857] Starting subtest: basic-fence-mmap
[247.031660] Subtest basic-fence-mmap: SUCCESS (0.073s)
[247.237857] [138/161] (780s left) prime_vgem (basic-fence-read)
[247.529233] Starting subtest: basic-fence-read
[247.615374] Subtest basic-fence-read: SUCCESS (0.147s)
[247.818396] [139/161] (779s left) prime_vgem (basic-gtt)
[248.102690] Starting subtest: basic-gtt
[248.125964] Subtest basic-gtt: SUCCESS (0.032s)
[248.322699] [140/161] (779s left) prime_vgem (basic-read)
[248.607626] Starting subtest: basic-read
[248.630277] Subtest basic-read: SUCCESS (0.068s)
[248.830329] [141/161] (779s left) prime_vgem (basic-write)
[249.120314] Starting subtest: basic-write
[249.170253] Subtest basic-write: SUCCESS (0.095s)
[249.397640] [142/161] (778s left) vgem_basic (setversion)
[249.671116] Starting subtest: setversion
[249.718031] Subtest setversion: SUCCESS (0.000s)
[249.894831] [143/161] (778s left) vgem_basic (create)
[250.152146] Starting subtest: create
[250.172765] Subtest create: SUCCESS (0.001s)
[250.352393] [144/161] (778s left) vgem_basic (debugfs)
[250.626093] Starting subtest: debugfs
[250.647212] Subtest debugfs: SUCCESS (0.001s)
[250.825075] [145/161] (778s left) vgem_basic (dmabuf-export)
[251.074141] Starting subtest: dmabuf-export
[251.120546] Subtest dmabuf-export: SUCCESS (0.017s)
[251.295936] [146/161] (777s left) vgem_basic (dmabuf-fence)
[251.555296] Starting subtest: dmabuf-fence
[251.579137] Subtest dmabuf-fence: SUCCESS (0.001s)
[251.753365] [147/161] (777s left) vgem_basic (dmabuf-fence-before)
[252.020565] Starting subtest: dmabuf-fence-before
[252.040594] Subtest dmabuf-fence-before: SUCCESS (0.002s)
[252.218827] [148/161] (777s left) vgem_basic (dmabuf-mmap)
[252.465504] Starting subtest: dmabuf-mmap
[252.488100] Subtest dmabuf-mmap: SUCCESS (0.038s)
[252.672527] [149/161] (777s left) vgem_basic (mmap)
[252.917205] Starting subtest: mmap
[252.940589] Subtest mmap: SUCCESS (0.024s)
[253.124415] [150/161] (776s left) vgem_basic (second-client)
[253.374204] Starting subtest: second-client
[253.418338] Subtest second-client: SUCCESS (0.032s)
[253.592730] [151/161] (776s left) vgem_basic (sysfs)
[253.860821] Starting subtest: sysfs
[253.881449] Subtest sysfs: SUCCESS (0.001s)
[254.058575] [152/161] (776s left) core_hotunplug (unbind-rebind)
[254.448549] Starting subtest: unbind-rebind
[259.091879] Subtest unbind-rebind: SUCCESS (4.649s)
[259.273498] [153/161] (771s left) vgem_basic (unload)
[259.545961] Starting subtest: unload
[288.910875] Subtest unload: SUCCESS (29.246s)
[289.083951] [154/161] (741s left) i915_module_load (reload)
[289.330222] Starting subtest: reload
[295.545919] Subtest reload: SUCCESS (6.228s)
[295.815362] [155/161] (735s left) gem_lmem_swapping (basic)
[296.124706] Subtest basic: SKIP (0.000s)
[296.296706] [156/161] (734s left) gem_lmem_swapping (parallel-random-engines)
[296.583816] Subtest parallel-random-engines: SKIP (0.000s)
[296.756664] [157/161] (734s left) gem_lmem_swapping (random-engines)
[297.042918] Subtest random-engines: SKIP (0.000s)
[297.222361] [158/161] (734s left) gem_lmem_swapping (verify-random)
[297.491461] Subtest verify-random: SKIP (0.000s)
[297.663300] [159/161] (734s left) i915_pm_rpm (module-reload)
[299.830357] Starting subtest: module-reload
[314.470613] Subtest module-reload: SUCCESS (14.663s)
[314.594915] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[314.598995] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[314.624373] [160/161] (717s left) i915_selftest (live)
[314.852713] Starting subtest: live
[314.873467] Starting dynamic subtest: sanitycheck
[318.831210] Dynamic subtest sanitycheck: SUCCESS (3.983s)
[318.889369] Starting dynamic subtest: uncore
[322.615935] Dynamic subtest uncore: SUCCESS (3.779s)
[322.657697] Starting dynamic subtest: workarounds
[326.702053] Dynamic subtest workarounds: SUCCESS (4.083s)
[326.745643] Starting dynamic subtest: gt_engines
[330.964597] Dynamic subtest gt_engines: SUCCESS (4.254s)
[331.007561] Starting dynamic subtest: gt_timelines
[354.745220] Dynamic subtest gt_timelines: SUCCESS (23.776s)
[354.787425] Starting dynamic subtest: gt_contexts
[358.963758] Dynamic subtest gt_contexts: SUCCESS (4.215s)
[359.025416] Starting dynamic subtest: gt_lrc
[365.594552] Dynamic subtest gt_lrc: SUCCESS (6.625s)
[365.653760] Starting dynamic subtest: gt_mocs
[369.696387] Dynamic subtest gt_mocs: SUCCESS (4.097s)
[369.756413] Starting dynamic subtest: gt_pm
[383.478537] Dynamic subtest gt_pm: SUCCESS (13.778s)
[383.562786] Starting dynamic subtest: gt_heartbeat
[387.357101] Dynamic subtest gt_heartbeat: SUCCESS (3.876s)
[387.400287] Starting dynamic subtest: gt_tlb
[391.902808] Dynamic subtest gt_tlb: SUCCESS (4.538s)
[391.945735] Starting dynamic subtest: requests
[402.170720] Dynamic subtest requests: SUCCESS (10.257s)
[402.220120] Starting dynamic subtest: migrate
[409.536297] Dynamic subtest migrate: SUCCESS (7.369s)
[409.580175] Starting dynamic subtest: active
[413.495504] Dynamic subtest active: SUCCESS (3.955s)
[413.555397] Starting dynamic subtest: objects
[419.946497] Dynamic subtest objects: SUCCESS (6.442s)
[419.990096] Starting dynamic subtest: mman
[430.001179] Dynamic subtest mman: SUCCESS (10.052s)
[430.043293] Starting dynamic subtest: dmabuf
[433.843071] Dynamic subtest dmabuf: SUCCESS (3.835s)
[433.902409] Starting dynamic subtest: vma
[437.579158] Dynamic subtest vma: SUCCESS (3.731s)
[437.620611] Starting dynamic subtest: coherency
[451.431830] Dynamic subtest coherency: SUCCESS (13.846s)
[451.475344] Starting dynamic subtest: gtt
[462.694280] Dynamic subtest gtt: SUCCESS (11.260s)
[462.739899] Starting dynamic subtest: gem
[468.133374] Dynamic subtest gem: SUCCESS (5.427s)
[468.182692] Starting dynamic subtest: evict
[477.082301] Dynamic subtest evict: SUCCESS (8.951s)
[477.124707] Starting dynamic subtest: hugepages
[487.417263] Dynamic subtest hugepages: SUCCESS (10.331s)
[487.476434] Starting dynamic subtest: gem_contexts
[514.052884] Dynamic subtest gem_contexts: SUCCESS (26.458s)
[514.093870] Starting dynamic subtest: client
[517.792384] Dynamic subtest client: SUCCESS (3.905s)
[517.877995] Starting dynamic subtest: gem_migrate
[521.681503] Dynamic subtest gem_migrate: SUCCESS (3.886s)
[521.723294] Starting dynamic subtest: reset
[536.269037] Dynamic subtest reset: SUCCESS (14.585s)
[536.313505] Starting dynamic subtest: memory_region
[540.073838] Dynamic subtest memory_region: SUCCESS (3.799s)
[540.131442] Starting dynamic subtest: hangcheck
[567.346300] Dynamic subtest hangcheck: SUCCESS (27.270s)
[567.391853] Starting dynamic subtest: execlists
[582.135916] Dynamic subtest execlists: SUCCESS (14.779s)
[582.180832] Starting dynamic subtest: ring_submission
[586.144858] Dynamic subtest ring_submission: SUCCESS (4.011s)
[586.196046] Starting dynamic subtest: perf
[590.078681] Dynamic subtest perf: SUCCESS (3.931s)
[590.138869] Starting dynamic subtest: slpc
[593.878889] Dynamic subtest slpc: SUCCESS (3.796s)
[593.921209] Starting dynamic subtest: guc
[597.581543] Dynamic subtest guc: SUCCESS (3.692s)
[597.645601] Starting dynamic subtest: guc_multi_lrc
[601.550817] Dynamic subtest guc_multi_lrc: SUCCESS (3.969s)
[601.593314] Starting dynamic subtest: guc_hang
[605.389854] Dynamic subtest guc_hang: SUCCESS (3.833s)
[605.434585] Starting dynamic subtest: late_gt_pm
[609.514729] Dynamic subtest late_gt_pm: SUCCESS (4.123s)
[609.537646] Subtest live: SUCCESS (294.666s)
[609.689656] [161/161] (422s left) dmabuf (all-tests)
�����������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������