[61.317405] Initializing watchdogs
[61.317632]   /dev/watchdog0
[61.374606] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[61.405628] [001/161] (960s left) i915_module_load (load)
[61.680250] Starting subtest: load
[65.835086] Subtest load: SUCCESS (4.130s)
[65.975996] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[65.979696] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[66.007314] [002/161] (956s left) core_auth (basic-auth)
[66.346341] Starting subtest: basic-auth
[66.367716] Subtest basic-auth: SUCCESS (0.002s)
[66.522870] [003/161] (955s left) debugfs_test (read_all_entries)
[66.776337] Starting subtest: read_all_entries
[66.943753] Subtest read_all_entries: SUCCESS (0.168s)
[67.259301] [004/161] (955s left) debugfs_test (basic-hwmon)
[67.536679] Starting subtest: basic-hwmon
[67.584093] Subtest basic-hwmon: SKIP (0.000s)
[67.923885] [005/161] (954s left) debugfs_test (sysfs)
[68.294771] Starting subtest: sysfs
[68.610712] Subtest sysfs: SUCCESS (0.306s)
[68.939858] [006/161] (953s left) fbdev (eof)
[69.301649] Starting subtest: eof
[69.324348] Subtest eof: SUCCESS (0.002s)
[69.540091] [007/161] (953s left) fbdev (info)
[69.830276] Starting subtest: info
[69.851628] Subtest info: SUCCESS (0.000s)
[70.027831] [008/161] (953s left) fbdev (nullptr)
[70.305995] Starting subtest: nullptr
[70.328406] Subtest nullptr: SUCCESS (0.000s)
[70.480834] [009/161] (952s left) fbdev (read)
[70.699361] Starting subtest: read
[72.197371] Subtest read: SUCCESS (1.498s)
[72.364647] [010/161] (951s left) fbdev (write)
[72.592485] Starting subtest: write
[73.361079] Subtest write: SUCCESS (0.775s)
[73.536473] [011/161] (950s left) gem_basic (bad-close)
[73.809815] Starting subtest: bad-close
[73.852471] Subtest bad-close: SUCCESS (0.000s)
[74.040455] [012/161] (949s left) gem_basic (create-close)
[74.310715] Starting subtest: create-close
[74.352253] Subtest create-close: SUCCESS (0.000s)
[74.516919] [013/161] (949s left) gem_basic (create-fd-close)
[74.812726] Starting subtest: create-fd-close
[74.854068] Subtest create-fd-close: SUCCESS (0.001s)
[75.050078] [014/161] (949s left) gem_busy (busy@all-engines)
[75.367251] Starting subtest: busy
[75.389566] Starting dynamic subtest: all-engines
[75.476974] Dynamic subtest all-engines: SUCCESS (0.124s)
[75.497575] Subtest busy: SUCCESS (0.125s)
[75.953907] [015/161] (948s left) gem_close_race (basic-process)
[76.221922] Starting subtest: basic-process
[76.332990] Subtest basic-process: SUCCESS (0.121s)
[76.623342] [016/161] (948s left) gem_close_race (basic-threads)
[76.878915] Starting subtest: basic-threads
[78.103085] Subtest basic-threads: SUCCESS (1.233s)
[78.391649] [017/161] (946s left) gem_ctx_create (basic)
[78.641090] Starting subtest: basic
[78.661794] Subtest basic: SUCCESS (0.000s)
[78.901238] [018/161] (946s left) gem_ctx_create (basic-files)
[79.143107] Starting subtest: basic-files
[81.202036] Subtest basic-files: SUCCESS (2.041s)
[81.505085] [019/161] (943s left) gem_ctx_exec (basic)
[81.774325] Starting subtest: basic
[81.797524] Subtest basic: SUCCESS (0.011s)
[81.947829] [020/161] (943s left) gem_exec_basic (basic)
[82.219794] Starting subtest: basic
[82.240129] Starting dynamic subtest: rcs0-smem
[82.263073] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[82.313137] Starting dynamic subtest: bcs0-smem
[82.333534] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[82.383440] Starting dynamic subtest: vcs0-smem
[82.406889] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[82.468693] Starting dynamic subtest: vecs0-smem
[82.489726] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[82.510229] Subtest basic: SUCCESS (0.013s)
[82.666239] [021/161] (942s left) gem_exec_create (basic)
[82.938489] Starting subtest: basic
[82.958854] Starting dynamic subtest: smem
[85.012085] Dynamic subtest smem: SUCCESS (2.081s)
[85.032638] Subtest basic: SUCCESS (2.082s)
[85.313691] [022/161] (940s left) gem_exec_fence (basic-busy)
[85.725694] Starting subtest: basic-busy
[85.746425] Starting dynamic subtest: rcs0
[85.791918] Dynamic subtest rcs0: SUCCESS (0.040s)
[85.851503] Starting dynamic subtest: bcs0
[85.894204] Dynamic subtest bcs0: SUCCESS (0.026s)
[85.941257] Starting dynamic subtest: vcs0
[85.983298] Dynamic subtest vcs0: SUCCESS (0.036s)
[86.026410] Starting dynamic subtest: vecs0
[86.068847] Dynamic subtest vecs0: SUCCESS (0.062s)
[86.089205] Subtest basic-busy: SUCCESS (0.168s)
[86.760740] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[86.786381] [023/161] (939s left) gem_exec_fence (basic-wait)
[87.171926] Starting subtest: basic-wait
[87.194217] Starting dynamic subtest: rcs0
[87.214949] Dynamic subtest rcs0: SUCCESS (0.035s)
[87.263987] Starting dynamic subtest: bcs0
[87.285260] Dynamic subtest bcs0: SUCCESS (0.037s)
[87.341246] Starting dynamic subtest: vcs0
[87.368149] Dynamic subtest vcs0: SUCCESS (0.034s)
[87.417827] Starting dynamic subtest: vecs0
[87.440897] Dynamic subtest vecs0: SUCCESS (0.034s)
[87.463960] Subtest basic-wait: SUCCESS (0.143s)
[88.140076] [024/161] (937s left) gem_exec_fence (basic-await)
[88.524590] Starting subtest: basic-await
[88.548412] Starting dynamic subtest: rcs0
[88.685609] Dynamic subtest rcs0: SUCCESS (0.160s)
[88.727877] Starting dynamic subtest: bcs0
[88.852318] Dynamic subtest bcs0: SUCCESS (0.164s)
[88.895490] Starting dynamic subtest: vcs0
[89.016590] Dynamic subtest vcs0: SUCCESS (0.162s)
[89.062059] Starting dynamic subtest: vecs0
[89.181781] Dynamic subtest vecs0: SUCCESS (0.167s)
[89.204884] Subtest basic-await: SUCCESS (0.658s)
[90.014912] [025/161] (936s left) gem_exec_fence (nb-await)
[90.394322] Starting subtest: nb-await
[90.417305] Starting dynamic subtest: rcs0
[90.475289] Dynamic subtest rcs0: SUCCESS (0.082s)
[90.524117] Starting dynamic subtest: bcs0
[90.576740] Dynamic subtest bcs0: SUCCESS (0.101s)
[90.636108] Starting dynamic subtest: vcs0
[90.660193] Dynamic subtest vcs0: SUCCESS (0.069s)
[90.723137] Starting dynamic subtest: vecs0
[90.744184] Dynamic subtest vecs0: SUCCESS (0.076s)
[90.765153] Subtest nb-await: SUCCESS (0.332s)
[91.544646] [026/161] (934s left) gem_exec_gttfill (basic)
[91.811918] Starting subtest: basic
[121.744311] Subtest basic: SUCCESS (29.952s)
[122.074773] [027/161] (904s left) gem_exec_parallel (engines)
[122.479134] Starting subtest: engines
[122.501816] Starting dynamic subtest: basic
[127.896404] Dynamic subtest basic: SUCCESS (5.262s)
[127.946223] Starting dynamic subtest: contexts
[133.564416] Dynamic subtest contexts: SUCCESS (5.691s)
[133.611220] Starting dynamic subtest: fds
[139.123131] Dynamic subtest fds: SUCCESS (5.564s)
[139.171768] Starting dynamic subtest: userptr
[143.342574] Dynamic subtest userptr: SUCCESS (4.184s)
[143.364837] Subtest engines: SUCCESS (20.704s)
[143.543359] [028/161] (883s left) gem_exec_store (basic)
[143.859722] Starting subtest: basic
[143.901729] Subtest basic: SUCCESS (0.107s)
[144.214449] [029/161] (882s left) gem_flink_basic (bad-flink)
[144.464068] Starting subtest: bad-flink
[144.487713] Subtest bad-flink: SUCCESS (0.000s)
[144.653435] [030/161] (882s left) gem_flink_basic (bad-open)
[144.897687] Starting subtest: bad-open
[144.917973] Subtest bad-open: SUCCESS (0.000s)
[145.084873] [031/161] (882s left) gem_flink_basic (basic)
[145.324962] Starting subtest: basic
[145.349093] Subtest basic: SUCCESS (0.000s)
[145.514717] [032/161] (881s left) gem_flink_basic (double-flink)
[145.757596] Starting subtest: double-flink
[145.778034] Subtest double-flink: SUCCESS (0.000s)
[145.963912] [033/161] (881s left) gem_flink_basic (flink-lifetime)
[146.226953] Starting subtest: flink-lifetime
[146.247174] Subtest flink-lifetime: SUCCESS (0.003s)
[146.415770] [034/161] (881s left) gem_huc_copy (huc-copy)
[146.674659] Starting subtest: huc-copy
[146.726856] Subtest huc-copy: SKIP (0.000s)
[146.908984] [035/161] (881s left) gem_linear_blits (basic)
[147.326686] Starting subtest: basic
[147.348755] Subtest basic: SUCCESS (0.021s)
[147.642319] [036/161] (880s left) gem_mmap (basic)
[148.060320] Starting subtest: basic
[148.169705] Subtest basic: SUCCESS (0.001s)
[148.333648] [037/161] (880s left) gem_mmap_gtt (basic)
[148.583358] Starting subtest: basic
[148.603928] Subtest basic: SUCCESS (0.002s)
[148.777848] [038/161] (879s left) gem_render_linear_blits (basic)
[149.047952] Starting subtest: basic
[149.156741] Subtest basic: SUCCESS (0.098s)
[149.323737] [039/161] (879s left) gem_render_tiled_blits (basic)
[149.595904] Starting subtest: basic
[149.725484] Subtest basic: SUCCESS (0.139s)
[149.895727] [040/161] (879s left) gem_ringfill (basic-all)
[150.482151] Starting subtest: basic-all
[151.717312] Subtest basic-all: SUCCESS (1.257s)
[151.940640] [041/161] (877s left) gem_softpin (allocator-basic)
[152.222241] Starting subtest: allocator-basic
[152.462565] Subtest allocator-basic: SUCCESS (0.287s)
[152.674667] [042/161] (876s left) gem_softpin (allocator-basic-reserve)
[152.939548] Starting subtest: allocator-basic-reserve
[153.181601] Subtest allocator-basic-reserve: SUCCESS (0.284s)
[153.422170] [043/161] (875s left) gem_softpin (safe-alignment)
[153.658420] Starting subtest: safe-alignment
[153.679108] Subtest safe-alignment: SUCCESS (0.037s)
[153.891970] [044/161] (875s left) gem_sync (basic-all)
[154.331286] Starting subtest: basic-all
[156.440120] Subtest basic-all: SUCCESS (2.291s)
[156.741451] [045/161] (872s left) gem_sync (basic-each)
[157.181571] Starting subtest: basic-each
[159.297909] Subtest basic-each: SUCCESS (2.303s)
[159.593635] [046/161] (870s left) gem_tiled_blits (basic)
[159.913834] Starting subtest: basic
[159.980991] Subtest basic: SUCCESS (0.065s)
[160.284124] [047/161] (869s left) gem_tiled_fence_blits (basic)
[160.830046] Starting subtest: basic
[160.873202] Subtest basic: SUCCESS (0.044s)
[161.153620] [048/161] (869s left) gem_tiled_pread_basic
[161.987482] [049/161] (868s left) gem_wait (busy@all-engines)
[162.255595] Starting subtest: busy
[162.276057] Starting dynamic subtest: all-engines
[162.799369] Dynamic subtest all-engines: SUCCESS (0.531s)
[162.820379] Subtest busy: SUCCESS (0.532s)
[163.139450] [050/161] (867s left) gem_wait (wait@all-engines)
[163.420861] Starting subtest: wait
[163.447932] Starting dynamic subtest: all-engines
[164.470088] Dynamic subtest all-engines: SUCCESS (1.056s)
[164.499344] Subtest wait: SUCCESS (1.057s)
[164.833398] [051/161] (865s left) i915_getparams_basic (basic-eu-total)
[165.094905] Starting subtest: basic-eu-total
[165.147562] Subtest basic-eu-total: SUCCESS (0.000s)
[165.327540] [052/161] (865s left) i915_getparams_basic (basic-subslice-total)
[165.574406] Starting subtest: basic-subslice-total
[165.616699] Subtest basic-subslice-total: SUCCESS (0.000s)
[165.791429] [053/161] (865s left) i915_hangman (error-state-basic)
[166.064031] Starting subtest: error-state-basic
[166.085073] Subtest error-state-basic: SUCCESS (0.019s)
[166.268548] [054/161] (865s left) i915_pciid
[166.721708] [055/161] (864s left) kms_addfb_basic (addfb25-4-tiled)
[167.139051] Starting subtest: addfb25-4-tiled
[167.160641] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[167.343325] [056/161] (864s left) kms_addfb_basic (addfb25-bad-modifier)
[167.692680] Starting subtest: addfb25-bad-modifier
[167.713346] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[167.879174] [057/161] (863s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[168.238573] Starting subtest: addfb25-framebuffer-vs-set-tiling
[168.258107] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[168.430632] [058/161] (863s left) kms_addfb_basic (addfb25-modifier-no-flag)
[168.787342] Starting subtest: addfb25-modifier-no-flag
[168.807927] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[168.979140] [059/161] (863s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[169.310980] Starting subtest: addfb25-x-tiled-legacy
[169.332825] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[169.506736] [060/161] (862s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[169.863793] Starting subtest: addfb25-x-tiled-mismatch-legacy
[169.886104] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[170.053730] [061/161] (862s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[170.405565] Starting subtest: addfb25-yf-tiled-legacy
[170.427310] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[170.594185] [062/161] (862s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[170.943279] Starting subtest: addfb25-y-tiled-legacy
[170.962673] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[171.144140] [063/161] (861s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[171.494831] Starting subtest: addfb25-y-tiled-small-legacy
[171.514454] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[171.704402] [064/161] (861s left) kms_addfb_basic (bad-pitch-0)
[171.962297] Starting subtest: bad-pitch-0
[171.984150] Subtest bad-pitch-0: SUCCESS (0.000s)
[172.242638] [065/161] (860s left) kms_addfb_basic (bad-pitch-1024)
[172.479670] Starting subtest: bad-pitch-1024
[172.499454] Subtest bad-pitch-1024: SUCCESS (0.000s)
[172.799425] [066/161] (860s left) kms_addfb_basic (bad-pitch-128)
[173.039493] Starting subtest: bad-pitch-128
[173.061127] Subtest bad-pitch-128: SUCCESS (0.000s)
[173.345447] [067/161] (860s left) kms_addfb_basic (bad-pitch-256)
[173.582759] Starting subtest: bad-pitch-256
[173.604937] Subtest bad-pitch-256: SUCCESS (0.000s)
[173.892109] [068/161] (859s left) kms_addfb_basic (bad-pitch-32)
[174.155980] Starting subtest: bad-pitch-32
[174.182772] Subtest bad-pitch-32: SUCCESS (0.000s)
[174.451812] [069/161] (859s left) kms_addfb_basic (bad-pitch-63)
[174.716479] Starting subtest: bad-pitch-63
[174.741176] Subtest bad-pitch-63: SUCCESS (0.000s)
[175.020790] [070/161] (859s left) kms_addfb_basic (bad-pitch-65536)
[175.286167] Starting subtest: bad-pitch-65536
[175.307338] Subtest bad-pitch-65536: SUCCESS (0.001s)
[175.576373] [071/161] (858s left) kms_addfb_basic (bad-pitch-999)
[175.811662] Starting subtest: bad-pitch-999
[175.832995] Subtest bad-pitch-999: SUCCESS (0.000s)
[176.124678] [072/161] (858s left) kms_addfb_basic (basic)
[176.384471] Starting subtest: basic
[176.405492] Subtest basic: SUCCESS (0.000s)
[176.664691] [073/161] (857s left) kms_addfb_basic (basic-x-tiled-legacy)
[176.929027] Starting subtest: basic-x-tiled-legacy
[176.948397] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[177.220817] [074/161] (857s left) kms_addfb_basic (basic-y-tiled-legacy)
[177.462858] Starting subtest: basic-y-tiled-legacy
[177.485217] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[177.766422] [075/161] (857s left) kms_addfb_basic (bo-too-small)
[178.119883] Starting subtest: bo-too-small
[178.142403] Subtest bo-too-small: SUCCESS (0.001s)
[178.309776] [076/161] (856s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[178.665907] Starting subtest: bo-too-small-due-to-tiling
[178.689071] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[178.855622] [077/161] (856s left) kms_addfb_basic (clobberred-modifier)
[179.100367] Starting subtest: clobberred-modifier
[179.120195] Subtest clobberred-modifier: SUCCESS (0.000s)
[179.382285] [078/161] (855s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[179.622212] Starting subtest: framebuffer-vs-set-tiling
[179.644515] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[179.909186] [079/161] (855s left) kms_addfb_basic (invalid-get-prop)
[180.150290] Starting subtest: invalid-get-prop
[180.170153] Subtest invalid-get-prop: SUCCESS (0.000s)
[180.443350] [080/161] (855s left) kms_addfb_basic (invalid-get-prop-any)
[180.680854] Starting subtest: invalid-get-prop-any
[180.702899] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[180.976990] [081/161] (854s left) kms_addfb_basic (invalid-set-prop)
[181.237249] Starting subtest: invalid-set-prop
[181.259405] Subtest invalid-set-prop: SUCCESS (0.000s)
[181.514092] [082/161] (854s left) kms_addfb_basic (invalid-set-prop-any)
[181.780677] Starting subtest: invalid-set-prop-any
[181.800144] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[182.062308] [083/161] (854s left) kms_addfb_basic (no-handle)
[182.300620] Starting subtest: no-handle
[182.321923] Subtest no-handle: SUCCESS (0.000s)
[182.612467] [084/161] (853s left) kms_addfb_basic (size-max)
[182.964925] Starting subtest: size-max
[182.986404] Subtest size-max: SUCCESS (0.000s)
[183.156729] [085/161] (853s left) kms_addfb_basic (small-bo)
[183.518909] Starting subtest: small-bo
[183.545779] Subtest small-bo: SUCCESS (0.000s)
[183.729989] [086/161] (852s left) kms_addfb_basic (tile-pitch-mismatch)
[184.016862] Starting subtest: tile-pitch-mismatch
[184.041102] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[184.280377] [087/161] (852s left) kms_addfb_basic (too-high)
[184.631485] Starting subtest: too-high
[184.654995] Subtest too-high: SUCCESS (0.003s)
[184.821425] [088/161] (852s left) kms_addfb_basic (too-wide)
[185.175306] Starting subtest: too-wide
[185.197719] Subtest too-wide: SUCCESS (0.000s)
[185.362913] [089/161] (851s left) kms_addfb_basic (unused-handle)
[185.625663] Starting subtest: unused-handle
[185.647625] Subtest unused-handle: SUCCESS (0.000s)
[185.910631] [090/161] (851s left) kms_addfb_basic (unused-modifier)
[186.173735] Starting subtest: unused-modifier
[186.195835] Subtest unused-modifier: SUCCESS (0.000s)
[186.464565] [091/161] (851s left) kms_addfb_basic (unused-offsets)
[186.724208] Starting subtest: unused-offsets
[186.746370] Subtest unused-offsets: SUCCESS (0.000s)
[187.005545] [092/161] (850s left) kms_addfb_basic (unused-pitches)
[187.265663] Starting subtest: unused-pitches
[187.288446] Subtest unused-pitches: SUCCESS (0.000s)
[187.559881] [093/161] (850s left) kms_busy (basic)
[187.918180] Starting subtest: basic
[187.938202] Starting dynamic subtest: flip
[188.756272] Dynamic subtest flip: SUCCESS (0.823s)
[188.811227] Starting dynamic subtest: modeset
[190.216889] Dynamic subtest modeset: SUCCESS (1.454s)
[190.236967] Subtest basic: SUCCESS (2.287s)
[190.693286] [094/161] (847s left) kms_prop_blob (basic)
[190.964147] Starting subtest: basic
[190.984237] Subtest basic: SUCCESS (0.000s)
[191.158185] [095/161] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[191.511378] Starting subtest: basic-busy-flip-before-cursor-atomic
[191.552350] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[191.720736] [096/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[192.080388] Starting subtest: basic-busy-flip-before-cursor-legacy
[192.130461] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[192.312430] [097/161] (846s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[192.679160] Starting subtest: basic-flip-after-cursor-atomic
[193.648499] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.992s)
[194.087162] [098/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[194.438764] Starting subtest: basic-flip-after-cursor-legacy
[195.438587] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.993s)
[195.852661] [099/161] (843s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[196.197982] Starting subtest: basic-flip-after-cursor-varying-size
[197.183230] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.996s)
[197.633812] [100/161] (841s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[197.977965] Starting subtest: basic-flip-before-cursor-atomic
[198.975498] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.995s)
[199.396456] [101/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[199.747186] Starting subtest: basic-flip-before-cursor-legacy
[200.744338] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.989s)
[201.167367] [102/161] (838s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[201.495618] Starting subtest: basic-flip-before-cursor-varying-size
[202.477999] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.998s)
[202.924461] [103/161] (836s left) kms_dsc (dsc-basic)
[203.282156] Subtest dsc-basic: SKIP (0.000s)
[203.451178] [104/161] (836s left) kms_flip (basic-flip-vs-dpms)
[203.837851] Starting subtest: basic-flip-vs-dpms
[203.856918] Starting dynamic subtest: A-DP1
[205.068646] Dynamic subtest A-DP1: SUCCESS (1.246s)
[205.114298] Starting dynamic subtest: B-DP1
[206.199035] Dynamic subtest B-DP1: SUCCESS (1.139s)
[206.244712] Starting dynamic subtest: C-DP1
[207.376389] Dynamic subtest C-DP1: SUCCESS (1.153s)
[207.399309] Subtest basic-flip-vs-dpms: SUCCESS (3.550s)
[207.854163] [105/161] (832s left) kms_flip (basic-flip-vs-modeset)
[208.225519] Starting subtest: basic-flip-vs-modeset
[208.247874] Starting dynamic subtest: A-DP1
[209.469326] Dynamic subtest A-DP1: SUCCESS (1.271s)
[209.516173] Starting dynamic subtest: B-DP1
[210.618340] Dynamic subtest B-DP1: SUCCESS (1.147s)
[210.666142] Starting dynamic subtest: C-DP1
[211.783687] Dynamic subtest C-DP1: SUCCESS (1.150s)
[211.811080] Subtest basic-flip-vs-modeset: SUCCESS (3.579s)
[212.274590] [106/161] (827s left) kms_flip (basic-flip-vs-wf_vblank)
[212.661136] Starting subtest: basic-flip-vs-wf_vblank
[212.680231] Starting dynamic subtest: A-DP1
[214.089405] Dynamic subtest A-DP1: SUCCESS (1.452s)
[214.139058] Starting dynamic subtest: B-DP1
[215.517036] Dynamic subtest B-DP1: SUCCESS (1.419s)
[215.562938] Starting dynamic subtest: C-DP1
[216.942632] Dynamic subtest C-DP1: SUCCESS (1.423s)
[216.962320] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.306s)
[217.473619] [107/161] (822s left) kms_flip (basic-plain-flip)
[217.847372] Starting subtest: basic-plain-flip
[217.868092] Starting dynamic subtest: A-DP1
[218.987252] Dynamic subtest A-DP1: SUCCESS (1.166s)
[219.033206] Starting dynamic subtest: B-DP1
[220.124364] Dynamic subtest B-DP1: SUCCESS (1.135s)
[220.171818] Starting dynamic subtest: C-DP1
[221.265283] Dynamic subtest C-DP1: SUCCESS (1.138s)
[221.284875] Subtest basic-plain-flip: SUCCESS (3.451s)
[221.810764] [108/161] (818s left) kms_force_connector_basic (force-connector-state)
[222.090077] Subtest force-connector-state: SKIP (0.000s)
[222.261701] [109/161] (818s left) kms_force_connector_basic (force-edid)
[222.553849] Subtest force-edid: SKIP (0.000s)
[222.724832] [110/161] (818s left) kms_force_connector_basic (force-load-detect)
[223.009154] Subtest force-load-detect: SKIP (0.000s)
[223.176794] [111/161] (817s left) kms_force_connector_basic (prune-stale-modes)
[223.454506] Subtest prune-stale-modes: SKIP (0.000s)
[223.627131] [112/161] (817s left) kms_frontbuffer_tracking (basic)
[224.075735] Starting subtest: basic
[227.723863] Subtest basic: SUCCESS (3.728s)
[227.946370] [113/161] (813s left) kms_hdmi_inject (inject-audio)
[228.235617] Subtest inject-audio: SKIP (0.000s)
[228.403918] [114/161] (813s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[228.756319] Starting subtest: compare-crc-sanitycheck-xr24
[228.778922] Starting dynamic subtest: pipe-A-DP-1
[229.232419] Dynamic subtest pipe-A-DP-1: SUCCESS (0.463s)
[229.290659] Starting dynamic subtest: pipe-B-DP-1
[229.848204] Dynamic subtest pipe-B-DP-1: SUCCESS (0.629s)
[229.895271] Starting dynamic subtest: pipe-C-DP-1
[230.505354] Dynamic subtest pipe-C-DP-1: SUCCESS (0.633s)
[230.524326] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.741s)
[230.948135] [115/161] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[231.285443] Starting subtest: compare-crc-sanitycheck-nv12
[231.307166] Starting dynamic subtest: pipe-A-DP-1
[231.346439] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[231.414860] Starting dynamic subtest: pipe-B-DP-1
[231.457423] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[231.529104] Starting dynamic subtest: pipe-C-DP-1
[231.574786] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[231.615776] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[231.793324] [116/161] (810s left) kms_pipe_crc_basic (hang-read-crc)
[232.146107] Starting subtest: hang-read-crc
[232.165953] Starting dynamic subtest: pipe-A-DP-1
[233.458296] Dynamic subtest pipe-A-DP-1: SUCCESS (1.310s)
[233.507204] Starting dynamic subtest: pipe-B-DP-1
[234.931133] Dynamic subtest pipe-B-DP-1: SUCCESS (1.453s)
[235.002127] Starting dynamic subtest: pipe-C-DP-1
[236.373833] Dynamic subtest pipe-C-DP-1: SUCCESS (1.460s)
[236.397304] Subtest hang-read-crc: SUCCESS (4.237s)
[236.807675] [117/161] (805s left) kms_pipe_crc_basic (nonblocking-crc)
[237.165053] Starting subtest: nonblocking-crc
[237.184810] Starting dynamic subtest: pipe-A-DP-1
[237.815191] Dynamic subtest pipe-A-DP-1: SUCCESS (0.647s)
[237.870136] Starting dynamic subtest: pipe-B-DP-1
[238.615092] Dynamic subtest pipe-B-DP-1: SUCCESS (0.789s)
[238.691476] Starting dynamic subtest: pipe-C-DP-1
[239.396509] Dynamic subtest pipe-C-DP-1: SUCCESS (0.793s)
[239.417778] Subtest nonblocking-crc: SUCCESS (2.245s)
[239.828377] [118/161] (802s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[240.193097] Starting subtest: nonblocking-crc-frame-sequence
[240.215202] Starting dynamic subtest: pipe-A-DP-1
[240.824962] Dynamic subtest pipe-A-DP-1: SUCCESS (0.636s)
[240.873843] Starting dynamic subtest: pipe-B-DP-1
[241.639983] Dynamic subtest pipe-B-DP-1: SUCCESS (0.810s)
[241.703414] Starting dynamic subtest: pipe-C-DP-1
[242.451095] Dynamic subtest pipe-C-DP-1: SUCCESS (0.801s)
[242.472856] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.263s)
[242.866271] [119/161] (799s left) kms_pipe_crc_basic (read-crc)
[243.234751] Starting subtest: read-crc
[243.257839] Starting dynamic subtest: pipe-A-DP-1
[243.778927] Dynamic subtest pipe-A-DP-1: SUCCESS (0.554s)
[243.857925] Starting dynamic subtest: pipe-B-DP-1
[244.522466] Dynamic subtest pipe-B-DP-1: SUCCESS (0.735s)
[244.587594] Starting dynamic subtest: pipe-C-DP-1
[245.278751] Dynamic subtest pipe-C-DP-1: SUCCESS (0.750s)
[245.300328] Subtest read-crc: SUCCESS (2.054s)
[245.707905] [120/161] (796s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[246.066718] Starting subtest: read-crc-frame-sequence
[246.088122] Starting dynamic subtest: pipe-A-DP-1
[246.615474] Dynamic subtest pipe-A-DP-1: SUCCESS (0.560s)
[246.680262] Starting dynamic subtest: pipe-B-DP-1
[247.371110] Dynamic subtest pipe-B-DP-1: SUCCESS (0.739s)
[247.420908] Starting dynamic subtest: pipe-C-DP-1
[248.130241] Dynamic subtest pipe-C-DP-1: SUCCESS (0.749s)
[248.153232] Subtest read-crc-frame-sequence: SUCCESS (2.064s)
[248.547565] [121/161] (794s left) kms_pm_backlight (basic-brightness)
[248.932116] Subtest basic-brightness: SKIP (0.000s)
[249.103810] [122/161] (793s left) kms_pm_rpm (basic-pci-d3-state)
[250.932561] Starting subtest: basic-pci-d3-state
[251.612623] Subtest basic-pci-d3-state: SUCCESS (0.676s)
[251.929802] [123/161] (791s left) kms_pm_rpm (basic-rte)
[252.178260] Starting subtest: basic-rte
[254.377257] Subtest basic-rte: SUCCESS (2.224s)
[255.308518] [124/161] (787s left) kms_psr (psr-primary-page-flip)
[255.689645] Subtest psr-primary-page-flip: SKIP (0.000s)
[255.860747] [125/161] (787s left) kms_psr (psr-cursor-plane-move)
[256.236591] Subtest psr-cursor-plane-move: SKIP (0.000s)
[256.409809] [126/161] (787s left) kms_psr (psr-sprite-plane-onoff)
[256.789235] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[256.956326] [127/161] (786s left) kms_psr (psr-primary-mmap-gtt)
[257.315431] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[257.495526] [128/161] (786s left) kms_setmode (basic-clone-single-crtc)
[257.764837] Starting subtest: basic-clone-single-crtc
[257.826628] Subtest basic-clone-single-crtc: SKIP (0.000s)
[257.997444] [129/161] (786s left) i915_pm_rps (basic-api)
[258.249364] Starting subtest: basic-api
[258.270358] Subtest basic-api: SUCCESS (0.008s)
[258.468603] [130/161] (785s left) prime_self_import (basic-llseek-bad)
[258.715988] Starting subtest: basic-llseek-bad
[258.759762] Subtest basic-llseek-bad: SUCCESS (0.005s)
[258.936812] [131/161] (785s left) prime_self_import (basic-llseek-size)
[259.172713] Starting subtest: basic-llseek-size
[259.217170] Subtest basic-llseek-size: SUCCESS (0.008s)
[259.387641] [132/161] (785s left) prime_self_import (basic-with_fd_dup)
[259.613839] Starting subtest: basic-with_fd_dup
[259.655384] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[259.835612] [133/161] (785s left) prime_self_import (basic-with_one_bo)
[260.055387] Starting subtest: basic-with_one_bo
[260.099380] Subtest basic-with_one_bo: SUCCESS (0.009s)
[260.274146] [134/161] (784s left) prime_self_import (basic-with_one_bo_two_files)
[260.513756] Starting subtest: basic-with_one_bo_two_files
[260.558151] Subtest basic-with_one_bo_two_files: SUCCESS (0.007s)
[260.731231] [135/161] (784s left) prime_self_import (basic-with_two_bos)
[260.969313] Starting subtest: basic-with_two_bos
[261.010514] Subtest basic-with_two_bos: SUCCESS (0.009s)
[261.183760] [136/161] (784s left) prime_vgem (basic-fence-flip)
[261.456937] Starting subtest: basic-fence-flip
[262.077833] Subtest basic-fence-flip: SUCCESS (0.654s)
[262.275590] [137/161] (783s left) prime_vgem (basic-fence-mmap)
[262.558382] Starting subtest: basic-fence-mmap
[262.611270] Subtest basic-fence-mmap: SUCCESS (0.076s)
[262.811564] [138/161] (782s left) prime_vgem (basic-fence-read)
[263.073947] Starting subtest: basic-fence-read
[263.168078] Subtest basic-fence-read: SUCCESS (0.142s)
[263.359777] [139/161] (782s left) prime_vgem (basic-gtt)
[263.645587] Starting subtest: basic-gtt
[263.665445] Subtest basic-gtt: SUCCESS (0.032s)
[263.858924] [140/161] (782s left) prime_vgem (basic-read)
[264.163398] Starting subtest: basic-read
[264.192751] Subtest basic-read: SUCCESS (0.065s)
[264.402203] [141/161] (781s left) prime_vgem (basic-write)
[264.718937] Starting subtest: basic-write
[264.773109] Subtest basic-write: SUCCESS (0.092s)
[264.987152] [142/161] (781s left) vgem_basic (setversion)
[265.267210] Starting subtest: setversion
[265.310533] Subtest setversion: SUCCESS (0.000s)
[265.480363] [143/161] (781s left) vgem_basic (create)
[265.741909] Starting subtest: create
[265.764470] Subtest create: SUCCESS (0.001s)
[265.947742] [144/161] (780s left) vgem_basic (debugfs)
[266.210689] Starting subtest: debugfs
[266.231958] Subtest debugfs: SUCCESS (0.001s)
[266.404208] [145/161] (780s left) vgem_basic (dmabuf-export)
[266.668381] Starting subtest: dmabuf-export
[266.709148] Subtest dmabuf-export: SUCCESS (0.005s)
[266.881898] [146/161] (780s left) vgem_basic (dmabuf-fence)
[267.146916] Starting subtest: dmabuf-fence
[267.167540] Subtest dmabuf-fence: SUCCESS (0.002s)
[267.339878] [147/161] (780s left) vgem_basic (dmabuf-fence-before)
[267.585277] Starting subtest: dmabuf-fence-before
[267.614920] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[267.790197] [148/161] (779s left) vgem_basic (dmabuf-mmap)
[268.060301] Starting subtest: dmabuf-mmap
[268.082672] Subtest dmabuf-mmap: SUCCESS (0.036s)
[268.256611] [149/161] (779s left) vgem_basic (mmap)
[268.513818] Starting subtest: mmap
[268.534962] Subtest mmap: SUCCESS (0.023s)
[268.705745] [150/161] (779s left) vgem_basic (second-client)
[268.968378] Starting subtest: second-client
[269.009486] Subtest second-client: SUCCESS (0.032s)
[269.181992] [151/161] (779s left) vgem_basic (sysfs)
[269.442563] Starting subtest: sysfs
[269.462508] Subtest sysfs: SUCCESS (0.001s)
[269.635404] [152/161] (778s left) core_hotunplug (unbind-rebind)
[270.013154] Starting subtest: unbind-rebind
[274.591223] Subtest unbind-rebind: SUCCESS (4.575s)
[274.771718] [153/161] (773s left) vgem_basic (unload)
[275.017556] Starting subtest: unload
[304.399421] Subtest unload: SUCCESS (29.210s)
[304.597563] [154/161] (744s left) i915_module_load (reload)
[304.852061] Starting subtest: reload
[310.526645] Subtest reload: SUCCESS (5.733s)
[310.790794] [155/161] (738s left) gem_lmem_swapping (basic)
[311.127603] Subtest basic: SKIP (0.000s)
[311.297215] [156/161] (737s left) gem_lmem_swapping (parallel-random-engines)
[311.567340] Subtest parallel-random-engines: SKIP (0.000s)
[311.744594] [157/161] (737s left) gem_lmem_swapping (random-engines)
[312.012269] Subtest random-engines: SKIP (0.000s)
[312.190080] [158/161] (737s left) gem_lmem_swapping (verify-random)
[312.455960] Subtest verify-random: SKIP (0.000s)
[312.629427] [159/161] (737s left) i915_pm_rpm (module-reload)
[314.767161] Starting subtest: module-reload
[329.824291] Subtest module-reload: SUCCESS (15.074s)
[329.948904] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[329.952964] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[329.979348] [160/161] (720s left) i915_selftest (live)
[330.214510] Starting subtest: live
[330.237565] Starting dynamic subtest: sanitycheck
[334.035145] Dynamic subtest sanitycheck: SUCCESS (3.818s)
[334.094314] Starting dynamic subtest: uncore
[337.786140] Dynamic subtest uncore: SUCCESS (3.746s)
[337.845366] Starting dynamic subtest: workarounds
[342.127450] Dynamic subtest workarounds: SUCCESS (4.338s)
[342.187448] Starting dynamic subtest: gt_engines
[346.588860] Dynamic subtest gt_engines: SUCCESS (4.457s)
[346.649090] Starting dynamic subtest: gt_timelines
[370.997247] Dynamic subtest gt_timelines: SUCCESS (24.403s)
[371.057955] Starting dynamic subtest: gt_contexts
[375.011357] Dynamic subtest gt_contexts: SUCCESS (4.005s)
[375.061750] Starting dynamic subtest: gt_lrc
[381.995227] Dynamic subtest gt_lrc: SUCCESS (6.980s)
[382.038871] Starting dynamic subtest: gt_mocs
[386.056144] Dynamic subtest gt_mocs: SUCCESS (4.051s)
[386.119049] Starting dynamic subtest: gt_pm
[398.984843] Dynamic subtest gt_pm: SUCCESS (12.930s)
[399.044584] Starting dynamic subtest: gt_heartbeat
[403.122713] Dynamic subtest gt_heartbeat: SUCCESS (4.132s)
[403.166817] Starting dynamic subtest: gt_tlb
[408.209899] Dynamic subtest gt_tlb: SUCCESS (5.079s)
[408.252768] Starting dynamic subtest: requests
[418.765064] Dynamic subtest requests: SUCCESS (10.550s)
[418.808398] Starting dynamic subtest: migrate
[426.149349] Dynamic subtest migrate: SUCCESS (7.382s)
[426.209309] Starting dynamic subtest: active
[430.316878] Dynamic subtest active: SUCCESS (4.160s)
[430.359786] Starting dynamic subtest: objects
[436.658641] Dynamic subtest objects: SUCCESS (6.331s)
[436.730460] Starting dynamic subtest: mman
[447.294247] Dynamic subtest mman: SUCCESS (10.639s)
[447.354110] Starting dynamic subtest: dmabuf
[451.460452] Dynamic subtest dmabuf: SUCCESS (4.159s)
[451.520084] Starting dynamic subtest: vma
[455.532746] Dynamic subtest vma: SUCCESS (4.071s)
[455.590748] Starting dynamic subtest: coherency
[469.557479] Dynamic subtest coherency: SUCCESS (14.018s)
[469.598395] Starting dynamic subtest: gtt
[481.497200] Dynamic subtest gtt: SUCCESS (11.934s)
[481.540203] Starting dynamic subtest: gem
[487.062524] Dynamic subtest gem: SUCCESS (5.562s)
[487.120355] Starting dynamic subtest: evict
[495.919848] Dynamic subtest evict: SUCCESS (8.853s)
[495.961464] Starting dynamic subtest: hugepages
[506.438957] Dynamic subtest hugepages: SUCCESS (10.508s)
[506.486166] Starting dynamic subtest: gem_contexts
[533.088862] Dynamic subtest gem_contexts: SUCCESS (26.481s)
[533.130464] Starting dynamic subtest: client
[537.023594] Dynamic subtest client: SUCCESS (4.102s)
[537.067419] Starting dynamic subtest: gem_migrate
[540.739155] Dynamic subtest gem_migrate: SUCCESS (3.712s)
[540.796944] Starting dynamic subtest: reset
[555.019757] Dynamic subtest reset: SUCCESS (14.274s)
[555.081192] Starting dynamic subtest: memory_region
[559.094574] Dynamic subtest memory_region: SUCCESS (4.065s)
[559.136719] Starting dynamic subtest: hangcheck
[586.218590] Dynamic subtest hangcheck: SUCCESS (27.125s)
[586.262455] Starting dynamic subtest: execlists
[601.097813] Dynamic subtest execlists: SUCCESS (14.875s)
[601.158572] Starting dynamic subtest: ring_submission
[605.079193] Dynamic subtest ring_submission: SUCCESS (3.979s)
[605.139971] Starting dynamic subtest: perf
[609.364680] Dynamic subtest perf: SUCCESS (4.279s)
[609.425782] Starting dynamic subtest: slpc
[613.115678] Dynamic subtest slpc: SUCCESS (3.748s)
[613.157956] Starting dynamic subtest: guc
[617.086374] Dynamic subtest guc: SUCCESS (3.966s)
[617.170814] Starting dynamic subtest: guc_multi_lrc
[620.882175] Dynamic subtest guc_multi_lrc: SUCCESS (3.791s)
[620.945684] Starting dynamic subtest: guc_hang
[624.624850] Dynamic subtest guc_hang: SUCCESS (3.736s)
[624.685145] Starting dynamic subtest: late_gt_pm
[628.748160] Dynamic subtest late_gt_pm: SUCCESS (4.118s)
[628.769962] Subtest live: SUCCESS (298.530s)
[628.931876] [161/161] (421s left) dmabuf (all-tests)
[629.224205] Starting subtest: all-tests
[629.246602] Starting dynamic subtest: sanitycheck
[629.302118] Dynamic subtest sanitycheck: SUCCESS (0.099s)
[629.348528] Starting dynamic subtest: dma_fence
[629.561197] Dynamic subtes