[65.511253] Initializing watchdogs
[65.511538]   /dev/watchdog0
[65.572899] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[65.604170] [001/162] (960s left) i915_module_load (load)
[65.844084] Starting subtest: load
[70.504211] Subtest load: SUCCESS (4.676s)
[70.729133] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[70.737051] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[70.767128] [002/162] (955s left) core_auth (basic-auth)
[71.327574] Starting subtest: basic-auth
[71.355857] Subtest basic-auth: SUCCESS (0.002s)
[71.601494] [003/162] (955s left) fbdev (eof)
[71.838231] Starting subtest: eof
[71.866043] Subtest eof: SUCCESS (0.002s)
[72.105464] [004/162] (954s left) fbdev (info)
[72.334228] Starting subtest: info
[72.362492] Subtest info: SUCCESS (0.000s)
[72.566155] [005/162] (954s left) fbdev (nullptr)
[72.776848] Starting subtest: nullptr
[72.797732] Subtest nullptr: SUCCESS (0.000s)
[72.997457] [006/162] (954s left) fbdev (read)
[73.200138] Starting subtest: read
[74.680335] Subtest read: SUCCESS (1.490s)
[74.902787] [007/162] (952s left) fbdev (write)
[75.126929] Starting subtest: write
[75.879360] Subtest write: SUCCESS (0.773s)
[76.102737] [008/162] (951s left) gem_basic (bad-close)
[76.333292] Starting subtest: bad-close
[76.382273] Subtest bad-close: SUCCESS (0.000s)
[76.580381] [009/162] (951s left) gem_basic (create-close)
[76.831193] Starting subtest: create-close
[76.874109] Subtest create-close: SUCCESS (0.000s)
[77.075463] [010/162] (950s left) gem_basic (create-fd-close)
[77.325329] Starting subtest: create-fd-close
[77.369939] Subtest create-fd-close: SUCCESS (0.000s)
[77.567940] [011/162] (950s left) gem_busy (busy@all-engines)
[77.815299] Starting subtest: busy
[77.838122] Starting dynamic subtest: all-engines
[77.891284] Dynamic subtest all-engines: SUCCESS (0.086s)
[77.912403] Subtest busy: SUCCESS (0.087s)
[78.410735] [012/162] (950s left) gem_close_race (basic-process)
[78.667860] Starting subtest: basic-process
[78.765882] Subtest basic-process: SUCCESS (0.115s)
[79.099571] [013/162] (949s left) gem_close_race (basic-threads)
[79.347091] Starting subtest: basic-threads
[80.614337] Subtest basic-threads: SUCCESS (1.283s)
[80.950487] [014/162] (947s left) gem_ctx_create (basic)
[81.202743] Starting subtest: basic
[81.224713] Subtest basic: SUCCESS (0.000s)
[81.507106] [015/162] (947s left) gem_ctx_create (basic-files)
[81.730304] Starting subtest: basic-files
[83.746276] Subtest basic-files: SUCCESS (2.034s)
[84.098557] [016/162] (945s left) gem_ctx_exec (basic)
[84.348047] Starting subtest: basic
[84.371819] Subtest basic: SUCCESS (0.011s)
[84.574642] [017/162] (944s left) gem_exec_basic (basic)
[84.807115] Starting subtest: basic
[84.828762] Starting dynamic subtest: rcs0-smem
[84.849999] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[84.901948] Starting dynamic subtest: bcs0-smem
[84.928260] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[84.979902] Starting dynamic subtest: vcs0-smem
[85.003528] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[85.048646] Starting dynamic subtest: vecs0-smem
[85.069971] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[85.091346] Subtest basic: SUCCESS (0.014s)
[85.313144] [018/162] (944s left) gem_exec_create (basic)
[85.565833] Starting subtest: basic
[85.586649] Starting dynamic subtest: smem
[87.628895] Dynamic subtest smem: SUCCESS (2.079s)
[87.652391] Subtest basic: SUCCESS (2.080s)
[87.948825] [019/162] (941s left) gem_exec_fence (basic-busy)
[88.316733] Starting subtest: basic-busy
[88.339482] Starting dynamic subtest: rcs0
[88.381420] Dynamic subtest rcs0: SUCCESS (0.043s)
[88.426954] Starting dynamic subtest: bcs0
[88.472366] Dynamic subtest bcs0: SUCCESS (0.040s)
[88.515541] Starting dynamic subtest: vcs0
[88.562138] Dynamic subtest vcs0: SUCCESS (0.048s)
[88.608250] Starting dynamic subtest: vecs0
[88.656455] Dynamic subtest vecs0: SUCCESS (0.033s)
[88.679372] Subtest basic-busy: SUCCESS (0.166s)
[89.414833] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[89.439407] [020/162] (940s left) gem_exec_fence (basic-wait)
[89.804248] Starting subtest: basic-wait
[89.824753] Starting dynamic subtest: rcs0
[89.848620] Dynamic subtest rcs0: SUCCESS (0.030s)
[89.893380] Starting dynamic subtest: bcs0
[89.915122] Dynamic subtest bcs0: SUCCESS (0.029s)
[89.974290] Starting dynamic subtest: vcs0
[89.998206] Dynamic subtest vcs0: SUCCESS (0.049s)
[90.055130] Starting dynamic subtest: vecs0
[90.076409] Dynamic subtest vecs0: SUCCESS (0.030s)
[90.100317] Subtest basic-wait: SUCCESS (0.141s)
[90.801560] [021/162] (939s left) gem_exec_fence (basic-await)
[91.160979] Starting subtest: basic-await
[91.186208] Starting dynamic subtest: rcs0
[91.292121] Dynamic subtest rcs0: SUCCESS (0.144s)
[91.338285] Starting dynamic subtest: bcs0
[91.439968] Dynamic subtest bcs0: SUCCESS (0.145s)
[91.485311] Starting dynamic subtest: vcs0
[91.591275] Dynamic subtest vcs0: SUCCESS (0.149s)
[91.638380] Starting dynamic subtest: vecs0
[91.733088] Dynamic subtest vecs0: SUCCESS (0.144s)
[91.756745] Subtest basic-await: SUCCESS (0.587s)
[92.600118] [022/162] (937s left) gem_exec_fence (nb-await)
[92.955715] Starting subtest: nb-await
[92.978920] Starting dynamic subtest: rcs0
[93.038453] Dynamic subtest rcs0: SUCCESS (0.087s)
[93.088997] Starting dynamic subtest: bcs0
[93.139079] Dynamic subtest bcs0: SUCCESS (0.100s)
[93.188232] Starting dynamic subtest: vcs0
[93.242280] Dynamic subtest vcs0: SUCCESS (0.100s)
[93.291937] Starting dynamic subtest: vecs0
[93.345369] Dynamic subtest vecs0: SUCCESS (0.101s)
[93.367965] Subtest nb-await: SUCCESS (0.392s)
[94.250966] [023/162] (936s left) gem_exec_gttfill (basic)
[94.499321] Starting subtest: basic
[124.771651] Subtest basic: SUCCESS (30.300s)
[125.136090] [024/162] (905s left) gem_exec_parallel (engines)
[125.492563] Starting subtest: engines
[125.514769] Starting dynamic subtest: basic
[130.092196] Dynamic subtest basic: SUCCESS (4.421s)
[130.142322] Starting dynamic subtest: contexts
[136.168639] Dynamic subtest contexts: SUCCESS (6.095s)
[136.213903] Starting dynamic subtest: fds
[142.029475] Dynamic subtest fds: SUCCESS (5.853s)
[142.080809] Starting dynamic subtest: userptr
[146.385528] Dynamic subtest userptr: SUCCESS (4.340s)
[146.408097] Subtest engines: SUCCESS (20.712s)
[146.628053] [025/162] (884s left) gem_exec_store (basic)
[146.936550] Starting subtest: basic
[146.957023] Subtest basic: SUCCESS (0.084s)
[147.275608] [026/162] (884s left) gem_flink_basic (bad-flink)
[147.526359] Starting subtest: bad-flink
[147.548278] Subtest bad-flink: SUCCESS (0.000s)
[147.766200] [027/162] (883s left) gem_flink_basic (bad-open)
[147.985391] Starting subtest: bad-open
[148.007218] Subtest bad-open: SUCCESS (0.000s)
[148.218485] [028/162] (883s left) gem_flink_basic (basic)
[148.444284] Starting subtest: basic
[148.465520] Subtest basic: SUCCESS (0.000s)
[148.675696] [029/162] (883s left) gem_flink_basic (double-flink)
[148.915753] Starting subtest: double-flink
[148.939063] Subtest double-flink: SUCCESS (0.000s)
[149.150553] [030/162] (883s left) gem_flink_basic (flink-lifetime)
[149.376048] Starting subtest: flink-lifetime
[149.396028] Subtest flink-lifetime: SUCCESS (0.004s)
[149.605976] [031/162] (882s left) gem_huc_copy (huc-copy)
[149.833088] Starting subtest: huc-copy
[149.877556] Subtest huc-copy: SKIP (0.000s)
[150.087606] [032/162] (882s left) gem_linear_blits (basic)
[150.453436] Starting subtest: basic
[150.496969] Subtest basic: SUCCESS (0.037s)
[150.806387] [033/162] (882s left) gem_mmap (basic)
[151.183088] Starting subtest: basic
[151.291844] Subtest basic: SUCCESS (0.001s)
[151.508354] [034/162] (881s left) gem_mmap_gtt (basic)
[151.736764] Starting subtest: basic
[151.757294] Subtest basic: SUCCESS (0.002s)
[151.969660] [035/162] (881s left) gem_render_linear_blits (basic)
[152.213880] Starting subtest: basic
[152.317157] Subtest basic: SUCCESS (0.109s)
[152.527801] [036/162] (881s left) gem_render_tiled_blits (basic)
[152.750240] Starting subtest: basic
[152.877903] Subtest basic: SUCCESS (0.136s)
[153.112374] [037/162] (880s left) gem_ringfill (basic-all)
[153.689776] Starting subtest: basic-all
[154.858143] Subtest basic-all: SUCCESS (1.196s)
[155.124647] [038/162] (878s left) gem_softpin (allocator-basic)
[155.357761] Starting subtest: allocator-basic
[155.660821] Subtest allocator-basic: SUCCESS (0.320s)
[155.941994] [039/162] (878s left) gem_softpin (allocator-basic-reserve)
[156.164899] Starting subtest: allocator-basic-reserve
[156.454775] Subtest allocator-basic-reserve: SUCCESS (0.320s)
[156.763175] [040/162] (877s left) gem_softpin (safe-alignment)
[157.019939] Starting subtest: safe-alignment
[157.047323] Subtest safe-alignment: SUCCESS (0.036s)
[157.344345] [041/162] (877s left) gem_sync (basic-all)
[157.841669] Starting subtest: basic-all
[160.056253] Subtest basic-all: SUCCESS (2.466s)
[160.388971] [042/162] (874s left) gem_sync (basic-each)
[160.830274] Starting subtest: basic-each
[162.909736] Subtest basic-each: SUCCESS (2.323s)
[163.262072] [043/162] (871s left) gem_tiled_blits (basic)
[163.568829] Starting subtest: basic
[163.626535] Subtest basic: SUCCESS (0.059s)
[163.964642] [044/162] (871s left) gem_tiled_fence_blits (basic)
[164.488864] Starting subtest: basic
[164.512069] Subtest basic: SUCCESS (0.045s)
[164.827857] [045/162] (870s left) gem_tiled_pread_basic
[165.677484] [046/162] (870s left) gem_wait (busy@all-engines)
[165.933545] Starting subtest: busy
[165.954405] Starting dynamic subtest: all-engines
[166.469522] Dynamic subtest all-engines: SUCCESS (0.531s)
[166.490103] Subtest busy: SUCCESS (0.532s)
[166.859837] [047/162] (869s left) gem_wait (wait@all-engines)
[167.101280] Starting subtest: wait
[167.124144] Starting dynamic subtest: all-engines
[168.145908] Dynamic subtest all-engines: SUCCESS (1.051s)
[168.168342] Subtest wait: SUCCESS (1.052s)
[168.521631] [048/162] (867s left) i915_getparams_basic (basic-eu-total)
[168.774684] Starting subtest: basic-eu-total
[168.820276] Subtest basic-eu-total: SUCCESS (0.000s)
[169.036525] [049/162] (867s left) i915_getparams_basic (basic-subslice-total)
[169.273530] Starting subtest: basic-subslice-total
[169.318716] Subtest basic-subslice-total: SUCCESS (0.000s)
[169.533277] [050/162] (867s left) i915_hangman (error-state-basic)
[169.738104] Starting subtest: error-state-basic
[169.757847] Subtest error-state-basic: SUCCESS (0.022s)
[170.003872] [051/162] (866s left) i915_pciid
[170.484362] [052/162] (866s left) intel_hwmon (hwmon-read)
[170.729172] Subtest hwmon-read: SKIP (0.000s)
[170.956383] [053/162] (866s left) intel_hwmon (hwmon-write)
[171.214408] Subtest hwmon-write: SKIP (0.000s)
[171.426564] [054/162] (866s left) intel_sysfs_debugfs (i915-debugfs-read-all-entries)
[171.674948] Starting subtest: i915-debugfs-read-all-entries
[171.770471] Subtest i915-debugfs-read-all-entries: SUCCESS (0.134s)
[172.227765] [055/162] (865s left) intel_sysfs_debugfs (i915-sysfs-read-all-entries)
[172.432186] Starting subtest: i915-sysfs-read-all-entries
[172.601756] Subtest i915-sysfs-read-all-entries: SUCCESS (0.172s)
[172.996705] [056/162] (864s left) kms_addfb_basic (addfb25-4-tiled)
[173.346152] Starting subtest: addfb25-4-tiled
[173.371984] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[173.608176] [057/162] (864s left) kms_addfb_basic (addfb25-bad-modifier)
[173.935284] Starting subtest: addfb25-bad-modifier
[173.956770] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[174.185772] [058/162] (864s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[174.527120] Starting subtest: addfb25-framebuffer-vs-set-tiling
[174.547162] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[174.766794] [059/162] (863s left) kms_addfb_basic (addfb25-modifier-no-flag)
[175.089279] Starting subtest: addfb25-modifier-no-flag
[175.111840] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[175.331864] [060/162] (863s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[175.671920] Starting subtest: addfb25-x-tiled-legacy
[175.694152] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[175.919549] [061/162] (863s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[176.237286] Starting subtest: addfb25-x-tiled-mismatch-legacy
[176.260651] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[176.505874] [062/162] (862s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[176.825262] Starting subtest: addfb25-yf-tiled-legacy
[176.847893] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[177.089196] [063/162] (862s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[177.427228] Starting subtest: addfb25-y-tiled-legacy
[177.449816] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[177.664962] [064/162] (862s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[177.966042] Starting subtest: addfb25-y-tiled-small-legacy
[177.986908] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[178.210579] [065/162] (861s left) kms_addfb_basic (bad-pitch-0)
[178.443238] Starting subtest: bad-pitch-0
[178.465317] Subtest bad-pitch-0: SUCCESS (0.000s)
[178.822496] [066/162] (861s left) kms_addfb_basic (bad-pitch-1024)
[179.043466] Starting subtest: bad-pitch-1024
[179.066701] Subtest bad-pitch-1024: SUCCESS (0.000s)
[179.422187] [067/162] (860s left) kms_addfb_basic (bad-pitch-128)
[179.651857] Starting subtest: bad-pitch-128
[179.674271] Subtest bad-pitch-128: SUCCESS (0.000s)
[180.055087] [068/162] (860s left) kms_addfb_basic (bad-pitch-256)
[180.301417] Starting subtest: bad-pitch-256
[180.323053] Subtest bad-pitch-256: SUCCESS (0.000s)
[180.656927] [069/162] (860s left) kms_addfb_basic (bad-pitch-32)
[180.902308] Starting subtest: bad-pitch-32
[180.923387] Subtest bad-pitch-32: SUCCESS (0.000s)
[181.233974] [070/162] (859s left) kms_addfb_basic (bad-pitch-63)
[181.459969] Starting subtest: bad-pitch-63
[181.482694] Subtest bad-pitch-63: SUCCESS (0.000s)
[181.793613] [071/162] (859s left) kms_addfb_basic (bad-pitch-65536)
[182.022201] Starting subtest: bad-pitch-65536
[182.043349] Subtest bad-pitch-65536: SUCCESS (0.001s)
[182.598248] [072/162] (859s left) kms_addfb_basic (bad-pitch-999)
[182.855833] Starting subtest: bad-pitch-999
[182.878705] Subtest bad-pitch-999: SUCCESS (0.000s)
[183.193826] [073/162] (858s left) kms_addfb_basic (basic)
[183.420945] Starting subtest: basic
[183.444105] Subtest basic: SUCCESS (0.000s)
[183.779553] [074/162] (858s left) kms_addfb_basic (basic-x-tiled-legacy)
[184.003868] Starting subtest: basic-x-tiled-legacy
[184.026833] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[184.340140] [075/162] (858s left) kms_addfb_basic (basic-y-tiled-legacy)
[184.585786] Starting subtest: basic-y-tiled-legacy
[184.606796] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[184.914527] [076/162] (857s left) kms_addfb_basic (bo-too-small)
[185.261579] Starting subtest: bo-too-small
[185.282255] Subtest bo-too-small: SUCCESS (0.001s)
[185.503739] [077/162] (857s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[185.822213] Starting subtest: bo-too-small-due-to-tiling
[185.844898] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[186.092926] [078/162] (856s left) kms_addfb_basic (clobberred-modifier)
[186.318703] Starting subtest: clobberred-modifier
[186.341922] Subtest clobberred-modifier: SUCCESS (0.000s)
[186.671610] [079/162] (856s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[186.895793] Starting subtest: framebuffer-vs-set-tiling
[186.918694] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[187.259933] [080/162] (856s left) kms_addfb_basic (invalid-get-prop)
[187.486636] Starting subtest: invalid-get-prop
[187.507662] Subtest invalid-get-prop: SUCCESS (0.000s)
[187.854371] [081/162] (855s left) kms_addfb_basic (invalid-get-prop-any)
[188.102849] Starting subtest: invalid-get-prop-any
[188.123727] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[188.476639] [082/162] (855s left) kms_addfb_basic (invalid-set-prop)
[188.726833] Starting subtest: invalid-set-prop
[188.747719] Subtest invalid-set-prop: SUCCESS (0.000s)
[189.064687] [083/162] (855s left) kms_addfb_basic (invalid-set-prop-any)
[189.295928] Starting subtest: invalid-set-prop-any
[189.318960] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[189.633632] [084/162] (854s left) kms_addfb_basic (no-handle)
[189.861112] Starting subtest: no-handle
[189.881741] Subtest no-handle: SUCCESS (0.000s)
[190.195825] [085/162] (854s left) kms_addfb_basic (size-max)
[190.491842] Starting subtest: size-max
[190.514978] Subtest size-max: SUCCESS (0.000s)
[190.761120] [086/162] (854s left) kms_addfb_basic (small-bo)
[191.057416] Starting subtest: small-bo
[191.080329] Subtest small-bo: SUCCESS (0.000s)
[191.324778] [087/162] (853s left) kms_addfb_basic (tile-pitch-mismatch)
[191.571749] Starting subtest: tile-pitch-mismatch
[191.594737] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[191.905277] [088/162] (853s left) kms_addfb_basic (too-high)
[192.251648] Starting subtest: too-high
[192.275688] Subtest too-high: SUCCESS (0.003s)
[192.517451] [089/162] (853s left) kms_addfb_basic (too-wide)
[192.842602] Starting subtest: too-wide
[192.863829] Subtest too-wide: SUCCESS (0.000s)
[193.108887] [090/162] (852s left) kms_addfb_basic (unused-handle)
[193.358989] Starting subtest: unused-handle
[193.380852] Subtest unused-handle: SUCCESS (0.000s)
[193.676170] [091/162] (852s left) kms_addfb_basic (unused-modifier)
[193.922212] Starting subtest: unused-modifier
[193.944194] Subtest unused-modifier: SUCCESS (0.000s)
[194.252807] [092/162] (851s left) kms_addfb_basic (unused-offsets)
[194.477257] Starting subtest: unused-offsets
[194.499872] Subtest unused-offsets: SUCCESS (0.000s)
[194.868972] [093/162] (851s left) kms_addfb_basic (unused-pitches)
[195.095509] Starting subtest: unused-pitches
[195.118457] Subtest unused-pitches: SUCCESS (0.000s)
[195.445769] [094/162] (851s left) kms_busy (basic)
[195.793446] Starting subtest: basic
[195.815371] Starting dynamic subtest: flip
[196.615279] Dynamic subtest flip: SUCCESS (0.828s)
[196.667113] Starting dynamic subtest: modeset
[198.138754] Dynamic subtest modeset: SUCCESS (1.524s)
[198.161065] Subtest basic: SUCCESS (2.356s)
[198.698868] [095/162] (848s left) kms_prop_blob (basic)
[198.951858] Starting subtest: basic
[198.973854] Subtest basic: SUCCESS (0.000s)
[199.189210] [096/162] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[199.546989] Starting subtest: basic-busy-flip-before-cursor-atomic
[199.590609] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[199.807686] [097/162] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[200.130966] Starting subtest: basic-busy-flip-before-cursor-legacy
[200.176656] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[200.410469] [098/162] (847s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[200.732633] Starting subtest: basic-flip-after-cursor-atomic
[201.754382] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.001s)
[202.253429] [099/162] (845s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[202.603228] Starting subtest: basic-flip-after-cursor-legacy
[203.595789] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.988s)
[204.089385] [100/162] (843s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[204.430124] Starting subtest: basic-flip-after-cursor-varying-size
[205.423970] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.994s)
[205.898553] [101/162] (842s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[206.215052] Starting subtest: basic-flip-before-cursor-atomic
[207.209345] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.989s)
[207.708228] [102/162] (840s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[208.035379] Starting subtest: basic-flip-before-cursor-legacy
[209.031087] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.982s)
[209.513773] [103/162] (839s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[209.832971] Starting subtest: basic-flip-before-cursor-varying-size
[210.838668] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.985s)
[211.323589] [104/162] (837s left) kms_dsc (dsc-basic)
[211.669590] Subtest dsc-basic: SKIP (0.000s)
[211.894293] [105/162] (837s left) kms_flip (basic-flip-vs-dpms)
[212.245841] Starting subtest: basic-flip-vs-dpms
[212.266302] Starting dynamic subtest: A-DP1
[213.529511] Dynamic subtest A-DP1: SUCCESS (1.286s)
[213.580325] Starting dynamic subtest: B-DP1
[214.720329] Dynamic subtest B-DP1: SUCCESS (1.205s)
[214.770425] Starting dynamic subtest: C-DP1
[215.946256] Dynamic subtest C-DP1: SUCCESS (1.207s)
[216.012183] Subtest basic-flip-vs-dpms: SUCCESS (3.710s)
[216.477156] [106/162] (832s left) kms_flip (basic-flip-vs-modeset)
[216.820246] Starting subtest: basic-flip-vs-modeset
[216.842866] Starting dynamic subtest: A-DP1
[218.109831] Dynamic subtest A-DP1: SUCCESS (1.303s)
[218.159050] Starting dynamic subtest: B-DP1
[219.338594] Dynamic subtest B-DP1: SUCCESS (1.224s)
[219.392511] Starting dynamic subtest: C-DP1
[220.526964] Dynamic subtest C-DP1: SUCCESS (1.184s)
[220.550282] Subtest basic-flip-vs-modeset: SUCCESS (3.721s)
[221.034361] [107/162] (828s left) kms_flip (basic-flip-vs-wf_vblank)
[221.402099] Starting subtest: basic-flip-vs-wf_vblank
[221.431190] Starting dynamic subtest: A-DP1
[222.880149] Dynamic subtest A-DP1: SUCCESS (1.469s)
[222.934073] Starting dynamic subtest: B-DP1
[224.307047] Dynamic subtest B-DP1: SUCCESS (1.425s)
[224.372223] Starting dynamic subtest: C-DP1
[225.732298] Dynamic subtest C-DP1: SUCCESS (1.431s)
[225.753960] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.336s)
[226.302955] [108/162] (823s left) kms_flip (basic-plain-flip)
[226.650342] Starting subtest: basic-plain-flip
[226.672134] Starting dynamic subtest: A-DP1
[227.812787] Dynamic subtest A-DP1: SUCCESS (1.179s)
[227.862909] Starting dynamic subtest: B-DP1
[228.958025] Dynamic subtest B-DP1: SUCCESS (1.143s)
[229.012063] Starting dynamic subtest: C-DP1
[230.108941] Dynamic subtest C-DP1: SUCCESS (1.144s)
[230.130943] Subtest basic-plain-flip: SUCCESS (3.478s)
[230.696876] [109/162] (819s left) kms_force_connector_basic (force-connector-state)
[230.972223] Subtest force-connector-state: SKIP (0.000s)
[231.193671] [110/162] (818s left) kms_force_connector_basic (force-edid)
[231.464891] Subtest force-edid: SKIP (0.000s)
[231.689577] [111/162] (818s left) kms_force_connector_basic (force-load-detect)
[231.940297] Subtest force-load-detect: SKIP (0.000s)
[232.156429] [112/162] (818s left) kms_force_connector_basic (prune-stale-modes)
[232.429049] Subtest prune-stale-modes: SKIP (0.000s)
[232.652615] [113/162] (818s left) kms_frontbuffer_tracking (basic)
[233.062745] Starting subtest: basic
[236.804974] Subtest basic: SUCCESS (3.814s)
[237.073944] [114/162] (813s left) kms_hdmi_inject (inject-audio)
[237.345794] Subtest inject-audio: SKIP (0.000s)
[237.575425] [115/162] (813s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[237.898796] Starting subtest: compare-crc-sanitycheck-xr24
[237.919344] Starting dynamic subtest: pipe-A-DP-1
[238.397491] Dynamic subtest pipe-A-DP-1: SUCCESS (0.492s)
[238.451835] Starting dynamic subtest: pipe-B-DP-1
[239.075254] Dynamic subtest pipe-B-DP-1: SUCCESS (0.658s)
[239.145344] Starting dynamic subtest: pipe-C-DP-1
[239.710548] Dynamic subtest pipe-C-DP-1: SUCCESS (0.646s)
[239.733592] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.811s)
[240.217216] [116/162] (811s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[240.535353] Starting subtest: compare-crc-sanitycheck-nv12
[240.555609] Starting dynamic subtest: pipe-A-DP-1
[240.600824] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[240.650687] Starting dynamic subtest: pipe-B-DP-1
[240.695599] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[240.772822] Starting dynamic subtest: pipe-C-DP-1
[240.818338] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[240.863314] Subtest compare-crc-sanitycheck-nv12: SKIP (0.018s)
[241.092944] [117/162] (810s left) kms_pipe_crc_basic (hang-read-crc)
[241.409668] Starting subtest: hang-read-crc
[241.434731] Starting dynamic subtest: pipe-A-DP-1
[242.748933] Dynamic subtest pipe-A-DP-1: SUCCESS (1.330s)
[242.799160] Starting dynamic subtest: pipe-B-DP-1
[244.225460] Dynamic subtest pipe-B-DP-1: SUCCESS (1.475s)
[244.277552] Starting dynamic subtest: pipe-C-DP-1
[245.746357] Dynamic subtest pipe-C-DP-1: SUCCESS (1.497s)
[245.771174] Subtest hang-read-crc: SUCCESS (4.317s)
[246.225739] [118/162] (805s left) kms_pipe_crc_basic (nonblocking-crc)
[246.561343] Starting subtest: nonblocking-crc
[246.583075] Starting dynamic subtest: pipe-A-DP-1
[247.227379] Dynamic subtest pipe-A-DP-1: SUCCESS (0.659s)
[247.294699] Starting dynamic subtest: pipe-B-DP-1
[248.063190] Dynamic subtest pipe-B-DP-1: SUCCESS (0.818s)
[248.115853] Starting dynamic subtest: pipe-C-DP-1
[248.880113] Dynamic subtest pipe-C-DP-1: SUCCESS (0.824s)
[248.903603] Subtest nonblocking-crc: SUCCESS (2.316s)
[249.374562] [119/162] (802s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[249.706250] Starting subtest: nonblocking-crc-frame-sequence
[249.727150] Starting dynamic subtest: pipe-A-DP-1
[250.366918] Dynamic subtest pipe-A-DP-1: SUCCESS (0.654s)
[250.418042] Starting dynamic subtest: pipe-B-DP-1
[251.201921] Dynamic subtest pipe-B-DP-1: SUCCESS (0.822s)
[251.290807] Starting dynamic subtest: pipe-C-DP-1
[252.032808] Dynamic subtest pipe-C-DP-1: SUCCESS (0.832s)
[252.055779] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.323s)
[252.492815] [120/162] (799s left) kms_pipe_crc_basic (read-crc)
[252.811600] Starting subtest: read-crc
[252.834119] Starting dynamic subtest: pipe-A-DP-1
[253.393578] Dynamic subtest pipe-A-DP-1: SUCCESS (0.559s)
[253.446535] Starting dynamic subtest: pipe-B-DP-1
[254.124194] Dynamic subtest pipe-B-DP-1: SUCCESS (0.730s)
[254.180500] Starting dynamic subtest: pipe-C-DP-1
[254.871446] Dynamic subtest pipe-C-DP-1: SUCCESS (0.756s)
[254.897610] Subtest read-crc: SUCCESS (2.061s)
[255.369523] [121/162] (797s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[255.694599] Starting subtest: read-crc-frame-sequence
[255.717084] Starting dynamic subtest: pipe-A-DP-1
[256.285625] Dynamic subtest pipe-A-DP-1: SUCCESS (0.583s)
[256.346579] Starting dynamic subtest: pipe-B-DP-1
[257.104858] Dynamic subtest pipe-B-DP-1: SUCCESS (0.803s)
[257.164321] Starting dynamic subtest: pipe-C-DP-1
[257.986312] Dynamic subtest pipe-C-DP-1: SUCCESS (0.852s)
[258.008630] Subtest read-crc-frame-sequence: SUCCESS (2.255s)
[258.477306] [122/162] (794s left) kms_pm_backlight (basic-brightness)
[258.831495] Subtest basic-brightness: SKIP (0.000s)
[259.101305] [123/162] (793s left) kms_pm_rpm (basic-pci-d3-state)
[260.912163] Starting subtest: basic-pci-d3-state
[261.622312] Subtest basic-pci-d3-state: SUCCESS (0.716s)
[262.017390] [124/162] (791s left) kms_pm_rpm (basic-rte)
[262.252872] Starting subtest: basic-rte
[264.486349] Subtest basic-rte: SUCCESS (2.255s)
[265.536243] [125/162] (787s left) kms_psr (psr-primary-page-flip)
[265.888915] Subtest psr-primary-page-flip: SKIP (0.000s)
[266.110627] [126/162] (787s left) kms_psr (psr-cursor-plane-move)
[266.454478] Subtest psr-cursor-plane-move: SKIP (0.000s)
[266.680382] [127/162] (787s left) kms_psr (psr-sprite-plane-onoff)
[267.022801] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[267.311201] [128/162] (786s left) kms_psr (psr-primary-mmap-gtt)
[267.652467] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[267.920568] [129/162] (786s left) kms_setmode (basic-clone-single-crtc)
[268.176516] Starting subtest: basic-clone-single-crtc
[268.243247] Subtest basic-clone-single-crtc: SKIP (0.000s)
[268.462356] [130/162] (786s left) i915_pm_rps (basic-api)
[268.713751] Starting subtest: basic-api
[268.734839] Subtest basic-api: SUCCESS (0.008s)
[268.983402] [131/162] (785s left) prime_self_import (basic-llseek-bad)
[269.214747] Starting subtest: basic-llseek-bad
[269.259430] Subtest basic-llseek-bad: SUCCESS (0.005s)
[269.477787] [132/162] (785s left) prime_self_import (basic-llseek-size)
[269.684234] Starting subtest: basic-llseek-size
[269.728224] Subtest basic-llseek-size: SUCCESS (0.008s)
[269.952698] [133/162] (785s left) prime_self_import (basic-with_fd_dup)
[270.171493] Starting subtest: basic-with_fd_dup
[270.218404] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[270.447974] [134/162] (785s left) prime_self_import (basic-with_one_bo)
[270.675999] Starting subtest: basic-with_one_bo
[270.725803] Subtest basic-with_one_bo: SUCCESS (0.008s)
[270.947637] [135/162] (784s left) prime_self_import (basic-with_one_bo_two_files)
[271.179053] Starting subtest: basic-with_one_bo_two_files
[271.222786] Subtest basic-with_one_bo_two_files: SUCCESS (0.007s)
[271.447963] [136/162] (784s left) prime_self_import (basic-with_two_bos)
[271.676084] Starting subtest: basic-with_two_bos
[271.726177] Subtest basic-with_two_bos: SUCCESS (0.008s)
[271.945646] [137/162] (784s left) prime_vgem (basic-fence-flip)
[272.217951] Starting subtest: basic-fence-flip
[272.804677] Subtest basic-fence-flip: SUCCESS (0.631s)
[273.047381] [138/162] (783s left) prime_vgem (basic-fence-mmap)
[273.324456] Starting subtest: basic-fence-mmap
[273.346644] Subtest basic-fence-mmap: SUCCESS (0.063s)
[273.587151] [139/162] (783s left) prime_vgem (basic-fence-read)
[273.852334] Starting subtest: basic-fence-read
[273.949560] Subtest basic-fence-read: SUCCESS (0.141s)
[274.194768] [140/162] (782s left) prime_vgem (basic-gtt)
[274.467380] Starting subtest: basic-gtt
[274.489363] Subtest basic-gtt: SUCCESS (0.031s)
[274.775643] [141/162] (782s left) prime_vgem (basic-read)
[275.082728] Starting subtest: basic-read
[275.111382] Subtest basic-read: SUCCESS (0.068s)
[275.396287] [142/162] (782s left) prime_vgem (basic-write)
[275.705292] Starting subtest: basic-write
[275.732529] Subtest basic-write: SUCCESS (0.102s)
[276.028823] [143/162] (781s left) vgem_basic (setversion)
[276.293444] Starting subtest: setversion
[276.348086] Subtest setversion: SUCCESS (0.000s)
[276.622254] [144/162] (781s left) vgem_basic (create)
[276.912137] Starting subtest: create
[276.943417] Subtest create: SUCCESS (0.001s)
[277.172170] [145/162] (781s left) vgem_basic (debugfs)
[277.425660] Starting subtest: debugfs
[277.446819] Subtest debugfs: SUCCESS (0.001s)
[277.669368] [146/162] (780s left) vgem_basic (dmabuf-export)
[277.917160] Starting subtest: dmabuf-export
[277.959949] Subtest dmabuf-export: SUCCESS (0.006s)
[278.229870] [147/162] (780s left) vgem_basic (dmabuf-fence)
[278.458343] Starting subtest: dmabuf-fence
[278.479900] Subtest dmabuf-fence: SUCCESS (0.001s)
[278.710631] [148/162] (780s left) vgem_basic (dmabuf-fence-before)
[278.957111] Starting subtest: dmabuf-fence-before
[278.980084] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[279.206190] [149/162] (780s left) vgem_basic (dmabuf-mmap)
[279.452237] Starting subtest: dmabuf-mmap
[279.474307] Subtest dmabuf-mmap: SUCCESS (0.037s)
[279.699776] [150/162] (779s left) vgem_basic (mmap)
[279.949427] Starting subtest: mmap
[279.970395] Subtest mmap: SUCCESS (0.023s)
[280.191803] [151/162] (779s left) vgem_basic (second-client)
[280.445402] Starting subtest: second-client
[280.487901] Subtest second-client: SUCCESS (0.032s)
[280.709765] [152/162] (779s left) vgem_basic (sysfs)
[280.961361] Starting subtest: sysfs
[280.983487] Subtest sysfs: SUCCESS (0.002s)
[281.209847] [153/162] (778s left) core_hotunplug (unbind-rebind)
[281.582829] Starting subtest: unbind-rebind
[286.239870] Subtest unbind-rebind: SUCCESS (4.644s)
[286.467820] [154/162] (773s left) vgem_basic (unload)
[286.702188] Starting subtest: unload
[316.060329] Subtest unload: SUCCESS (29.206s)
[316.288272] [155/162] (744s left) i915_module_load (reload)
[316.515729] Starting subtest: reload
[322.974454] Subtest reload: SUCCESS (6.495s)
[323.259490] [156/162] (737s left) gem_lmem_swapping (basic)
[323.532210] Subtest basic: SKIP (0.000s)
[323.767708] [157/162] (737s left) gem_lmem_swapping (parallel-random-engines)
[324.012788] Subtest parallel-random-engines: SKIP (0.000s)
[324.228460] [158/162] (737s left) gem_lmem_swapping (random-engines)
[324.478992] Subtest random-engines: SKIP (0.000s)
[324.705930] [159/162] (736s left) gem_lmem_swapping (verify-random)
[324.969601] Subtest verify-random: SKIP (0.000s)
[325.185201] [160/162] (736s left) i915_pm_rpm (module-reload)
[327.299141] Starting subtest: module-reload
[342.894945] Subtest module-reload: SUCCESS (15.595s)
[343.061809] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[343.065551] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[343.091136] [161/162] (719s left) i915_selftest (live)
[343.303912] Starting subtest: live
[343.324803] Starting dynamic subtest: sanitycheck
[347.403211] Dynamic subtest sanitycheck: SUCCESS (4.101s)
[347.448746] Starting dynamic subtest: uncore
[351.619838] Dynamic subtest uncore: SUCCESS (4.211s)
[351.663713] Starting dynamic subtest: workarounds
[356.101283] Dynamic subtest workarounds: SUCCESS (4.477s)
[356.144112] Starting dynamic subtest: gt_engines
[360.865285] Dynamic subtest gt_engines: SUCCESS (4.760s)
[360.908112] Starting dynamic subtest: gt_timelines
[388.795211] Dynamic subtest gt_timelines: SUCCESS (27.925s)
[388.856866] Starting dynamic subtest: gt_contexts
[392.945912] Dynamic subtest gt_contexts: SUCCESS (4.150s)
[392.988917] Starting dynamic subtest: gt_lrc
[400.058706] Dynamic subtest gt_lrc: SUCCESS (7.099s)
[400.113836] Starting dynamic subtest: gt_mocs
[404.025199] Dynamic subtest gt_mocs: SUCCESS (3.967s)
[404.077557] Starting dynamic subtest: gt_pm
[418.147859] Dynamic subtest gt_pm: SUCCESS (14.122s)
[418.212664] Starting dynamic subtest: gt_heartbeat
[422.236522] Dynamic subtest gt_heartbeat: SUCCESS (4.085s)
[422.297776] Starting dynamic subtest: gt_tlb
[427.798933] Dynamic subtest gt_tlb: SUCCESS (5.559s)
[427.863573] Starting dynamic subtest: requests
[438.362214] Dynamic subtest requests: SUCCESS (10.557s)
[438.424252] Starting dynamic subtest: migrate
[448.348766] Dynamic subtest migrate: SUCCESS (9.981s)
[448.393281] Starting dynamic subtest: active
[452.439228] Dynamic subtest active: SUCCESS (4.085s)
[452.501934] Starting dynamic subtest: objects
[458.844509] Dynamic subtest objects: SUCCESS (6.404s)
[458.908402] Starting dynamic subtest: mman
[469.357053] Dynamic subtest mman: SUCCESS (10.509s)
[469.403330] Starting dynamic subtest: dmabuf
[473.684171] Dynamic subtest dmabuf: SUCCESS (4.323s)
[473.749203] Starting dynamic subtest: vma
[477.631274] Dynamic subtest vma: SUCCESS (3.941s)
[477.675187] Starting dynamic subtest: coherency
[492.089043] Dynamic subtest coherency: SUCCESS (14.453s)
[492.134224] Starting dynamic subtest: gtt
[504.004738] Dynamic subtest gtt: SUCCESS (11.913s)
[504.053181] Starting dynamic subtest: gem
[509.817323] Dynamic subtest gem: SUCCESS (5.807s)
[509.862514] Starting dynamic subtest: evict
[519.308066] Dynamic subtest evict: SUCCESS (9.488s)
[519.389599] Starting dynamic subtest: hugepages
[530.121584] Dynamic subtest hugepages: SUCCESS (10.811s)
[530.165936] Starting dynamic subtest: gem_contexts
[558.824806] Dynamic subtest gem_contexts: SUCCESS (28.524s)
[558.875705] Starting dynamic subtest: client
[562.920135] Dynamic subtest client: SUCCESS (4.263s)
[562.986060] Starting dynamic subtest: gem_migrate
[567.042351] Dynamic subtest gem_migrate: SUCCESS (4.117s)
[567.085329] Starting dynamic subtest: reset
[582.269994] Dynamic subtest reset: SUCCESS (15.213s)
[582.332670] Starting dynamic subtest: memory_region
[586.420485] Dynamic subtest memory_region: SUCCESS (4.147s)
[586.471992] Starting dynamic subtest: hangcheck
[616.328204] Dynamic subtest hangcheck: SUCCESS (29.907s)
[616.372864] Starting dynamic subtest: execlists
[631.416064] Dynamic subtest execlists: SUCCESS (15.079s)
[631.476177] Starting dynamic subtest: ring_submission
[635.448860] Dynamic subtest ring_submission: SUCCESS (4.030s)
[635.493765] Starting dynamic subtest: perf
[639.753495] Dynamic subtest perf: SUCCESS (4.303s)
[639.796601] Starting dynamic subtest: slpc
[643.911841] Dynamic subtest slpc: SUCCESS (4.147s)
[643.960689] Starting dynamic subtest: guc
[648.093683] Dynamic subtest guc: SUCCESS (4.182s)
[648.136841] Starting dynamic subtest: guc_multi_lrc
[652.220166] Dynamic subtest guc_multi_lrc: SUCCESS (4.122s)
[652.265717] Starting dynamic subtest: guc_hang
[656.298777] Dynamic subtest guc_hang: SUCCESS (4.074s)
[656.361033] Starting dynamic subtest: late_gt_pm
[660.528486] Dynamic subtest late_gt_pm: SUCCESS (4.224s)
[660.551563] Subtest live: SUCCESS (317.229s)
[660.745202] [162/162] (401s left) dmabuf (all-tests)
[660.977873] Starting subtest: all-t