[63.632507] Initializing watchdogs
[63.632822]   /dev/watchdog0
[63.690130] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[63.723744] [001/161] (960s left) i915_module_load (load)
[63.980877] Starting subtest: load
[68.218901] Subtest load: SUCCESS (4.239s)
[68.396661] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[68.401711] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[68.449501] [002/161] (955s left) core_auth (basic-auth)
[68.845933] Starting subtest: basic-auth
[68.867891] Subtest basic-auth: SUCCESS (0.004s)
[69.122745] [003/161] (955s left) debugfs_test (read_all_entries)
[69.440619] Starting subtest: read_all_entries
[69.650835] Subtest read_all_entries: SUCCESS (0.227s)
[70.074883] [004/161] (954s left) debugfs_test (basic-hwmon)
[70.397234] Starting subtest: basic-hwmon
[70.440634] Subtest basic-hwmon: SKIP (0.000s)
[70.700747] [005/161] (954s left) debugfs_test (sysfs)
[70.982996] Starting subtest: sysfs
[71.144249] Subtest sysfs: SUCCESS (0.177s)
[71.429359] [006/161] (953s left) fbdev (eof)
[71.691394] Starting subtest: eof
[71.712941] Subtest eof: SUCCESS (0.003s)
[71.870341] [007/161] (953s left) fbdev (info)
[72.114882] Starting subtest: info
[72.136474] Subtest info: SUCCESS (0.000s)
[72.290391] [008/161] (953s left) fbdev (nullptr)
[72.510232] Starting subtest: nullptr
[72.539696] Subtest nullptr: SUCCESS (0.000s)
[72.691985] [009/161] (953s left) fbdev (read)
[72.891360] Starting subtest: read
[74.386992] Subtest read: SUCCESS (1.498s)
[74.551455] [010/161] (951s left) fbdev (write)
[74.794908] Starting subtest: write
[75.544646] Subtest write: SUCCESS (0.771s)
[75.719431] [011/161] (950s left) gem_basic (bad-close)
[75.983219] Starting subtest: bad-close
[76.028343] Subtest bad-close: SUCCESS (0.000s)
[76.179133] [012/161] (950s left) gem_basic (create-close)
[76.422267] Starting subtest: create-close
[76.464864] Subtest create-close: SUCCESS (0.000s)
[76.623624] [013/161] (949s left) gem_basic (create-fd-close)
[76.867366] Starting subtest: create-fd-close
[76.907414] Subtest create-fd-close: SUCCESS (0.000s)
[77.063295] [014/161] (949s left) gem_busy (busy@all-engines)
[77.339790] Starting subtest: busy
[77.359421] Starting dynamic subtest: all-engines
[77.433484] Dynamic subtest all-engines: SUCCESS (0.110s)
[77.455550] Subtest busy: SUCCESS (0.111s)
[77.933952] [015/161] (948s left) gem_close_race (basic-process)
[78.200114] Starting subtest: basic-process
[78.304712] Subtest basic-process: SUCCESS (0.117s)
[78.600193] [016/161] (948s left) gem_close_race (basic-threads)
[78.860565] Starting subtest: basic-threads
[80.060572] Subtest basic-threads: SUCCESS (1.202s)
[80.328332] [017/161] (946s left) gem_ctx_create (basic)
[80.600808] Starting subtest: basic
[80.621661] Subtest basic: SUCCESS (0.000s)
[80.868623] [018/161] (946s left) gem_ctx_create (basic-files)
[81.088761] Starting subtest: basic-files
[83.146320] Subtest basic-files: SUCCESS (2.041s)
[83.435750] [019/161] (943s left) gem_ctx_exec (basic)
[83.709871] Starting subtest: basic
[83.730459] Subtest basic: SUCCESS (0.012s)
[83.884394] [020/161] (943s left) gem_exec_basic (basic)
[84.112187] Starting subtest: basic
[84.134828] Starting dynamic subtest: rcs0-smem
[84.157056] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[84.198194] Starting dynamic subtest: bcs0-smem
[84.218210] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[84.278064] Starting dynamic subtest: vcs0-smem
[84.301192] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[84.343577] Starting dynamic subtest: vecs0-smem
[84.363755] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[84.386506] Subtest basic: SUCCESS (0.014s)
[84.542564] [021/161] (943s left) gem_exec_create (basic)
[84.814474] Starting subtest: basic
[84.834628] Starting dynamic subtest: smem
[86.873869] Dynamic subtest smem: SUCCESS (2.074s)
[86.897255] Subtest basic: SUCCESS (2.075s)
[87.155350] [022/161] (940s left) gem_exec_fence (basic-busy)
[87.547411] Starting subtest: basic-busy
[87.567610] Starting dynamic subtest: rcs0
[87.609973] Dynamic subtest rcs0: SUCCESS (0.041s)
[87.661205] Starting dynamic subtest: bcs0
[87.701511] Dynamic subtest bcs0: SUCCESS (0.035s)
[87.764461] Starting dynamic subtest: vcs0
[87.806347] Dynamic subtest vcs0: SUCCESS (0.050s)
[87.854863] Starting dynamic subtest: vecs0
[87.899525] Dynamic subtest vecs0: SUCCESS (0.034s)
[87.919366] Subtest basic-busy: SUCCESS (0.163s)
[88.651658] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[88.675220] [023/161] (939s left) gem_exec_fence (basic-wait)
[89.058494] Starting subtest: basic-wait
[89.081788] Starting dynamic subtest: rcs0
[89.108476] Dynamic subtest rcs0: SUCCESS (0.037s)
[89.154850] Starting dynamic subtest: bcs0
[89.177496] Dynamic subtest bcs0: SUCCESS (0.032s)
[89.231104] Starting dynamic subtest: vcs0
[89.253351] Dynamic subtest vcs0: SUCCESS (0.037s)
[89.309673] Starting dynamic subtest: vecs0
[89.334672] Dynamic subtest vecs0: SUCCESS (0.034s)
[89.359226] Subtest basic-wait: SUCCESS (0.146s)
[90.040338] [024/161] (938s left) gem_exec_fence (basic-await)
[90.416180] Starting subtest: basic-await
[90.438568] Starting dynamic subtest: rcs0
[90.582583] Dynamic subtest rcs0: SUCCESS (0.166s)
[90.627358] Starting dynamic subtest: bcs0
[90.750413] Dynamic subtest bcs0: SUCCESS (0.166s)
[90.792523] Starting dynamic subtest: vcs0
[90.917395] Dynamic subtest vcs0: SUCCESS (0.164s)
[90.961239] Starting dynamic subtest: vecs0
[91.083899] Dynamic subtest vecs0: SUCCESS (0.167s)
[91.106511] Subtest basic-await: SUCCESS (0.668s)
[91.901403] [025/161] (936s left) gem_exec_fence (nb-await)
[92.289587] Starting subtest: nb-await
[92.311352] Starting dynamic subtest: rcs0
[92.370290] Dynamic subtest rcs0: SUCCESS (0.081s)
[92.418518] Starting dynamic subtest: bcs0
[92.468486] Dynamic subtest bcs0: SUCCESS (0.098s)
[92.516941] Starting dynamic subtest: vcs0
[92.570132] Dynamic subtest vcs0: SUCCESS (0.098s)
[92.616187] Starting dynamic subtest: vecs0
[92.661212] Dynamic subtest vecs0: SUCCESS (0.073s)
[92.682368] Subtest nb-await: SUCCESS (0.355s)
[93.460687] [026/161] (934s left) gem_exec_gttfill (basic)
[93.731458] Starting subtest: basic
[123.593213] Subtest basic: SUCCESS (29.882s)
[123.916523] [027/161] (904s left) gem_exec_parallel (engines)
[124.322773] Starting subtest: engines
[124.344846] Starting dynamic subtest: basic
[129.542338] Dynamic subtest basic: SUCCESS (5.057s)
[129.586405] Starting dynamic subtest: contexts
[135.364867] Dynamic subtest contexts: SUCCESS (5.823s)
[135.409340] Starting dynamic subtest: fds
[140.868264] Dynamic subtest fds: SUCCESS (5.527s)
[140.912389] Starting dynamic subtest: userptr
[144.993512] Dynamic subtest userptr: SUCCESS (4.113s)
[145.016428] Subtest engines: SUCCESS (20.522s)
[145.194772] [028/161] (883s left) gem_exec_store (basic)
[145.538328] Starting subtest: basic
[145.559059] Subtest basic: SUCCESS (0.087s)
[145.845964] [029/161] (883s left) gem_flink_basic (bad-flink)
[146.116766] Starting subtest: bad-flink
[146.139583] Subtest bad-flink: SUCCESS (0.000s)
[146.303590] [030/161] (882s left) gem_flink_basic (bad-open)
[146.544548] Starting subtest: bad-open
[146.564944] Subtest bad-open: SUCCESS (0.000s)
[146.733904] [031/161] (882s left) gem_flink_basic (basic)
[146.997588] Starting subtest: basic
[147.018873] Subtest basic: SUCCESS (0.000s)
[147.208191] [032/161] (882s left) gem_flink_basic (double-flink)
[147.436298] Starting subtest: double-flink
[147.457987] Subtest double-flink: SUCCESS (0.000s)
[147.630750] [033/161] (882s left) gem_flink_basic (flink-lifetime)
[147.891578] Starting subtest: flink-lifetime
[147.911423] Subtest flink-lifetime: SUCCESS (0.003s)
[148.077625] [034/161] (881s left) gem_huc_copy (huc-copy)
[148.341401] Starting subtest: huc-copy
[148.382352] Subtest huc-copy: SKIP (0.000s)
[148.560816] [035/161] (881s left) gem_linear_blits (basic)
[148.997821] Starting subtest: basic
[149.030208] Subtest basic: SUCCESS (0.020s)
[149.290128] [036/161] (880s left) gem_mmap (basic)
[149.701279] Starting subtest: basic
[149.809513] Subtest basic: SUCCESS (0.001s)
[149.979955] [037/161] (880s left) gem_mmap_gtt (basic)
[150.256252] Starting subtest: basic
[150.276620] Subtest basic: SUCCESS (0.002s)
[150.474822] [038/161] (880s left) gem_render_linear_blits (basic)
[150.736972] Starting subtest: basic
[150.844161] Subtest basic: SUCCESS (0.098s)
[151.010431] [039/161] (879s left) gem_render_tiled_blits (basic)
[151.256643] Starting subtest: basic
[151.382451] Subtest basic: SUCCESS (0.142s)
[151.583190] [040/161] (879s left) gem_ringfill (basic-all)
[152.145440] Starting subtest: basic-all
[153.346627] Subtest basic-all: SUCCESS (1.226s)
[153.566672] [041/161] (877s left) gem_softpin (allocator-basic)
[153.823978] Starting subtest: allocator-basic
[154.088669] Subtest allocator-basic: SUCCESS (0.284s)
[154.302646] [042/161] (876s left) gem_softpin (allocator-basic-reserve)
[154.565574] Starting subtest: allocator-basic-reserve
[154.805624] Subtest allocator-basic-reserve: SUCCESS (0.285s)
[155.036265] [043/161] (876s left) gem_softpin (safe-alignment)
[155.271652] Starting subtest: safe-alignment
[155.294115] Subtest safe-alignment: SUCCESS (0.035s)
[155.536980] [044/161] (876s left) gem_sync (basic-all)
[155.990880] Starting subtest: basic-all
[158.346477] Subtest basic-all: SUCCESS (2.391s)
[158.510664] [045/161] (873s left) gem_sync (basic-each)
[158.925771] Starting subtest: basic-each
[161.238940] Subtest basic-each: SUCCESS (2.476s)
[161.534768] [046/161] (870s left) gem_tiled_blits (basic)
[161.877631] Starting subtest: basic
[161.940418] Subtest basic: SUCCESS (0.062s)
[162.230406] [047/161] (869s left) gem_tiled_fence_blits (basic)
[162.758665] Starting subtest: basic
[162.802554] Subtest basic: SUCCESS (0.045s)
[163.080555] [048/161] (869s left) gem_tiled_pread_basic
[163.950956] [049/161] (868s left) gem_wait (busy@all-engines)
[164.229766] Starting subtest: busy
[164.252266] Starting dynamic subtest: all-engines
[164.770745] Dynamic subtest all-engines: SUCCESS (0.530s)
[164.793595] Subtest busy: SUCCESS (0.531s)
[165.130715] [050/161] (867s left) gem_wait (wait@all-engines)
[165.392940] Starting subtest: wait
[165.413859] Starting dynamic subtest: all-engines
[166.443851] Dynamic subtest all-engines: SUCCESS (1.043s)
[166.464887] Subtest wait: SUCCESS (1.044s)
[166.783650] [051/161] (866s left) i915_getparams_basic (basic-eu-total)
[167.087227] Starting subtest: basic-eu-total
[167.139526] Subtest basic-eu-total: SUCCESS (0.000s)
[167.333089] [052/161] (865s left) i915_getparams_basic (basic-subslice-total)
[167.600052] Starting subtest: basic-subslice-total
[167.652547] Subtest basic-subslice-total: SUCCESS (0.000s)
[167.836148] [053/161] (865s left) i915_hangman (error-state-basic)
[168.120755] Starting subtest: error-state-basic
[168.144559] Subtest error-state-basic: SUCCESS (0.022s)
[168.321076] [054/161] (865s left) i915_pciid
[168.759423] [055/161] (864s left) kms_addfb_basic (addfb25-4-tiled)
[169.195457] Starting subtest: addfb25-4-tiled
[169.216847] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[169.399726] [056/161] (864s left) kms_addfb_basic (addfb25-bad-modifier)
[169.744937] Starting subtest: addfb25-bad-modifier
[169.767617] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[169.931614] [057/161] (863s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[170.277365] Starting subtest: addfb25-framebuffer-vs-set-tiling
[170.296877] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[170.465403] [058/161] (863s left) kms_addfb_basic (addfb25-modifier-no-flag)
[170.816803] Starting subtest: addfb25-modifier-no-flag
[170.836852] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[171.018150] [059/161] (863s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[171.370582] Starting subtest: addfb25-x-tiled-legacy
[171.392507] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[171.572684] [060/161] (862s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[171.935067] Starting subtest: addfb25-x-tiled-mismatch-legacy
[171.956930] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[172.127510] [061/161] (862s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[172.477964] Starting subtest: addfb25-yf-tiled-legacy
[172.498092] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[172.686617] [062/161] (862s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[173.040782] Starting subtest: addfb25-y-tiled-legacy
[173.063942] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[173.229413] [063/161] (861s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[173.560275] Starting subtest: addfb25-y-tiled-small-legacy
[173.580381] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[173.774315] [064/161] (861s left) kms_addfb_basic (bad-pitch-0)
[174.036541] Starting subtest: bad-pitch-0
[174.056352] Subtest bad-pitch-0: SUCCESS (0.000s)
[174.320589] [065/161] (860s left) kms_addfb_basic (bad-pitch-1024)
[174.584284] Starting subtest: bad-pitch-1024
[174.604241] Subtest bad-pitch-1024: SUCCESS (0.000s)
[174.868971] [066/161] (860s left) kms_addfb_basic (bad-pitch-128)
[175.125838] Starting subtest: bad-pitch-128
[175.147774] Subtest bad-pitch-128: SUCCESS (0.000s)
[175.394884] [067/161] (860s left) kms_addfb_basic (bad-pitch-256)
[175.653580] Starting subtest: bad-pitch-256
[175.672931] Subtest bad-pitch-256: SUCCESS (0.000s)
[175.934344] [068/161] (859s left) kms_addfb_basic (bad-pitch-32)
[176.201255] Starting subtest: bad-pitch-32
[176.220867] Subtest bad-pitch-32: SUCCESS (0.000s)
[176.511631] [069/161] (859s left) kms_addfb_basic (bad-pitch-63)
[176.800857] Starting subtest: bad-pitch-63
[176.825708] Subtest bad-pitch-63: SUCCESS (0.000s)
[177.086912] [070/161] (859s left) kms_addfb_basic (bad-pitch-65536)
[177.376377] Starting subtest: bad-pitch-65536
[177.401870] Subtest bad-pitch-65536: SUCCESS (0.001s)
[177.637473] [071/161] (858s left) kms_addfb_basic (bad-pitch-999)
[177.901432] Starting subtest: bad-pitch-999
[177.921160] Subtest bad-pitch-999: SUCCESS (0.000s)
[178.183765] [072/161] (858s left) kms_addfb_basic (basic)
[178.445189] Starting subtest: basic
[178.466816] Subtest basic: SUCCESS (0.000s)
[178.729894] [073/161] (857s left) kms_addfb_basic (basic-x-tiled-legacy)
[178.985825] Starting subtest: basic-x-tiled-legacy
[179.007956] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[179.265119] [074/161] (857s left) kms_addfb_basic (basic-y-tiled-legacy)
[179.528888] Starting subtest: basic-y-tiled-legacy
[179.548789] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[179.800478] [075/161] (857s left) kms_addfb_basic (bo-too-small)
[180.153455] Starting subtest: bo-too-small
[180.175379] Subtest bo-too-small: SUCCESS (0.001s)
[180.345737] [076/161] (856s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[180.673434] Starting subtest: bo-too-small-due-to-tiling
[180.692910] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[180.908970] [077/161] (856s left) kms_addfb_basic (clobberred-modifier)
[181.143812] Starting subtest: clobberred-modifier
[181.163121] Subtest clobberred-modifier: SUCCESS (0.000s)
[181.444295] [078/161] (855s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[181.712887] Starting subtest: framebuffer-vs-set-tiling
[181.732638] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[181.997750] [079/161] (855s left) kms_addfb_basic (invalid-get-prop)
[182.256772] Starting subtest: invalid-get-prop
[182.276668] Subtest invalid-get-prop: SUCCESS (0.000s)
[182.549905] [080/161] (855s left) kms_addfb_basic (invalid-get-prop-any)
[182.809450] Starting subtest: invalid-get-prop-any
[182.831232] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[183.104298] [081/161] (854s left) kms_addfb_basic (invalid-set-prop)
[183.340186] Starting subtest: invalid-set-prop
[183.359700] Subtest invalid-set-prop: SUCCESS (0.000s)
[183.645947] [082/161] (854s left) kms_addfb_basic (invalid-set-prop-any)
[183.902788] Starting subtest: invalid-set-prop-any
[183.924770] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[184.182752] [083/161] (854s left) kms_addfb_basic (no-handle)
[184.427954] Starting subtest: no-handle
[184.447511] Subtest no-handle: SUCCESS (0.000s)
[184.713716] [084/161] (853s left) kms_addfb_basic (size-max)
[185.078682] Starting subtest: size-max
[185.098547] Subtest size-max: SUCCESS (0.000s)
[185.270583] [085/161] (853s left) kms_addfb_basic (small-bo)
[185.620177] Starting subtest: small-bo
[185.643935] Subtest small-bo: SUCCESS (0.000s)
[185.839764] [086/161] (852s left) kms_addfb_basic (tile-pitch-mismatch)
[186.107467] Starting subtest: tile-pitch-mismatch
[186.134604] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[186.378479] [087/161] (852s left) kms_addfb_basic (too-high)
[186.703397] Starting subtest: too-high
[186.727416] Subtest too-high: SUCCESS (0.002s)
[186.912372] [088/161] (852s left) kms_addfb_basic (too-wide)
[187.259331] Starting subtest: too-wide
[187.278908] Subtest too-wide: SUCCESS (0.000s)
[187.466754] [089/161] (851s left) kms_addfb_basic (unused-handle)
[187.704460] Starting subtest: unused-handle
[187.725704] Subtest unused-handle: SUCCESS (0.000s)
[188.022333] [090/161] (851s left) kms_addfb_basic (unused-modifier)
[188.285560] Starting subtest: unused-modifier
[188.306198] Subtest unused-modifier: SUCCESS (0.000s)
[188.564662] [091/161] (851s left) kms_addfb_basic (unused-offsets)
[188.826331] Starting subtest: unused-offsets
[188.848209] Subtest unused-offsets: SUCCESS (0.000s)
[189.114628] [092/161] (850s left) kms_addfb_basic (unused-pitches)
[189.374103] Starting subtest: unused-pitches
[189.395677] Subtest unused-pitches: SUCCESS (0.000s)
[189.668850] [093/161] (850s left) kms_busy (basic)
[190.028955] Starting subtest: basic
[190.050259] Starting dynamic subtest: flip
[190.862065] Dynamic subtest flip: SUCCESS (0.816s)
[190.912130] Starting dynamic subtest: modeset
[192.270107] Dynamic subtest modeset: SUCCESS (1.405s)
[192.291444] Subtest basic: SUCCESS (2.226s)
[192.742829] [094/161] (847s left) kms_prop_blob (basic)
[192.970701] Starting subtest: basic
[192.993878] Subtest basic: SUCCESS (0.000s)
[193.161754] [095/161] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[193.525778] Starting subtest: basic-busy-flip-before-cursor-atomic
[193.567080] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[193.733918] [096/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[194.069507] Starting subtest: basic-busy-flip-before-cursor-legacy
[194.108423] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[194.283174] [097/161] (846s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[194.631469] Starting subtest: basic-flip-after-cursor-atomic
[195.645410] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.994s)
[196.078215] [098/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[196.452163] Starting subtest: basic-flip-after-cursor-legacy
[197.447878] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.999s)
[197.865696] [099/161] (843s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[198.216263] Starting subtest: basic-flip-after-cursor-varying-size
[199.227108] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.998s)
[199.658198] [100/161] (841s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[200.012397] Starting subtest: basic-flip-before-cursor-atomic
[201.016211] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.997s)
[201.475521] [101/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[201.807555] Starting subtest: basic-flip-before-cursor-legacy
[202.824926] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.996s)
[203.242981] [102/161] (838s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[203.594393] Starting subtest: basic-flip-before-cursor-varying-size
[204.585239] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.994s)
[205.024952] [103/161] (836s left) kms_dsc (dsc-basic)
[205.408903] Subtest dsc-basic: SKIP (0.000s)
[205.581489] [104/161] (836s left) kms_flip (basic-flip-vs-dpms)
[205.967541] Starting subtest: basic-flip-vs-dpms
[205.988232] Starting dynamic subtest: A-DP1
[207.192318] Dynamic subtest A-DP1: SUCCESS (1.247s)
[207.238353] Starting dynamic subtest: B-DP1
[208.338807] Dynamic subtest B-DP1: SUCCESS (1.152s)
[208.385328] Starting dynamic subtest: C-DP1
[209.506389] Dynamic subtest C-DP1: SUCCESS (1.152s)
[209.526783] Subtest basic-flip-vs-dpms: SUCCESS (3.561s)
[209.985593] [105/161] (832s left) kms_flip (basic-flip-vs-modeset)
[210.366176] Starting subtest: basic-flip-vs-modeset
[210.388968] Starting dynamic subtest: A-DP1
[211.609390] Dynamic subtest A-DP1: SUCCESS (1.268s)
[211.661838] Starting dynamic subtest: B-DP1
[212.766934] Dynamic subtest B-DP1: SUCCESS (1.159s)
[212.815251] Starting dynamic subtest: C-DP1
[213.914066] Dynamic subtest C-DP1: SUCCESS (1.146s)
[213.944629] Subtest basic-flip-vs-modeset: SUCCESS (3.584s)
[214.376435] [106/161] (827s left) kms_flip (basic-flip-vs-wf_vblank)
[214.765867] Starting subtest: basic-flip-vs-wf_vblank
[214.791260] Starting dynamic subtest: A-DP1
[216.172387] Dynamic subtest A-DP1: SUCCESS (1.441s)
[216.220054] Starting dynamic subtest: B-DP1
[217.569874] Dynamic subtest B-DP1: SUCCESS (1.397s)
[217.617949] Starting dynamic subtest: C-DP1
[218.996765] Dynamic subtest C-DP1: SUCCESS (1.420s)
[219.019115] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.269s)
[219.535958] [107/161] (822s left) kms_flip (basic-plain-flip)
[219.898245] Starting subtest: basic-plain-flip
[219.920042] Starting dynamic subtest: A-DP1
[221.057286] Dynamic subtest A-DP1: SUCCESS (1.173s)
[221.111502] Starting dynamic subtest: B-DP1
[222.200228] Dynamic subtest B-DP1: SUCCESS (1.137s)
[222.248454] Starting dynamic subtest: C-DP1
[223.337235] Dynamic subtest C-DP1: SUCCESS (1.131s)
[223.360115] Subtest basic-plain-flip: SUCCESS (3.453s)
[223.871968] [108/161] (818s left) kms_force_connector_basic (force-connector-state)
[224.169218] Subtest force-connector-state: SKIP (0.000s)
[224.340117] [109/161] (818s left) kms_force_connector_basic (force-edid)
[224.622944] Subtest force-edid: SKIP (0.000s)
[224.792831] [110/161] (818s left) kms_force_connector_basic (force-load-detect)
[225.050636] Subtest force-load-detect: SKIP (0.000s)
[225.221376] [111/161] (817s left) kms_force_connector_basic (prune-stale-modes)
[225.506634] Subtest prune-stale-modes: SKIP (0.000s)
[225.679954] [112/161] (817s left) kms_frontbuffer_tracking (basic)
[226.088678] Starting subtest: basic
[229.863707] Subtest basic: SUCCESS (3.838s)
[230.084308] [113/161] (813s left) kms_hdmi_inject (inject-audio)
[230.378130] Subtest inject-audio: SKIP (0.000s)
[230.547741] [114/161] (813s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[230.884569] Starting subtest: compare-crc-sanitycheck-xr24
[230.903881] Starting dynamic subtest: pipe-A-DP-1
[231.372654] Dynamic subtest pipe-A-DP-1: SUCCESS (0.463s)
[231.420452] Starting dynamic subtest: pipe-B-DP-1
[231.983325] Dynamic subtest pipe-B-DP-1: SUCCESS (0.627s)
[232.033091] Starting dynamic subtest: pipe-C-DP-1
[232.635527] Dynamic subtest pipe-C-DP-1: SUCCESS (0.646s)
[232.655835] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.750s)
[233.087434] [115/161] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[233.417601] Starting subtest: compare-crc-sanitycheck-nv12
[233.439119] Starting dynamic subtest: pipe-A-DP-1
[233.482693] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[233.529983] Starting dynamic subtest: pipe-B-DP-1
[233.574311] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[233.649908] Starting dynamic subtest: pipe-C-DP-1
[233.691753] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[233.735565] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[233.915945] [116/161] (810s left) kms_pipe_crc_basic (hang-read-crc)
[234.249650] Starting subtest: hang-read-crc
[234.270402] Starting dynamic subtest: pipe-A-DP-1
[235.596778] Dynamic subtest pipe-A-DP-1: SUCCESS (1.323s)
[235.655266] Starting dynamic subtest: pipe-B-DP-1
[237.056669] Dynamic subtest pipe-B-DP-1: SUCCESS (1.456s)
[237.122966] Starting dynamic subtest: pipe-C-DP-1
[238.560902] Dynamic subtest pipe-C-DP-1: SUCCESS (1.480s)
[238.586672] Subtest hang-read-crc: SUCCESS (4.274s)
[238.971451] [117/161] (805s left) kms_pipe_crc_basic (nonblocking-crc)
[239.369810] Starting subtest: nonblocking-crc
[239.398069] Starting dynamic subtest: pipe-A-DP-1
[240.005339] Dynamic subtest pipe-A-DP-1: SUCCESS (0.640s)
[240.057142] Starting dynamic subtest: pipe-B-DP-1
[240.808221] Dynamic subtest pipe-B-DP-1: SUCCESS (0.790s)
[240.882847] Starting dynamic subtest: pipe-C-DP-1
[241.626464] Dynamic subtest pipe-C-DP-1: SUCCESS (0.815s)
[241.649699] Subtest nonblocking-crc: SUCCESS (2.259s)
[242.051665] [118/161] (802s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[242.417734] Starting subtest: nonblocking-crc-frame-sequence
[242.439257] Starting dynamic subtest: pipe-A-DP-1
[243.073303] Dynamic subtest pipe-A-DP-1: SUCCESS (0.656s)
[243.122821] Starting dynamic subtest: pipe-B-DP-1
[243.870796] Dynamic subtest pipe-B-DP-1: SUCCESS (0.788s)
[243.941202] Starting dynamic subtest: pipe-C-DP-1
[244.708061] Dynamic subtest pipe-C-DP-1: SUCCESS (0.796s)
[244.728731] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.255s)
[245.098664] [119/161] (799s left) kms_pipe_crc_basic (read-crc)
[245.466634] Starting subtest: read-crc
[245.487294] Starting dynamic subtest: pipe-A-DP-1
[246.047562] Dynamic subtest pipe-A-DP-1: SUCCESS (0.582s)
[246.098685] Starting dynamic subtest: pipe-B-DP-1
[246.775613] Dynamic subtest pipe-B-DP-1: SUCCESS (0.732s)
[246.829461] Starting dynamic subtest: pipe-C-DP-1
[247.503238] Dynamic subtest pipe-C-DP-1: SUCCESS (0.729s)
[247.561638] Subtest read-crc: SUCCESS (2.058s)
[247.942881] [120/161] (796s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[248.289906] Starting subtest: read-crc-frame-sequence
[248.310400] Starting dynamic subtest: pipe-A-DP-1
[248.888678] Dynamic subtest pipe-A-DP-1: SUCCESS (0.578s)
[248.939551] Starting dynamic subtest: pipe-B-DP-1
[249.596812] Dynamic subtest pipe-B-DP-1: SUCCESS (0.717s)
[249.660361] Starting dynamic subtest: pipe-C-DP-1
[250.338826] Dynamic subtest pipe-C-DP-1: SUCCESS (0.733s)
[250.361241] Subtest read-crc-frame-sequence: SUCCESS (2.044s)
[250.767856] [121/161] (794s left) kms_pm_backlight (basic-brightness)
[251.148433] Subtest basic-brightness: SKIP (0.000s)
[251.324758] [122/161] (793s left) kms_pm_rpm (basic-pci-d3-state)
[253.147240] Starting subtest: basic-pci-d3-state
[253.821746] Subtest basic-pci-d3-state: SUCCESS (0.666s)
[254.148380] [123/161] (791s left) kms_pm_rpm (basic-rte)
[254.394663] Starting subtest: basic-rte
[256.579986] Subtest basic-rte: SUCCESS (2.218s)
[257.601362] [124/161] (787s left) kms_psr (psr-primary-page-flip)
[257.977244] Subtest psr-primary-page-flip: SKIP (0.000s)
[258.154498] [125/161] (787s left) kms_psr (psr-cursor-plane-move)
[258.549535] Subtest psr-cursor-plane-move: SKIP (0.000s)
[258.725263] [126/161] (787s left) kms_psr (psr-sprite-plane-onoff)
[259.086945] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[259.265095] [127/161] (786s left) kms_psr (psr-primary-mmap-gtt)
[259.626056] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[259.804768] [128/161] (786s left) kms_setmode (basic-clone-single-crtc)
[260.082990] Starting subtest: basic-clone-single-crtc
[260.150447] Subtest basic-clone-single-crtc: SKIP (0.000s)
[260.324963] [129/161] (785s left) i915_pm_rps (basic-api)
[260.605158] Starting subtest: basic-api
[260.625972] Subtest basic-api: SUCCESS (0.007s)
[260.829364] [130/161] (785s left) prime_self_import (basic-llseek-bad)
[261.085272] Starting subtest: basic-llseek-bad
[261.127460] Subtest basic-llseek-bad: SUCCESS (0.005s)
[261.308172] [131/161] (785s left) prime_self_import (basic-llseek-size)
[261.555388] Starting subtest: basic-llseek-size
[261.597776] Subtest basic-llseek-size: SUCCESS (0.007s)
[261.772583] [132/161] (785s left) prime_self_import (basic-with_fd_dup)
[262.008043] Starting subtest: basic-with_fd_dup
[262.054231] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[262.230774] [133/161] (784s left) prime_self_import (basic-with_one_bo)
[262.458688] Starting subtest: basic-with_one_bo
[262.500615] Subtest basic-with_one_bo: SUCCESS (0.008s)
[262.681734] [134/161] (784s left) prime_self_import (basic-with_one_bo_two_files)
[262.905208] Starting subtest: basic-with_one_bo_two_files
[262.949532] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[263.126774] [135/161] (784s left) prime_self_import (basic-with_two_bos)
[263.374766] Starting subtest: basic-with_two_bos
[263.421534] Subtest basic-with_two_bos: SUCCESS (0.008s)
[263.630923] [136/161] (784s left) prime_vgem (basic-fence-flip)
[263.927817] Starting subtest: basic-fence-flip
[264.560677] Subtest basic-fence-flip: SUCCESS (0.674s)
[264.765193] [137/161] (783s left) prime_vgem (basic-fence-mmap)
[265.054284] Starting subtest: basic-fence-mmap
[265.098577] Subtest basic-fence-mmap: SUCCESS (0.074s)
[265.294789] [138/161] (782s left) prime_vgem (basic-fence-read)
[265.585223] Starting subtest: basic-fence-read
[265.685759] Subtest basic-fence-read: SUCCESS (0.145s)
[265.887521] [139/161] (782s left) prime_vgem (basic-gtt)
[266.159367] Starting subtest: basic-gtt
[266.180173] Subtest basic-gtt: SUCCESS (0.032s)
[266.376984] [140/161] (781s left) prime_vgem (basic-read)
[266.668251] Starting subtest: basic-read
[266.714259] Subtest basic-read: SUCCESS (0.064s)
[266.918218] [141/161] (781s left) prime_vgem (basic-write)
[267.216712] Starting subtest: basic-write
[267.273435] Subtest basic-write: SUCCESS (0.094s)
[267.497488] [142/161] (781s left) vgem_basic (setversion)
[267.779263] Starting subtest: setversion
[267.825377] Subtest setversion: SUCCESS (0.000s)
[268.000380] [143/161] (780s left) vgem_basic (create)
[268.273727] Starting subtest: create
[268.296224] Subtest create: SUCCESS (0.001s)
[268.495925] [144/161] (780s left) vgem_basic (debugfs)
[268.768324] Starting subtest: debugfs
[268.789931] Subtest debugfs: SUCCESS (0.001s)
[268.969530] [145/161] (780s left) vgem_basic (dmabuf-export)
[269.228130] Starting subtest: dmabuf-export
[269.273059] Subtest dmabuf-export: SUCCESS (0.005s)
[269.455497] [146/161] (780s left) vgem_basic (dmabuf-fence)
[269.704359] Starting subtest: dmabuf-fence
[269.728303] Subtest dmabuf-fence: SUCCESS (0.001s)
[269.904952] [147/161] (779s left) vgem_basic (dmabuf-fence-before)
[270.172180] Starting subtest: dmabuf-fence-before
[270.194422] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[270.370721] [148/161] (779s left) vgem_basic (dmabuf-mmap)
[270.641492] Starting subtest: dmabuf-mmap
[270.662538] Subtest dmabuf-mmap: SUCCESS (0.038s)
[270.840228] [149/161] (779s left) vgem_basic (mmap)
[271.086430] Starting subtest: mmap
[271.109787] Subtest mmap: SUCCESS (0.024s)
[271.281857] [150/161] (779s left) vgem_basic (second-client)
[271.537127] Starting subtest: second-client
[271.582691] Subtest second-client: SUCCESS (0.032s)
[271.756633] [151/161] (778s left) vgem_basic (sysfs)
[272.007099] Starting subtest: sysfs
[272.029136] Subtest sysfs: SUCCESS (0.001s)
[272.208181] [152/161] (778s left) core_hotunplug (unbind-rebind)
[272.610092] Starting subtest: unbind-rebind
[277.287583] Subtest unbind-rebind: SUCCESS (4.679s)
[277.463559] [153/161] (773s left) vgem_basic (unload)
[277.728694] Starting subtest: unload
[307.032469] Subtest unload: SUCCESS (29.188s)
[307.206882] [154/161] (743s left) i915_module_load (reload)
[307.457128] Starting subtest: reload
[313.546274] Subtest reload: SUCCESS (6.116s)
[313.796196] [155/161] (737s left) gem_lmem_swapping (basic)
[314.123325] Subtest basic: SKIP (0.000s)
[314.296376] [156/161] (737s left) gem_lmem_swapping (parallel-random-engines)
[314.585582] Subtest parallel-random-engines: SKIP (0.000s)
[314.757979] [157/161] (736s left) gem_lmem_swapping (random-engines)
[315.029596] Subtest random-engines: SKIP (0.000s)
[315.207605] [158/161] (736s left) gem_lmem_swapping (verify-random)
[315.505945] Subtest verify-random: SKIP (0.000s)
[315.678148] [159/161] (736s left) i915_pm_rpm (module-reload)
[317.827101] Starting subtest: module-reload
[332.401408] Subtest module-reload: SUCCESS (14.594s)
[332.535683] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[332.539814] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[332.568616] [160/161] (719s left) i915_selftest (live)
[332.819120] Starting subtest: live
[332.845935] Starting dynamic subtest: sanitycheck
[336.900998] Dynamic subtest sanitycheck: SUCCESS (4.091s)
[336.960719] Starting dynamic subtest: uncore
[340.801141] Dynamic subtest uncore: SUCCESS (3.897s)
[340.844796] Starting dynamic subtest: workarounds
[345.181693] Dynamic subtest workarounds: SUCCESS (4.374s)
[345.223155] Starting dynamic subtest: gt_engines
[349.660934] Dynamic subtest gt_engines: SUCCESS (4.472s)
[349.704816] Starting dynamic subtest: gt_timelines
[373.744820] Dynamic subtest gt_timelines: SUCCESS (24.078s)
[373.804303] Starting dynamic subtest: gt_contexts
[377.995666] Dynamic subtest gt_contexts: SUCCESS (4.249s)
[378.041901] Starting dynamic subtest: gt_lrc
[384.954422] Dynamic subtest gt_lrc: SUCCESS (6.952s)
[384.995853] Starting dynamic subtest: gt_mocs
[389.148558] Dynamic subtest gt_mocs: SUCCESS (4.187s)
[389.190193] Starting dynamic subtest: gt_pm
[402.931849] Dynamic subtest gt_pm: SUCCESS (13.776s)
[402.976911] Starting dynamic subtest: gt_heartbeat
[407.012359] Dynamic subtest gt_heartbeat: SUCCESS (4.080s)
[407.059127] Starting dynamic subtest: gt_tlb
[412.072858] Dynamic subtest gt_tlb: SUCCESS (5.056s)
[412.120466] Starting dynamic subtest: requests
[422.477967] Dynamic subtest requests: SUCCESS (10.399s)
[422.562674] Starting dynamic subtest: migrate
[429.904206] Dynamic subtest migrate: SUCCESS (7.423s)
[429.948704] Starting dynamic subtest: active
[433.940643] Dynamic subtest active: SUCCESS (4.032s)
[433.999396] Starting dynamic subtest: objects
[440.215502] Dynamic subtest objects: SUCCESS (6.270s)
[440.264855] Starting dynamic subtest: mman
[450.058153] Dynamic subtest mman: SUCCESS (9.833s)
[450.142685] Starting dynamic subtest: dmabuf
[454.274199] Dynamic subtest dmabuf: SUCCESS (4.212s)
[454.318820] Starting dynamic subtest: vma
[458.146818] Dynamic subtest vma: SUCCESS (3.872s)
[458.208272] Starting dynamic subtest: coherency
[472.454070] Dynamic subtest coherency: SUCCESS (14.300s)
[472.498405] Starting dynamic subtest: gtt
[483.627873] Dynamic subtest gtt: SUCCESS (11.171s)
[483.669624] Starting dynamic subtest: gem
[489.273868] Dynamic subtest gem: SUCCESS (5.642s)
[489.318211] Starting dynamic subtest: evict
[498.409772] Dynamic subtest evict: SUCCESS (9.129s)
[498.453287] Starting dynamic subtest: hugepages
[509.012179] Dynamic subtest hugepages: SUCCESS (10.599s)
[509.071209] Starting dynamic subtest: gem_contexts
[535.678625] Dynamic subtest gem_contexts: SUCCESS (26.491s)
[535.732350] Starting dynamic subtest: client
[539.536118] Dynamic subtest client: SUCCESS (4.025s)
[539.578246] Starting dynamic subtest: gem_migrate
[543.340718] Dynamic subtest gem_migrate: SUCCESS (3.799s)
[543.424943] Starting dynamic subtest: reset
[558.289627] Dynamic subtest reset: SUCCESS (14.945s)
[558.331272] Starting dynamic subtest: memory_region
[562.254795] Dynamic subtest memory_region: SUCCESS (3.960s)
[562.299258] Starting dynamic subtest: hangcheck
[589.502552] Dynamic subtest hangcheck: SUCCESS (27.241s)
[589.561381] Starting dynamic subtest: execlists
[604.328254] Dynamic subtest execlists: SUCCESS (14.822s)
[604.370901] Starting dynamic subtest: ring_submission
[608.165224] Dynamic subtest ring_submission: SUCCESS (3.831s)
[608.225896] Starting dynamic subtest: perf
[612.422844] Dynamic subtest perf: SUCCESS (4.256s)
[612.482511] Starting dynamic subtest: slpc
[616.333179] Dynamic subtest slpc: SUCCESS (3.904s)
[616.375235] Starting dynamic subtest: guc
[620.384783] Dynamic subtest guc: SUCCESS (4.048s)
[620.443730] Starting dynamic subtest: guc_multi_lrc
[624.479926] Dynamic subtest guc_multi_lrc: SUCCESS (4.089s)
[624.520357] Starting dynamic subtest: guc_hang
[628.227746] Dynamic subtest guc_hang: SUCCESS (3.745s)
[628.289813] Starting dynamic subtest: late_gt_pm
[632.349161] Dynamic subtest late_gt_pm: SUCCESS (4.114s)
[632.369438] Subtest live: SUCCESS (299.538s)
[632.519733] [161/161] (419s left) dmabuf (all-tests)
[632.768988] Starting subtest: all-tests
[632.792042] Starting dynamic subtest: sanitycheck
[632.878513] Dynamic subtest sanitycheck: SUCCESS (0.106s)
[632.920611] Starting dynamic subtest: dma_fence
[633.144847] Dynamic subtest