[52.387678] Initializing watchdogs
[52.387885]   /dev/watchdog0
[52.445103] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[52.480847] [001/161] (960s left) i915_module_load (load)
[53.139870] Starting subtest: load
[57.484382] Subtest load: SUCCESS (4.356s)
[57.622748] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.626562] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[57.677908] [002/161] (955s left) core_auth (basic-auth)
[57.954928] Starting subtest: basic-auth
[57.977205] Subtest basic-auth: SUCCESS (0.002s)
[58.122699] [003/161] (955s left) debugfs_test (read_all_entries)
[58.360349] Starting subtest: read_all_entries
[58.515578] Subtest read_all_entries: SUCCESS (0.164s)
[58.865451] [004/161] (954s left) debugfs_test (basic-hwmon)
[59.152276] Starting subtest: basic-hwmon
[59.192770] Subtest basic-hwmon: SKIP (0.000s)
[59.412699] [005/161] (954s left) debugfs_test (sysfs)
[59.651268] Starting subtest: sysfs
[59.821778] Subtest sysfs: SUCCESS (0.173s)
[60.097500] [006/161] (953s left) fbdev (eof)
[60.348742] Starting subtest: eof
[60.370703] Subtest eof: SUCCESS (0.002s)
[60.528871] [007/161] (953s left) fbdev (info)
[60.744836] Starting subtest: info
[60.764542] Subtest info: SUCCESS (0.000s)
[60.920192] [008/161] (953s left) fbdev (nullptr)
[61.138897] Starting subtest: nullptr
[61.162657] Subtest nullptr: SUCCESS (0.001s)
[61.311487] [009/161] (952s left) fbdev (read)
[61.556085] Starting subtest: read
[63.009798] Subtest read: SUCCESS (1.499s)
[63.164311] [010/161] (951s left) fbdev (write)
[63.388123] Starting subtest: write
[64.151269] Subtest write: SUCCESS (0.780s)
[64.312176] [011/161] (950s left) gem_basic (bad-close)
[64.550401] Starting subtest: bad-close
[64.592972] Subtest bad-close: SUCCESS (0.000s)
[64.744084] [012/161] (949s left) gem_basic (create-close)
[64.985997] Starting subtest: create-close
[65.025968] Subtest create-close: SUCCESS (0.000s)
[65.177976] [013/161] (949s left) gem_basic (create-fd-close)
[65.444317] Starting subtest: create-fd-close
[65.484262] Subtest create-fd-close: SUCCESS (0.000s)
[65.644646] [014/161] (949s left) gem_busy (busy@all-engines)
[65.909312] Starting subtest: busy
[65.931167] Starting dynamic subtest: all-engines
[66.005720] Dynamic subtest all-engines: SUCCESS (0.113s)
[66.026960] Subtest busy: SUCCESS (0.114s)
[66.502167] [015/161] (948s left) gem_close_race (basic-process)
[66.764347] Starting subtest: basic-process
[66.884225] Subtest basic-process: SUCCESS (0.132s)
[67.180263] [016/161] (947s left) gem_close_race (basic-threads)
[67.450112] Starting subtest: basic-threads
[68.676507] Subtest basic-threads: SUCCESS (1.249s)
[69.060567] [017/161] (946s left) gem_ctx_create (basic)
[69.390527] Starting subtest: basic
[69.412520] Subtest basic: SUCCESS (0.000s)
[69.730841] [018/161] (945s left) gem_ctx_create (basic-files)
[70.075766] Starting subtest: basic-files
[72.140921] Subtest basic-files: SUCCESS (2.072s)
[72.442489] [019/161] (943s left) gem_ctx_exec (basic)
[72.687518] Starting subtest: basic
[72.707815] Subtest basic: SUCCESS (0.011s)
[72.865722] [020/161] (942s left) gem_exec_basic (basic)
[73.117065] Starting subtest: basic
[73.138991] Starting dynamic subtest: rcs0-smem
[73.159582] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[73.209249] Starting dynamic subtest: bcs0-smem
[73.232100] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[73.281327] Starting dynamic subtest: vcs0-smem
[73.301597] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[73.363167] Starting dynamic subtest: vecs0-smem
[73.386203] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[73.407987] Subtest basic: SUCCESS (0.014s)
[73.571448] [021/161] (942s left) gem_exec_create (basic)
[73.836080] Starting subtest: basic
[73.858638] Starting dynamic subtest: smem
[75.907591] Dynamic subtest smem: SUCCESS (2.092s)
[75.929145] Subtest basic: SUCCESS (2.093s)
[76.192426] [022/161] (939s left) gem_exec_fence (basic-busy)
[76.603724] Starting subtest: basic-busy
[76.630567] Starting dynamic subtest: rcs0
[76.673291] Dynamic subtest rcs0: SUCCESS (0.037s)
[76.716081] Starting dynamic subtest: bcs0
[76.758468] Dynamic subtest bcs0: SUCCESS (0.036s)
[76.800886] Starting dynamic subtest: vcs0
[76.844847] Dynamic subtest vcs0: SUCCESS (0.031s)
[76.883969] Starting dynamic subtest: vecs0
[76.927354] Dynamic subtest vecs0: SUCCESS (0.048s)
[76.950434] Subtest basic-busy: SUCCESS (0.154s)
[77.607271] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[77.632951] [023/161] (938s left) gem_exec_fence (basic-wait)
[78.027969] Starting subtest: basic-wait
[78.049758] Starting dynamic subtest: rcs0
[78.070517] Dynamic subtest rcs0: SUCCESS (0.035s)
[78.115445] Starting dynamic subtest: bcs0
[78.138898] Dynamic subtest bcs0: SUCCESS (0.037s)
[78.190526] Starting dynamic subtest: vcs0
[78.211493] Dynamic subtest vcs0: SUCCESS (0.033s)
[78.254353] Starting dynamic subtest: vecs0
[78.277240] Dynamic subtest vecs0: SUCCESS (0.034s)
[78.298689] Subtest basic-wait: SUCCESS (0.142s)
[78.995174] [024/161] (937s left) gem_exec_fence (basic-await)
[79.386949] Starting subtest: basic-await
[79.412597] Starting dynamic subtest: rcs0
[79.556921] Dynamic subtest rcs0: SUCCESS (0.171s)
[79.600886] Starting dynamic subtest: bcs0
[79.723898] Dynamic subtest bcs0: SUCCESS (0.167s)
[79.770136] Starting dynamic subtest: vcs0
[79.889673] Dynamic subtest vcs0: SUCCESS (0.165s)
[79.933876] Starting dynamic subtest: vecs0
[80.056742] Dynamic subtest vecs0: SUCCESS (0.166s)
[80.077128] Subtest basic-await: SUCCESS (0.674s)
[80.872211] [025/161] (935s left) gem_exec_fence (nb-await)
[81.257730] Starting subtest: nb-await
[81.278996] Starting dynamic subtest: rcs0
[81.342766] Dynamic subtest rcs0: SUCCESS (0.083s)
[81.401859] Starting dynamic subtest: bcs0
[81.423137] Dynamic subtest bcs0: SUCCESS (0.074s)
[81.470841] Starting dynamic subtest: vcs0
[81.493445] Dynamic subtest vcs0: SUCCESS (0.072s)
[81.548958] Starting dynamic subtest: vecs0
[81.569826] Dynamic subtest vecs0: SUCCESS (0.072s)
[81.591966] Subtest nb-await: SUCCESS (0.305s)
[82.380080] [026/161] (934s left) gem_exec_gttfill (basic)
[82.654165] Starting subtest: basic
[113.077959] Subtest basic: SUCCESS (30.442s)
[113.392216] [027/161] (903s left) gem_exec_parallel (engines)
[113.798566] Starting subtest: engines
[113.818731] Starting dynamic subtest: basic
[118.085238] Dynamic subtest basic: SUCCESS (4.127s)
[118.130940] Starting dynamic subtest: contexts
[123.474674] Dynamic subtest contexts: SUCCESS (5.386s)
[123.523442] Starting dynamic subtest: fds
[129.034522] Dynamic subtest fds: SUCCESS (5.589s)
[129.081383] Starting dynamic subtest: userptr
[133.239069] Dynamic subtest userptr: SUCCESS (4.172s)
[133.261677] Subtest engines: SUCCESS (19.277s)
[133.442112] [028/161] (883s left) gem_exec_store (basic)
[133.782794] Starting subtest: basic
[133.804220] Subtest basic: SUCCESS (0.081s)
[134.099458] [029/161] (883s left) gem_flink_basic (bad-flink)
[134.357109] Starting subtest: bad-flink
[134.378125] Subtest bad-flink: SUCCESS (0.000s)
[134.553554] [030/161] (883s left) gem_flink_basic (bad-open)
[134.819439] Starting subtest: bad-open
[134.840428] Subtest bad-open: SUCCESS (0.000s)
[135.009533] [031/161] (882s left) gem_flink_basic (basic)
[135.257360] Starting subtest: basic
[135.280476] Subtest basic: SUCCESS (0.000s)
[135.448426] [032/161] (882s left) gem_flink_basic (double-flink)
[135.694516] Starting subtest: double-flink
[135.715186] Subtest double-flink: SUCCESS (0.000s)
[135.884843] [033/161] (882s left) gem_flink_basic (flink-lifetime)
[136.148291] Starting subtest: flink-lifetime
[136.169857] Subtest flink-lifetime: SUCCESS (0.003s)
[136.339161] [034/161] (881s left) gem_huc_copy (huc-copy)
[136.608122] Starting subtest: huc-copy
[136.650967] Subtest huc-copy: SKIP (0.000s)
[136.820326] [035/161] (881s left) gem_linear_blits (basic)
[137.244530] Starting subtest: basic
[137.267927] Subtest basic: SUCCESS (0.031s)
[137.545529] [036/161] (881s left) gem_mmap (basic)
[137.972838] Starting subtest: basic
[138.082109] Subtest basic: SUCCESS (0.001s)
[138.251499] [037/161] (880s left) gem_mmap_gtt (basic)
[138.503771] Starting subtest: basic
[138.524959] Subtest basic: SUCCESS (0.002s)
[138.695148] [038/161] (880s left) gem_render_linear_blits (basic)
[138.958857] Starting subtest: basic
[139.064809] Subtest basic: SUCCESS (0.095s)
[139.238146] [039/161] (879s left) gem_render_tiled_blits (basic)
[139.490238] Starting subtest: basic
[139.621104] Subtest basic: SUCCESS (0.135s)
[139.818832] [040/161] (879s left) gem_ringfill (basic-all)
[140.400239] Starting subtest: basic-all
[141.610355] Subtest basic-all: SUCCESS (1.235s)
[141.829281] [041/161] (877s left) gem_softpin (allocator-basic)
[142.109466] Starting subtest: allocator-basic
[142.358309] Subtest allocator-basic: SUCCESS (0.288s)
[142.560941] [042/161] (877s left) gem_softpin (allocator-basic-reserve)
[142.821330] Starting subtest: allocator-basic-reserve
[143.076275] Subtest allocator-basic-reserve: SUCCESS (0.284s)
[143.317590] [043/161] (876s left) gem_softpin (safe-alignment)
[143.630441] Starting subtest: safe-alignment
[143.656975] Subtest safe-alignment: SUCCESS (0.037s)
[143.850718] [044/161] (876s left) gem_sync (basic-all)
[144.273872] Starting subtest: basic-all
[146.578116] Subtest basic-all: SUCCESS (2.314s)
[146.749825] [045/161] (873s left) gem_sync (basic-each)
[147.165971] Starting subtest: basic-each
[149.380808] Subtest basic-each: SUCCESS (2.377s)
[149.676772] [046/161] (870s left) gem_tiled_blits (basic)
[150.019681] Starting subtest: basic
[150.075983] Subtest basic: SUCCESS (0.056s)
[150.359662] [047/161] (870s left) gem_tiled_fence_blits (basic)
[150.878396] Starting subtest: basic
[150.925565] Subtest basic: SUCCESS (0.046s)
[151.211105] [048/161] (869s left) gem_tiled_pread_basic
[152.060874] [049/161] (868s left) gem_wait (busy@all-engines)
[152.331637] Starting subtest: busy
[152.352369] Starting dynamic subtest: all-engines
[152.876049] Dynamic subtest all-engines: SUCCESS (0.535s)
[152.896323] Subtest busy: SUCCESS (0.535s)
[153.248599] [050/161] (867s left) gem_wait (wait@all-engines)
[153.511792] Starting subtest: wait
[153.535594] Starting dynamic subtest: all-engines
[154.558938] Dynamic subtest all-engines: SUCCESS (1.048s)
[154.581987] Subtest wait: SUCCESS (1.049s)
[154.912164] [051/161] (866s left) i915_getparams_basic (basic-eu-total)
[155.159949] Starting subtest: basic-eu-total
[155.201884] Subtest basic-eu-total: SUCCESS (0.000s)
[155.370123] [052/161] (866s left) i915_getparams_basic (basic-subslice-total)
[155.631144] Starting subtest: basic-subslice-total
[155.674825] Subtest basic-subslice-total: SUCCESS (0.000s)
[155.843638] [053/161] (865s left) i915_hangman (error-state-basic)
[156.119095] Starting subtest: error-state-basic
[156.140710] Subtest error-state-basic: SUCCESS (0.020s)
[156.324698] [054/161] (865s left) i915_pciid
[156.759675] [055/161] (865s left) kms_addfb_basic (addfb25-4-tiled)
[157.175165] Starting subtest: addfb25-4-tiled
[157.197437] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[157.373295] [056/161] (864s left) kms_addfb_basic (addfb25-bad-modifier)
[157.730062] Starting subtest: addfb25-bad-modifier
[157.750365] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[157.920586] [057/161] (864s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[158.274266] Starting subtest: addfb25-framebuffer-vs-set-tiling
[158.294588] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[158.482982] [058/161] (864s left) kms_addfb_basic (addfb25-modifier-no-flag)
[158.835670] Starting subtest: addfb25-modifier-no-flag
[158.857955] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[159.028691] [059/161] (863s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[159.349090] Starting subtest: addfb25-x-tiled-legacy
[159.371213] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[159.575374] [060/161] (863s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[159.949537] Starting subtest: addfb25-x-tiled-mismatch-legacy
[159.974918] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[160.159529] [061/161] (862s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[160.534083] Starting subtest: addfb25-yf-tiled-legacy
[160.557771] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[160.741871] [062/161] (862s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[161.109258] Starting subtest: addfb25-y-tiled-legacy
[161.130107] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[161.303588] [063/161] (862s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[161.663325] Starting subtest: addfb25-y-tiled-small-legacy
[161.685358] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[161.860883] [064/161] (861s left) kms_addfb_basic (bad-pitch-0)
[162.104755] Starting subtest: bad-pitch-0
[162.124982] Subtest bad-pitch-0: SUCCESS (0.000s)
[162.409996] [065/161] (861s left) kms_addfb_basic (bad-pitch-1024)
[162.637277] Starting subtest: bad-pitch-1024
[162.659930] Subtest bad-pitch-1024: SUCCESS (0.000s)
[162.949654] [066/161] (860s left) kms_addfb_basic (bad-pitch-128)
[163.200830] Starting subtest: bad-pitch-128
[163.223880] Subtest bad-pitch-128: SUCCESS (0.000s)
[163.504402] [067/161] (860s left) kms_addfb_basic (bad-pitch-256)
[163.752345] Starting subtest: bad-pitch-256
[163.772663] Subtest bad-pitch-256: SUCCESS (0.000s)
[164.063977] [068/161] (860s left) kms_addfb_basic (bad-pitch-32)
[164.333823] Starting subtest: bad-pitch-32
[164.354029] Subtest bad-pitch-32: SUCCESS (0.000s)
[164.633177] [069/161] (859s left) kms_addfb_basic (bad-pitch-63)
[164.905885] Starting subtest: bad-pitch-63
[164.928168] Subtest bad-pitch-63: SUCCESS (0.000s)
[165.194560] [070/161] (859s left) kms_addfb_basic (bad-pitch-65536)
[165.458098] Starting subtest: bad-pitch-65536
[165.480714] Subtest bad-pitch-65536: SUCCESS (0.001s)
[165.742906] [071/161] (859s left) kms_addfb_basic (bad-pitch-999)
[165.968399] Starting subtest: bad-pitch-999
[165.990622] Subtest bad-pitch-999: SUCCESS (0.000s)
[166.280855] [072/161] (858s left) kms_addfb_basic (basic)
[166.523031] Starting subtest: basic
[166.545639] Subtest basic: SUCCESS (0.000s)
[166.797485] [073/161] (858s left) kms_addfb_basic (basic-x-tiled-legacy)
[167.045381] Starting subtest: basic-x-tiled-legacy
[167.067401] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[167.343406] [074/161] (857s left) kms_addfb_basic (basic-y-tiled-legacy)
[167.610748] Starting subtest: basic-y-tiled-legacy
[167.633598] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[167.876414] [075/161] (857s left) kms_addfb_basic (bo-too-small)
[168.247306] Starting subtest: bo-too-small
[168.268223] Subtest bo-too-small: SUCCESS (0.001s)
[168.441540] [076/161] (857s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[168.797542] Starting subtest: bo-too-small-due-to-tiling
[168.817819] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[168.994434] [077/161] (856s left) kms_addfb_basic (clobberred-modifier)
[169.289711] Starting subtest: clobberred-modifier
[169.315317] Subtest clobberred-modifier: SUCCESS (0.000s)
[169.592352] [078/161] (856s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[169.865220] Starting subtest: framebuffer-vs-set-tiling
[169.892772] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[170.158302] [079/161] (855s left) kms_addfb_basic (invalid-get-prop)
[170.404803] Starting subtest: invalid-get-prop
[170.427027] Subtest invalid-get-prop: SUCCESS (0.000s)
[170.714967] [080/161] (855s left) kms_addfb_basic (invalid-get-prop-any)
[170.981381] Starting subtest: invalid-get-prop-any
[171.003606] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[171.267767] [081/161] (855s left) kms_addfb_basic (invalid-set-prop)
[171.538533] Starting subtest: invalid-set-prop
[171.560528] Subtest invalid-set-prop: SUCCESS (0.000s)
[171.810546] [082/161] (854s left) kms_addfb_basic (invalid-set-prop-any)
[172.085803] Starting subtest: invalid-set-prop-any
[172.105944] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[172.379538] [083/161] (854s left) kms_addfb_basic (no-handle)
[172.648445] Starting subtest: no-handle
[172.668642] Subtest no-handle: SUCCESS (0.000s)
[172.935451] [084/161] (854s left) kms_addfb_basic (size-max)
[173.301082] Starting subtest: size-max
[173.322342] Subtest size-max: SUCCESS (0.000s)
[173.499661] [085/161] (853s left) kms_addfb_basic (small-bo)
[173.858229] Starting subtest: small-bo
[173.884220] Subtest small-bo: SUCCESS (0.000s)
[174.068961] [086/161] (853s left) kms_addfb_basic (tile-pitch-mismatch)
[174.334217] Starting subtest: tile-pitch-mismatch
[174.356363] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[174.629942] [087/161] (852s left) kms_addfb_basic (too-high)
[174.972313] Starting subtest: too-high
[174.993323] Subtest too-high: SUCCESS (0.002s)
[175.194702] [088/161] (852s left) kms_addfb_basic (too-wide)
[175.555591] Starting subtest: too-wide
[175.579192] Subtest too-wide: SUCCESS (0.000s)
[175.751788] [089/161] (852s left) kms_addfb_basic (unused-handle)
[175.995636] Starting subtest: unused-handle
[176.016047] Subtest unused-handle: SUCCESS (0.000s)
[176.298479] [090/161] (851s left) kms_addfb_basic (unused-modifier)
[176.569366] Starting subtest: unused-modifier
[176.590098] Subtest unused-modifier: SUCCESS (0.000s)
[176.843886] [091/161] (851s left) kms_addfb_basic (unused-offsets)
[177.112916] Starting subtest: unused-offsets
[177.135920] Subtest unused-offsets: SUCCESS (0.000s)
[177.397496] [092/161] (850s left) kms_addfb_basic (unused-pitches)
[177.666247] Starting subtest: unused-pitches
[177.686565] Subtest unused-pitches: SUCCESS (0.000s)
[177.944114] [093/161] (850s left) kms_busy (basic)
[178.318054] Starting subtest: basic
[178.340053] Starting dynamic subtest: flip
[179.210407] Dynamic subtest flip: SUCCESS (0.880s)
[179.260986] Starting dynamic subtest: modeset
[180.680127] Dynamic subtest modeset: SUCCESS (1.482s)
[180.702585] Subtest basic: SUCCESS (2.366s)
[181.175697] [094/161] (847s left) kms_prop_blob (basic)
[181.441074] Starting subtest: basic
[181.464652] Subtest basic: SUCCESS (0.000s)
[181.636237] [095/161] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[181.971722] Starting subtest: basic-busy-flip-before-cursor-atomic
[182.015601] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[182.191263] [096/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[182.555156] Starting subtest: basic-busy-flip-before-cursor-legacy
[182.598177] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[182.784116] [097/161] (846s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[183.124577] Starting subtest: basic-flip-after-cursor-atomic
[184.107271] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.998s)
[184.560936] [098/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[184.924867] Starting subtest: basic-flip-after-cursor-legacy
[185.900881] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.996s)
[186.358784] [099/161] (843s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[186.695788] Starting subtest: basic-flip-after-cursor-varying-size
[187.692640] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.996s)
[188.143917] [100/161] (841s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[188.506989] Starting subtest: basic-flip-before-cursor-atomic
[189.476753] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.983s)
[189.925398] [101/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[190.289399] Starting subtest: basic-flip-before-cursor-legacy
[191.257673] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.988s)
[191.733316] [102/161] (838s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[192.104218] Starting subtest: basic-flip-before-cursor-varying-size
[193.078917] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.986s)
[193.569081] [103/161] (836s left) kms_dsc (dsc-basic)
[193.973271] Subtest dsc-basic: SKIP (0.000s)
[194.174937] [104/161] (836s left) kms_flip (basic-flip-vs-dpms)
[194.688808] Starting subtest: basic-flip-vs-dpms
[194.712393] Starting dynamic subtest: A-DP1
[195.842238] Dynamic subtest A-DP1: SUCCESS (1.269s)
[195.891863] Starting dynamic subtest: B-DP1
[197.007662] Dynamic subtest B-DP1: SUCCESS (1.161s)
[197.056422] Starting dynamic subtest: C-DP1
[198.201965] Dynamic subtest C-DP1: SUCCESS (1.188s)
[198.224707] Subtest basic-flip-vs-dpms: SUCCESS (3.628s)
[198.688369] [105/161] (831s left) kms_flip (basic-flip-vs-modeset)
[199.089829] Starting subtest: basic-flip-vs-modeset
[199.111372] Starting dynamic subtest: A-DP1
[200.324461] Dynamic subtest A-DP1: SUCCESS (1.269s)
[200.377425] Starting dynamic subtest: B-DP1
[201.499221] Dynamic subtest B-DP1: SUCCESS (1.169s)
[201.548793] Starting dynamic subtest: C-DP1
[202.660258] Dynamic subtest C-DP1: SUCCESS (1.156s)
[202.683521] Subtest basic-flip-vs-modeset: SUCCESS (3.605s)
[203.125099] [106/161] (827s left) kms_flip (basic-flip-vs-wf_vblank)
[203.518310] Starting subtest: basic-flip-vs-wf_vblank
[203.539348] Starting dynamic subtest: A-DP1
[204.935334] Dynamic subtest A-DP1: SUCCESS (1.450s)
[204.983978] Starting dynamic subtest: B-DP1
[206.361816] Dynamic subtest B-DP1: SUCCESS (1.420s)
[206.408876] Starting dynamic subtest: C-DP1
[207.785393] Dynamic subtest C-DP1: SUCCESS (1.422s)
[207.806792] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.304s)
[208.330702] [107/161] (822s left) kms_flip (basic-plain-flip)
[208.725707] Starting subtest: basic-plain-flip
[208.745922] Starting dynamic subtest: A-DP1
[209.861601] Dynamic subtest A-DP1: SUCCESS (1.162s)
[209.911263] Starting dynamic subtest: B-DP1
[211.002499] Dynamic subtest B-DP1: SUCCESS (1.137s)
[211.053982] Starting dynamic subtest: C-DP1
[212.143201] Dynamic subtest C-DP1: SUCCESS (1.134s)
[212.167345] Subtest basic-plain-flip: SUCCESS (3.445s)
[212.677535] [108/161] (818s left) kms_force_connector_basic (force-connector-state)
[212.980649] Subtest force-connector-state: SKIP (0.000s)
[213.154854] [109/161] (818s left) kms_force_connector_basic (force-edid)
[213.422869] Subtest force-edid: SKIP (0.000s)
[213.598716] [110/161] (817s left) kms_force_connector_basic (force-load-detect)
[213.884567] Subtest force-load-detect: SKIP (0.000s)
[214.069735] [111/161] (817s left) kms_force_connector_basic (prune-stale-modes)
[214.356248] Subtest prune-stale-modes: SKIP (0.000s)
[214.530553] [112/161] (817s left) kms_frontbuffer_tracking (basic)
[214.966175] Starting subtest: basic
[218.762105] Subtest basic: SUCCESS (3.861s)
[218.988365] [113/161] (813s left) kms_hdmi_inject (inject-audio)
[219.283875] Subtest inject-audio: SKIP (0.000s)
[219.461842] [114/161] (812s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[219.835287] Starting subtest: compare-crc-sanitycheck-xr24
[219.856212] Starting dynamic subtest: pipe-A-DP-1
[220.291592] Dynamic subtest pipe-A-DP-1: SUCCESS (0.465s)
[220.342176] Starting dynamic subtest: pipe-B-DP-1
[220.925650] Dynamic subtest pipe-B-DP-1: SUCCESS (0.625s)
[220.973491] Starting dynamic subtest: pipe-C-DP-1
[221.576480] Dynamic subtest pipe-C-DP-1: SUCCESS (0.641s)
[221.604489] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.746s)
[222.056913] [115/161] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[222.423629] Starting subtest: compare-crc-sanitycheck-nv12
[222.451863] Starting dynamic subtest: pipe-A-DP-1
[222.512050] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[222.581580] Starting dynamic subtest: pipe-B-DP-1
[222.648866] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[222.726833] Starting dynamic subtest: pipe-C-DP-1
[222.785139] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[222.844034] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[223.050388] [116/161] (809s left) kms_pipe_crc_basic (hang-read-crc)
[223.382254] Starting subtest: hang-read-crc
[223.405420] Starting dynamic subtest: pipe-A-DP-1
[224.725236] Dynamic subtest pipe-A-DP-1: SUCCESS (1.328s)
[224.789535] Starting dynamic subtest: pipe-B-DP-1
[226.220541] Dynamic subtest pipe-B-DP-1: SUCCESS (1.486s)
[226.285956] Starting dynamic subtest: pipe-C-DP-1
[227.715449] Dynamic subtest pipe-C-DP-1: SUCCESS (1.488s)
[227.739884] Subtest hang-read-crc: SUCCESS (4.316s)
[228.145267] [117/161] (804s left) kms_pipe_crc_basic (nonblocking-crc)
[228.493907] Starting subtest: nonblocking-crc
[228.516346] Starting dynamic subtest: pipe-A-DP-1
[229.147122] Dynamic subtest pipe-A-DP-1: SUCCESS (0.630s)
[229.214548] Starting dynamic subtest: pipe-B-DP-1
[229.938339] Dynamic subtest pipe-B-DP-1: SUCCESS (0.788s)
[229.987205] Starting dynamic subtest: pipe-C-DP-1
[230.726506] Dynamic subtest pipe-C-DP-1: SUCCESS (0.797s)
[230.751801] Subtest nonblocking-crc: SUCCESS (2.230s)
[231.174796] [118/161] (801s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[231.544950] Starting subtest: nonblocking-crc-frame-sequence
[231.572253] Starting dynamic subtest: pipe-A-DP-1
[232.192978] Dynamic subtest pipe-A-DP-1: SUCCESS (0.650s)
[232.265230] Starting dynamic subtest: pipe-B-DP-1
[232.994375] Dynamic subtest pipe-B-DP-1: SUCCESS (0.795s)
[233.062073] Starting dynamic subtest: pipe-C-DP-1
[233.816810] Dynamic subtest pipe-C-DP-1: SUCCESS (0.817s)
[233.837018] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.277s)
[234.243515] [119/161] (798s left) kms_pipe_crc_basic (read-crc)
[234.594304] Starting subtest: read-crc
[234.616267] Starting dynamic subtest: pipe-A-DP-1
[235.176119] Dynamic subtest pipe-A-DP-1: SUCCESS (0.576s)
[235.269124] Starting dynamic subtest: pipe-B-DP-1
[235.910243] Dynamic subtest pipe-B-DP-1: SUCCESS (0.729s)
[235.965704] Starting dynamic subtest: pipe-C-DP-1
[236.677828] Dynamic subtest pipe-C-DP-1: SUCCESS (0.731s)
[236.704386] Subtest read-crc: SUCCESS (2.052s)
[237.078373] [120/161] (796s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[237.451758] Starting subtest: read-crc-frame-sequence
[237.472821] Starting dynamic subtest: pipe-A-DP-1
[238.033390] Dynamic subtest pipe-A-DP-1: SUCCESS (0.578s)
[238.081575] Starting dynamic subtest: pipe-B-DP-1
[238.755903] Dynamic subtest pipe-B-DP-1: SUCCESS (0.721s)
[238.804571] Starting dynamic subtest: pipe-C-DP-1
[239.505682] Dynamic subtest pipe-C-DP-1: SUCCESS (0.744s)
[239.543944] Subtest read-crc-frame-sequence: SUCCESS (2.059s)
[239.930546] [121/161] (793s left) kms_pm_backlight (basic-brightness)
[240.318854] Subtest basic-brightness: SKIP (0.000s)
[240.498239] [122/161] (793s left) kms_pm_rpm (basic-pci-d3-state)
[242.368307] Starting subtest: basic-pci-d3-state
[243.026946] Subtest basic-pci-d3-state: SUCCESS (0.679s)
[243.357780] [123/161] (790s left) kms_pm_rpm (basic-rte)
[243.602155] Starting subtest: basic-rte
[245.824152] Subtest basic-rte: SUCCESS (2.248s)
[246.799743] [124/161] (787s left) kms_psr (psr-primary-page-flip)
[247.198583] Subtest psr-primary-page-flip: SKIP (0.000s)
[247.379826] [125/161] (786s left) kms_psr (psr-cursor-plane-move)
[247.765796] Subtest psr-cursor-plane-move: SKIP (0.000s)
[247.945297] [126/161] (786s left) kms_psr (psr-sprite-plane-onoff)
[248.313502] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[248.490740] [127/161] (786s left) kms_psr (psr-primary-mmap-gtt)
[248.880408] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[249.057793] [128/161] (785s left) kms_setmode (basic-clone-single-crtc)
[249.336214] Starting subtest: basic-clone-single-crtc
[249.401935] Subtest basic-clone-single-crtc: SKIP (0.000s)
[249.577298] [129/161] (785s left) i915_pm_rps (basic-api)
[249.852429] Starting subtest: basic-api
[249.873148] Subtest basic-api: SUCCESS (0.007s)
[250.077431] [130/161] (785s left) prime_self_import (basic-llseek-bad)
[250.313191] Starting subtest: basic-llseek-bad
[250.365754] Subtest basic-llseek-bad: SUCCESS (0.005s)
[250.548121] [131/161] (784s left) prime_self_import (basic-llseek-size)
[250.801346] Starting subtest: basic-llseek-size
[250.846548] Subtest basic-llseek-size: SUCCESS (0.008s)
[251.022754] [132/161] (784s left) prime_self_import (basic-with_fd_dup)
[251.266348] Starting subtest: basic-with_fd_dup
[251.311704] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[251.489878] [133/161] (784s left) prime_self_import (basic-with_one_bo)
[251.733561] Starting subtest: basic-with_one_bo
[251.778680] Subtest basic-with_one_bo: SUCCESS (0.008s)
[251.952686] [134/161] (783s left) prime_self_import (basic-with_one_bo_two_files)
[252.197720] Starting subtest: basic-with_one_bo_two_files
[252.240569] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[252.420936] [135/161] (783s left) prime_self_import (basic-with_two_bos)
[252.666546] Starting subtest: basic-with_two_bos
[252.708852] Subtest basic-with_two_bos: SUCCESS (0.007s)
[252.892228] [136/161] (783s left) prime_vgem (basic-fence-flip)
[253.178577] Starting subtest: basic-fence-flip
[253.813146] Subtest basic-fence-flip: SUCCESS (0.672s)
[254.039253] [137/161] (782s left) prime_vgem (basic-fence-mmap)
[254.326037] Starting subtest: basic-fence-mmap
[254.348048] Subtest basic-fence-mmap: SUCCESS (0.073s)
[254.548340] [138/161] (782s left) prime_vgem (basic-fence-read)
[254.837422] Starting subtest: basic-fence-read
[254.935449] Subtest basic-fence-read: SUCCESS (0.144s)
[255.136093] [139/161] (781s left) prime_vgem (basic-gtt)
[255.427806] Starting subtest: basic-gtt
[255.449368] Subtest basic-gtt: SUCCESS (0.031s)
[255.659633] [140/161] (781s left) prime_vgem (basic-read)
[255.936864] Starting subtest: basic-read
[255.982665] Subtest basic-read: SUCCESS (0.067s)
[256.182875] [141/161] (781s left) prime_vgem (basic-write)
[256.457859] Starting subtest: basic-write
[256.508546] Subtest basic-write: SUCCESS (0.096s)
[256.717340] [142/161] (780s left) vgem_basic (setversion)
[256.974897] Starting subtest: setversion
[257.021872] Subtest setversion: SUCCESS (0.000s)
[257.197624] [143/161] (780s left) vgem_basic (create)
[257.446642] Starting subtest: create
[257.468043] Subtest create: SUCCESS (0.001s)
[257.646747] [144/161] (780s left) vgem_basic (debugfs)
[257.900453] Starting subtest: debugfs
[257.923996] Subtest debugfs: SUCCESS (0.001s)
[258.105893] [145/161] (779s left) vgem_basic (dmabuf-export)
[258.373811] Starting subtest: dmabuf-export
[258.418392] Subtest dmabuf-export: SUCCESS (0.012s)
[258.595164] [146/161] (779s left) vgem_basic (dmabuf-fence)
[258.865076] Starting subtest: dmabuf-fence
[258.887360] Subtest dmabuf-fence: SUCCESS (0.002s)
[259.062528] [147/161] (779s left) vgem_basic (dmabuf-fence-before)
[259.332057] Starting subtest: dmabuf-fence-before
[259.353226] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[259.532110] [148/161] (779s left) vgem_basic (dmabuf-mmap)
[259.807698] Starting subtest: dmabuf-mmap
[259.829404] Subtest dmabuf-mmap: SUCCESS (0.039s)
[260.011926] [149/161] (778s left) vgem_basic (mmap)
[260.286804] Starting subtest: mmap
[260.311734] Subtest mmap: SUCCESS (0.024s)
[260.489763] [150/161] (778s left) vgem_basic (second-client)
[260.768580] Starting subtest: second-client
[260.812532] Subtest second-client: SUCCESS (0.032s)
[260.994807] [151/161] (778s left) vgem_basic (sysfs)
[261.260884] Starting subtest: sysfs
[261.285165] Subtest sysfs: SUCCESS (0.001s)
[261.478084] [152/161] (777s left) core_hotunplug (unbind-rebind)
[261.853924] Starting subtest: unbind-rebind
[266.545927] Subtest unbind-rebind: SUCCESS (4.686s)
[266.723799] [153/161] (772s left) vgem_basic (unload)
[266.963535] Starting subtest: unload
[296.309468] Subtest unload: SUCCESS (29.194s)
[296.512850] [154/161] (743s left) i915_module_load (reload)
[296.770218] Starting subtest: reload
[302.751114] Subtest reload: SUCCESS (6.015s)
[303.019570] [155/161] (737s left) gem_lmem_swapping (basic)
[303.327676] Subtest basic: SKIP (0.000s)
[303.507622] [156/161] (736s left) gem_lmem_swapping (parallel-random-engines)
[303.796906] Subtest parallel-random-engines: SKIP (0.000s)
[303.970416] [157/161] (736s left) gem_lmem_swapping (random-engines)
[304.259247] Subtest random-engines: SKIP (0.000s)
[304.434720] [158/161] (736s left) gem_lmem_swapping (verify-random)
[304.724412] Subtest verify-random: SKIP (0.000s)
[304.920181] [159/161] (735s left) i915_pm_rpm (module-reload)
[307.092533] Starting subtest: module-reload
[321.842654] Subtest module-reload: SUCCESS (14.767s)
[321.967185] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[321.970797] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[321.994586] [160/161] (718s left) i915_selftest (live)
[322.227910] Starting subtest: live
[322.248427] Starting dynamic subtest: sanitycheck
[327.383110] Dynamic subtest sanitycheck: SUCCESS (5.159s)
[327.424528] Starting dynamic subtest: uncore
[331.022143] Dynamic subtest uncore: SUCCESS (3.634s)
[331.063144] Starting dynamic subtest: workarounds
[335.184191] Dynamic subtest workarounds: SUCCESS (4.151s)
[335.234767] Starting dynamic subtest: gt_engines
[339.409545] Dynamic subtest gt_engines: SUCCESS (4.224s)
[339.460897] Starting dynamic subtest: gt_timelines
[364.502267] Dynamic subtest gt_timelines: SUCCESS (25.091s)
[364.543608] Starting dynamic subtest: gt_contexts
[368.746086] Dynamic subtest gt_contexts: SUCCESS (4.241s)
[368.787805] Starting dynamic subtest: gt_lrc
[375.588968] Dynamic subtest gt_lrc: SUCCESS (6.840s)
[375.647257] Starting dynamic subtest: gt_mocs
[379.435978] Dynamic subtest gt_mocs: SUCCESS (3.842s)
[379.489886] Starting dynamic subtest: gt_pm
[392.155860] Dynamic subtest gt_pm: SUCCESS (12.713s)
[392.198407] Starting dynamic subtest: gt_heartbeat
[395.894281] Dynamic subtest gt_heartbeat: SUCCESS (3.736s)
[395.935830] Starting dynamic subtest: gt_tlb
[400.419066] Dynamic subtest gt_tlb: SUCCESS (4.512s)
[400.468769] Starting dynamic subtest: requests
[410.850240] Dynamic subtest requests: SUCCESS (10.433s)
[410.893831] Starting dynamic subtest: migrate
[418.245581] Dynamic subtest migrate: SUCCESS (7.394s)
[418.302674] Starting dynamic subtest: active
[422.315617] Dynamic subtest active: SUCCESS (4.062s)
[422.359540] Starting dynamic subtest: objects
[428.706932] Dynamic subtest objects: SUCCESS (6.385s)
[428.750611] Starting dynamic subtest: mman
[438.802822] Dynamic subtest mman: SUCCESS (10.095s)
[438.846851] Starting dynamic subtest: dmabuf
[442.738873] Dynamic subtest dmabuf: SUCCESS (3.931s)
[442.801242] Starting dynamic subtest: vma
[446.764714] Dynamic subtest vma: SUCCESS (4.018s)
[446.808225] Starting dynamic subtest: coherency
[454.508598] Dynamic subtest coherency: SUCCESS (7.741s)
[454.566472] Starting dynamic subtest: gtt
[466.293854] Dynamic subtest gtt: SUCCESS (11.781s)
[466.353841] Starting dynamic subtest: gem
[471.618065] Dynamic subtest gem: SUCCESS (5.319s)
[471.678993] Starting dynamic subtest: evict
[480.665260] Dynamic subtest evict: SUCCESS (9.041s)
[480.725260] Starting dynamic subtest: hugepages
[491.264989] Dynamic subtest hugepages: SUCCESS (10.598s)
[491.324914] Starting dynamic subtest: gem_contexts
[517.896869] Dynamic subtest gem_contexts: SUCCESS (26.453s)
[517.950288] Starting dynamic subtest: client
[521.543650] Dynamic subtest client: SUCCESS (3.816s)
[521.588980] Starting dynamic subtest: gem_migrate
[525.404388] Dynamic subtest gem_migrate: SUCCESS (3.857s)
[525.446055] Starting dynamic subtest: reset
[539.466859] Dynamic subtest reset: SUCCESS (14.052s)
[539.524987] Starting dynamic subtest: memory_region
[543.246189] Dynamic subtest memory_region: SUCCESS (3.779s)
[543.290649] Starting dynamic subtest: hangcheck
[570.339559] Dynamic subtest hangcheck: SUCCESS (27.088s)
[570.381300] Starting dynamic subtest: execlists
[585.107043] Dynamic subtest execlists: SUCCESS (14.764s)
[585.151281] Starting dynamic subtest: ring_submission
[589.077440] Dynamic subtest ring_submission: SUCCESS (3.963s)
[589.160248] Starting dynamic subtest: perf
[593.435201] Dynamic subtest perf: SUCCESS (4.354s)
[593.478666] Starting dynamic subtest: slpc
[597.375424] Dynamic subtest slpc: SUCCESS (3.932s)
[597.435919] Starting dynamic subtest: guc
[601.116682] Dynamic subtest guc: SUCCESS (3.742s)
[601.159614] Starting dynamic subtest: guc_multi_lrc
[604.965700] Dynamic subtest guc_multi_lrc: SUCCESS (3.840s)
[605.016324] Starting dynamic subtest: guc_hang
[608.689268] Dynamic subtest guc_hang: SUCCESS (3.722s)
[608.731499] Starting dynamic subtest: late_gt_pm
[612.718906] Dynamic subtest late_gt_pm: SUCCESS (4.025s)
[612.743036] Subtest live: SUCCESS (290.496s)
[612.895739] [161/161] (428s left) dmabuf (all-tests)
[613.153563] Starting subtest: all-tests
[613.174098] Starting dynamic subtest: sanitycheck
[613.255209] Dynamic subtest sanitycheck: SUCCESS (0.096s)
[613.298412] Starting dynamic subtest: dma_fence
[613.487818] Dynamic subtest