[39.509106] Initializing watchdogs
[39.509233]   /dev/watchdog0
[39.541559] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:1916 Intel Skylake (Gen9) Skylake GT2 [HD Graphics 520]
[39.550093] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[39.557311] [001/162] (960s left) i915_module_load (load)
[39.638082] Starting subtest: load
[41.216915] Subtest load: SUCCESS (1.578s)
[41.269832] [FACT i915_module_load (load)] changed: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 -> card1
[41.273264] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[41.277434] [002/162] (958s left) core_auth (basic-auth)
[41.365415] Starting subtest: basic-auth
[41.366539] Subtest basic-auth: SUCCESS (0.001s)
[41.513551] [003/162] (958s left) core_debugfs (read-all-entries)
[41.630635] Starting subtest: read-all-entries
[41.747489] Subtest read-all-entries: SUCCESS (0.119s)
[41.856875] [004/162] (958s left) core_sysfs (read-all-entries)
[41.925126] Starting subtest: read-all-entries
[41.933157] Subtest read-all-entries: SUCCESS (0.008s)
[42.095298] [005/162] (958s left) fbdev (eof)
[42.184930] Starting subtest: eof
[42.186505] Subtest eof: SUCCESS (0.001s)
[42.289743] [006/162] (958s left) fbdev (info)
[42.353609] Starting subtest: info
[42.354673] Subtest info: SUCCESS (0.000s)
[42.498147] [007/162] (957s left) fbdev (nullptr)
[42.594804] Starting subtest: nullptr
[42.595848] Subtest nullptr: SUCCESS (0.000s)
[42.723825] [008/162] (957s left) fbdev (read)
[42.811848] Starting subtest: read
[43.714384] Subtest read: SUCCESS (0.904s)
[43.802865] [009/162] (956s left) fbdev (write)
[43.876444] Starting subtest: write
[44.353215] Subtest write: SUCCESS (0.468s)
[44.438047] [010/162] (956s left) gem_basic (bad-close)
[44.504261] Starting subtest: bad-close
[44.506278] Subtest bad-close: SUCCESS (0.000s)
[44.615376] [011/162] (956s left) gem_basic (create-close)
[44.694672] Starting subtest: create-close
[44.696540] Subtest create-close: SUCCESS (0.000s)
[44.862011] [012/162] (955s left) gem_basic (create-fd-close)
[44.928532] Starting subtest: create-fd-close
[44.930549] Subtest create-fd-close: SUCCESS (0.000s)
[45.091876] [013/162] (955s left) gem_busy (busy@all-engines)
[45.185628] Starting subtest: busy
[45.186995] Starting dynamic subtest: all-engines
[45.246715] Dynamic subtest all-engines: SUCCESS (0.061s)
[45.248124] Subtest busy: SUCCESS (0.061s)
[45.723382] [014/162] (955s left) gem_close_race (basic-process)
[45.813185] Starting subtest: basic-process
[45.887591] Subtest basic-process: SUCCESS (0.075s)
[46.157300] [015/162] (954s left) gem_close_race (basic-threads)
[46.247899] Starting subtest: basic-threads
[47.468383] Subtest basic-threads: SUCCESS (1.220s)
[47.767285] [016/162] (953s left) gem_ctx_create (basic)
[47.852350] Starting subtest: basic
[47.853217] Subtest basic: SUCCESS (0.000s)
[48.137236] [017/162] (953s left) gem_ctx_create (basic-files)
[48.221114] Starting subtest: basic-files
[50.242141] Subtest basic-files: SUCCESS (2.021s)
[50.520857] [018/162] (950s left) gem_ctx_exec (basic)
[50.595997] Starting subtest: basic
[50.603041] Subtest basic: SUCCESS (0.008s)
[50.770919] [019/162] (950s left) gem_exec_basic (basic)
[50.848464] Starting subtest: basic
[50.849469] Starting dynamic subtest: rcs0-smem
[50.851902] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[50.865373] Starting dynamic subtest: bcs0-smem
[50.866587] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[50.878768] Starting dynamic subtest: vcs0-smem
[50.880036] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[50.892029] Starting dynamic subtest: vecs0-smem
[50.893195] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[50.894435] Subtest basic: SUCCESS (0.009s)
[51.158412] [020/162] (950s left) gem_exec_create (basic)
[51.243441] Starting subtest: basic
[51.244518] Starting dynamic subtest: smem
[53.306441] Dynamic subtest smem: SUCCESS (2.063s)
[53.307434] Subtest basic: SUCCESS (2.063s)
[53.571482] [021/162] (947s left) gem_exec_fence (basic-busy)
[53.794763] Starting subtest: basic-busy
[53.796881] Starting dynamic subtest: rcs0
[53.830574] Dynamic subtest rcs0: SUCCESS (0.036s)
[53.853780] Starting dynamic subtest: bcs0
[53.857352] Dynamic subtest bcs0: SUCCESS (0.026s)
[53.870345] Starting dynamic subtest: vcs0
[53.879301] Dynamic subtest vcs0: SUCCESS (0.022s)
[53.892392] Starting dynamic subtest: vecs0
[53.903261] Dynamic subtest vecs0: SUCCESS (0.024s)
[53.904346] Subtest basic-busy: SUCCESS (0.109s)
[54.706361] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[54.711986] [022/162] (946s left) gem_exec_fence (basic-wait)
[54.952851] Starting subtest: basic-wait
[54.954229] Starting dynamic subtest: rcs0
[54.977344] Dynamic subtest rcs0: SUCCESS (0.024s)
[54.991431] Starting dynamic subtest: bcs0
[55.002316] Dynamic subtest bcs0: SUCCESS (0.025s)
[55.017215] Starting dynamic subtest: vcs0
[55.021223] Dynamic subtest vcs0: SUCCESS (0.019s)
[55.035113] Starting dynamic subtest: vecs0
[55.043420] Dynamic subtest vecs0: SUCCESS (0.022s)
[55.044425] Subtest basic-wait: SUCCESS (0.091s)
[55.778786] [023/162] (945s left) gem_exec_fence (basic-await)
[56.020169] Starting subtest: basic-await
[56.021355] Starting dynamic subtest: rcs0
[56.121621] Dynamic subtest rcs0: SUCCESS (0.101s)
[56.139452] Starting dynamic subtest: bcs0
[56.222237] Dynamic subtest bcs0: SUCCESS (0.100s)
[56.238045] Starting dynamic subtest: vcs0
[56.326519] Dynamic subtest vcs0: SUCCESS (0.103s)
[56.357879] Starting dynamic subtest: vecs0
[56.440898] Dynamic subtest vecs0: SUCCESS (0.114s)
[56.442051] Subtest basic-await: SUCCESS (0.420s)
[57.219659] [024/162] (944s left) gem_exec_fence (nb-await)
[57.457882] Starting subtest: nb-await
[57.459329] Starting dynamic subtest: rcs0
[57.526794] Dynamic subtest rcs0: SUCCESS (0.068s)
[57.551631] Starting dynamic subtest: bcs0
[57.605347] Dynamic subtest bcs0: SUCCESS (0.076s)
[57.657163] Starting dynamic subtest: vcs0
[57.710170] Dynamic subtest vcs0: SUCCESS (0.102s)
[57.770123] Starting dynamic subtest: vecs0
[57.818517] Dynamic subtest vecs0: SUCCESS (0.104s)
[57.821857] Subtest nb-await: SUCCESS (0.355s)
[58.652748] [025/162] (943s left) gem_exec_gttfill (basic)
[58.741293] Starting subtest: basic
[64.110115] Subtest basic: SUCCESS (5.363s)
[64.432688] [026/162] (937s left) gem_exec_parallel (engines)
[64.591241] Starting subtest: engines
[64.592375] Starting dynamic subtest: basic
[67.545828] Dynamic subtest basic: SUCCESS (2.944s)
[67.563673] Starting dynamic subtest: contexts
[72.130777] Dynamic subtest contexts: SUCCESS (4.591s)
[72.146766] Starting dynamic subtest: fds
[81.010631] Dynamic subtest fds: SUCCESS (8.876s)
[81.025379] Starting dynamic subtest: userptr
[83.946381] Dynamic subtest userptr: SUCCESS (2.935s)
[83.947565] Subtest engines: SUCCESS (19.347s)
[84.263859] [027/162] (917s left) gem_exec_store (basic)
[84.351048] Starting subtest: basic
[84.438516] Subtest basic: SUCCESS (0.087s)
[84.716239] [028/162] (917s left) gem_flink_basic (bad-flink)
[84.784429] Starting subtest: bad-flink
[84.785712] Subtest bad-flink: SUCCESS (0.000s)
[84.921563] [029/162] (917s left) gem_flink_basic (bad-open)
[84.987903] Starting subtest: bad-open
[84.989058] Subtest bad-open: SUCCESS (0.000s)
[85.130347] [030/162] (917s left) gem_flink_basic (basic)
[85.200136] Starting subtest: basic
[85.201179] Subtest basic: SUCCESS (0.000s)
[85.384766] [031/162] (916s left) gem_flink_basic (double-flink)
[85.453118] Starting subtest: double-flink
[85.454251] Subtest double-flink: SUCCESS (0.000s)
[85.627527] [032/162] (916s left) gem_flink_basic (flink-lifetime)
[85.694535] Starting subtest: flink-lifetime
[85.697284] Subtest flink-lifetime: SUCCESS (0.003s)
[85.874471] [033/162] (916s left) gem_huc_copy (huc-copy)
[85.952448] Starting subtest: huc-copy
[85.954366] Subtest huc-copy: SKIP (0.000s)
[86.134870] [034/162] (916s left) gem_linear_blits (basic)
[86.347975] Starting subtest: basic
[86.366875] Subtest basic: SUCCESS (0.012s)
[86.567582] [035/162] (916s left) gem_mmap (basic)
[86.708106] Starting subtest: basic
[86.712837] Subtest basic: SUCCESS (0.001s)
[86.868059] [036/162] (915s left) gem_mmap_gtt (basic)
[86.936412] Starting subtest: basic
[86.938384] Subtest basic: SUCCESS (0.002s)
[87.071831] [037/162] (915s left) gem_render_linear_blits (basic)
[87.152946] Starting subtest: basic
[87.203125] Subtest basic: SUCCESS (0.050s)
[87.295740] [038/162] (915s left) gem_render_tiled_blits (basic)
[87.371892] Starting subtest: basic
[87.426510] Subtest basic: SUCCESS (0.055s)
[87.528689] [039/162] (915s left) gem_ringfill (basic-all)
[87.814317] Starting subtest: basic-all
[88.976855] Subtest basic-all: SUCCESS (1.162s)
[89.170093] [040/162] (913s left) gem_softpin (allocator-basic)
[89.246455] Starting subtest: allocator-basic
[89.433905] Subtest allocator-basic: SUCCESS (0.178s)
[89.588789] [041/162] (913s left) gem_softpin (allocator-basic-reserve)
[89.666187] Starting subtest: allocator-basic-reserve
[89.840776] Subtest allocator-basic-reserve: SUCCESS (0.167s)
[90.019376] [042/162] (913s left) gem_softpin (safe-alignment)
[90.095481] Starting subtest: safe-alignment
[90.114231] Subtest safe-alignment: SUCCESS (0.019s)
[90.326482] [043/162] (912s left) gem_sync (basic-all)
[90.417501] Starting subtest: basic-all
[92.649472] Subtest basic-all: SUCCESS (2.232s)
[92.956642] [044/162] (910s left) gem_sync (basic-each)
[93.046386] Starting subtest: basic-each
[95.248378] Subtest basic-each: SUCCESS (2.202s)
[95.631733] [045/162] (907s left) gem_tiled_blits (basic)
[95.789916] Starting subtest: basic
[95.810753] Subtest basic: SUCCESS (0.021s)
[96.013157] [046/162] (907s left) gem_tiled_fence_blits (basic)
[96.250161] Starting subtest: basic
[96.263786] Subtest basic: SUCCESS (0.014s)
[96.462960] [047/162] (907s left) gem_tiled_pread_basic
[96.888837] [048/162] (906s left) gem_wait (busy@all-engines)
[96.982938] Starting subtest: busy
[96.984034] Starting dynamic subtest: all-engines
[97.523400] Dynamic subtest all-engines: SUCCESS (0.540s)
[97.524418] Subtest busy: SUCCESS (0.541s)
[97.833958] [049/162] (906s left) gem_wait (wait@all-engines)
[97.926454] Starting subtest: wait
[97.927565] Starting dynamic subtest: all-engines
[98.972916] Dynamic subtest all-engines: SUCCESS (1.045s)
[98.975211] Subtest wait: SUCCESS (1.046s)
[99.313724] [050/162] (904s left) i915_getparams_basic (basic-eu-total)
[99.382476] Starting subtest: basic-eu-total
[99.384507] Subtest basic-eu-total: SUCCESS (0.000s)
[99.518176] [051/162] (904s left) i915_getparams_basic (basic-subslice-total)
[99.586197] Starting subtest: basic-subslice-total
[99.588197] Subtest basic-subslice-total: SUCCESS (0.000s)
[99.726476] [052/162] (904s left) i915_hangman (error-state-basic)
[99.813467] Starting subtest: error-state-basic
[99.822767] Subtest error-state-basic: SUCCESS (0.009s)
[99.958809] [053/162] (904s left) i915_pciid
[100.177905] [054/162] (904s left) intel_hwmon (hwmon-read)
[100.248411] Subtest hwmon-read: SKIP (0.000s)
[100.337016] [055/162] (903s left) intel_hwmon (hwmon-write)
[100.406016] Subtest hwmon-write: SKIP (0.000s)
[100.485829] [056/162] (903s left) kms_addfb_basic (addfb25-4-tiled)
[100.614397] Starting subtest: addfb25-4-tiled
[100.615477] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[100.792732] [057/162] (903s left) kms_addfb_basic (addfb25-bad-modifier)
[100.917570] Starting subtest: addfb25-bad-modifier
[100.918596] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[101.059852] [058/162] (903s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[101.183192] Starting subtest: addfb25-framebuffer-vs-set-tiling
[101.184246] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[101.366259] [059/162] (903s left) kms_addfb_basic (addfb25-modifier-no-flag)
[101.491353] Starting subtest: addfb25-modifier-no-flag
[101.492295] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[101.684970] [060/162] (903s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[101.809502] Starting subtest: addfb25-x-tiled-legacy
[101.810480] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[101.951268] [061/162] (902s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[102.072607] Starting subtest: addfb25-x-tiled-mismatch-legacy
[102.073639] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[102.239806] [062/162] (902s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[102.363373] Starting subtest: addfb25-yf-tiled-legacy
[102.364276] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[102.531336] [063/162] (902s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[102.654267] Starting subtest: addfb25-y-tiled-legacy
[102.655156] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[102.820654] [064/162] (902s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[102.940270] Starting subtest: addfb25-y-tiled-small-legacy
[102.941324] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[103.099141] [065/162] (902s left) kms_addfb_basic (bad-pitch-0)
[103.169585] Starting subtest: bad-pitch-0
[103.170593] Subtest bad-pitch-0: SUCCESS (0.000s)
[103.369025] [066/162] (901s left) kms_addfb_basic (bad-pitch-1024)
[103.436630] Starting subtest: bad-pitch-1024
[103.437889] Subtest bad-pitch-1024: SUCCESS (0.000s)
[103.666298] [067/162] (901s left) kms_addfb_basic (bad-pitch-128)
[103.734122] Starting subtest: bad-pitch-128
[103.735186] Subtest bad-pitch-128: SUCCESS (0.000s)
[103.979769] [068/162] (901s left) kms_addfb_basic (bad-pitch-256)
[104.048513] Starting subtest: bad-pitch-256
[104.049389] Subtest bad-pitch-256: SUCCESS (0.000s)
[104.269998] [069/162] (901s left) kms_addfb_basic (bad-pitch-32)
[104.338916] Starting subtest: bad-pitch-32
[104.340013] Subtest bad-pitch-32: SUCCESS (0.000s)
[104.552416] [070/162] (901s left) kms_addfb_basic (bad-pitch-63)
[104.620558] Starting subtest: bad-pitch-63
[104.621859] Subtest bad-pitch-63: SUCCESS (0.000s)
[104.847951] [071/162] (900s left) kms_addfb_basic (bad-pitch-65536)
[104.916111] Starting subtest: bad-pitch-65536
[104.917183] Subtest bad-pitch-65536: SUCCESS (0.000s)
[105.154629] [072/162] (900s left) kms_addfb_basic (bad-pitch-999)
[105.222752] Starting subtest: bad-pitch-999
[105.223923] Subtest bad-pitch-999: SUCCESS (0.000s)
[105.423165] [073/162] (900s left) kms_addfb_basic (basic)
[105.492493] Starting subtest: basic
[105.493585] Subtest basic: SUCCESS (0.000s)
[105.724252] [074/162] (900s left) kms_addfb_basic (basic-x-tiled-legacy)
[105.793402] Starting subtest: basic-x-tiled-legacy
[105.794532] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[106.037842] [075/162] (899s left) kms_addfb_basic (basic-y-tiled-legacy)
[106.107806] Starting subtest: basic-y-tiled-legacy
[106.108914] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[106.325323] [076/162] (899s left) kms_addfb_basic (bo-too-small)
[106.444986] Starting subtest: bo-too-small
[106.446126] Subtest bo-too-small: SUCCESS (0.000s)
[106.598653] [077/162] (899s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[106.720429] Starting subtest: bo-too-small-due-to-tiling
[106.721462] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[106.882680] [078/162] (899s left) kms_addfb_basic (clobberred-modifier)
[106.950299] Starting subtest: clobberred-modifier
[106.951210] Subtest clobberred-modifier: SUCCESS (0.000s)
[107.160890] [079/162] (899s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[107.229266] Starting subtest: framebuffer-vs-set-tiling
[107.230491] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[107.460264] [080/162] (898s left) kms_addfb_basic (invalid-get-prop)
[107.528612] Starting subtest: invalid-get-prop
[107.529895] Subtest invalid-get-prop: SUCCESS (0.000s)
[107.732169] [081/162] (898s left) kms_addfb_basic (invalid-get-prop-any)
[107.802716] Starting subtest: invalid-get-prop-any
[107.803731] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[108.027131] [082/162] (898s left) kms_addfb_basic (invalid-set-prop)
[108.096453] Starting subtest: invalid-set-prop
[108.097532] Subtest invalid-set-prop: SUCCESS (0.000s)
[108.330296] [083/162] (898s left) kms_addfb_basic (invalid-set-prop-any)
[108.399690] Starting subtest: invalid-set-prop-any
[108.400857] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[108.631904] [084/162] (898s left) kms_addfb_basic (no-handle)
[108.700599] Starting subtest: no-handle
[108.701809] Subtest no-handle: SUCCESS (0.000s)
[108.928790] [085/162] (897s left) kms_addfb_basic (size-max)
[109.054253] Starting subtest: size-max
[109.055180] Subtest size-max: SUCCESS (0.000s)
[109.201866] [086/162] (897s left) kms_addfb_basic (small-bo)
[109.330363] Starting subtest: small-bo
[109.331251] Subtest small-bo: SUCCESS (0.000s)
[109.501689] [087/162] (897s left) kms_addfb_basic (tile-pitch-mismatch)
[109.570390] Starting subtest: tile-pitch-mismatch
[109.571375] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[109.794903] [088/162] (897s left) kms_addfb_basic (too-high)
[109.914266] Starting subtest: too-high
[109.915357] Subtest too-high: SUCCESS (0.001s)
[110.065489] [089/162] (897s left) kms_addfb_basic (too-wide)
[110.189273] Starting subtest: too-wide
[110.190277] Subtest too-wide: SUCCESS (0.000s)
[110.371128] [090/162] (896s left) kms_addfb_basic (unused-handle)
[110.439507] Starting subtest: unused-handle
[110.440506] Subtest unused-handle: SUCCESS (0.000s)
[110.685211] [091/162] (896s left) kms_addfb_basic (unused-modifier)
[110.753381] Starting subtest: unused-modifier
[110.754366] Subtest unused-modifier: SUCCESS (0.000s)
[110.961993] [092/162] (896s left) kms_addfb_basic (unused-offsets)
[111.029657] Starting subtest: unused-offsets
[111.030721] Subtest unused-offsets: SUCCESS (0.000s)
[111.264584] [093/162] (896s left) kms_addfb_basic (unused-pitches)
[111.332834] Starting subtest: unused-pitches
[111.334216] Subtest unused-pitches: SUCCESS (0.000s)
[111.582644] [094/162] (895s left) kms_busy (basic)
[111.713305] Starting subtest: basic
[111.714319] Starting dynamic subtest: flip
[112.571175] Dynamic subtest flip: SUCCESS (0.855s)
[112.628649] Starting dynamic subtest: modeset
[116.268621] Dynamic subtest modeset: SUCCESS (3.695s)
[116.271280] Subtest basic: SUCCESS (4.555s)
[117.217721] [095/162] (890s left) kms_prop_blob (basic)
[117.287157] Starting subtest: basic
[117.288276] Subtest basic: SUCCESS (0.000s)
[117.415854] [096/162] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[117.537366] Starting subtest: basic-busy-flip-before-cursor-atomic
[117.539477] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[117.675831] [097/162] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[117.797366] Starting subtest: basic-busy-flip-before-cursor-legacy
[117.799122] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[117.939188] [098/162] (889s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[118.060156] Starting subtest: basic-flip-after-cursor-atomic
[119.376790] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.312s)
[120.316282] [099/162] (887s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[120.440076] Starting subtest: basic-flip-after-cursor-legacy
[121.729724] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.286s)
[122.681525] [100/162] (885s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[122.806046] Starting subtest: basic-flip-after-cursor-varying-size
[124.086346] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.277s)
[124.954717] [101/162] (883s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[125.075222] Starting subtest: basic-flip-before-cursor-atomic
[126.410708] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.332s)
[127.316438] [102/162] (880s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[127.449304] Starting subtest: basic-flip-before-cursor-legacy
[128.797506] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.344s)
[129.750415] [103/162] (878s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[129.870217] Starting subtest: basic-flip-before-cursor-varying-size
[131.149746] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.275s)
[132.090540] [104/162] (876s left) kms_dsc (dsc-basic)
[132.211949] Subtest dsc-basic: SKIP (0.000s)
[132.296566] [105/162] (876s left) kms_flip (basic-flip-vs-dpms)
[132.422452] Starting subtest: basic-flip-vs-dpms
[132.426146] Starting dynamic subtest: A-eDP1
[135.064734] Dynamic subtest A-eDP1: SUCCESS (2.637s)
[135.129853] Starting dynamic subtest: B-eDP1
[137.351269] Dynamic subtest B-eDP1: SUCCESS (2.276s)
[137.414127] Starting dynamic subtest: C-eDP1
[139.627277] Dynamic subtest C-eDP1: SUCCESS (2.273s)
[139.638713] Subtest basic-flip-vs-dpms: SUCCESS (7.215s)
[140.611491] [106/162] (868s left) kms_flip (basic-flip-vs-modeset)
[140.734905] Starting subtest: basic-flip-vs-modeset
[140.737138] Starting dynamic subtest: A-eDP1
[143.431083] Dynamic subtest A-eDP1: SUCCESS (2.691s)
[143.483333] Starting dynamic subtest: B-eDP1
[145.772673] Dynamic subtest B-eDP1: SUCCESS (2.337s)
[145.841241] Starting dynamic subtest: C-eDP1
[148.067847] Dynamic subtest C-eDP1: SUCCESS (2.289s)
[148.080627] Subtest basic-flip-vs-modeset: SUCCESS (7.345s)
[149.038545] [107/162] (859s left) kms_flip (basic-flip-vs-wf_vblank)
[149.161822] Starting subtest: basic-flip-vs-wf_vblank
[149.164350] Starting dynamic subtest: A-eDP1
[151.308735] Dynamic subtest A-eDP1: SUCCESS (2.144s)
[151.328196] Starting dynamic subtest: B-eDP1
[153.497797] Dynamic subtest B-eDP1: SUCCESS (2.187s)
[153.516374] Starting dynamic subtest: C-eDP1
[155.625386] Dynamic subtest C-eDP1: SUCCESS (2.126s)
[155.628490] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.468s)
[156.856337] [108/162] (851s left) kms_flip (basic-plain-flip)
[156.978700] Starting subtest: basic-plain-flip
[156.981153] Starting dynamic subtest: A-eDP1
[158.853501] Dynamic subtest A-eDP1: SUCCESS (1.866s)
[158.911083] Starting dynamic subtest: B-eDP1
[160.837755] Dynamic subtest B-eDP1: SUCCESS (1.978s)
[160.905672] Starting dynamic subtest: C-eDP1
[162.746922] Dynamic subtest C-eDP1: SUCCESS (1.900s)
[162.757218] Subtest basic-plain-flip: SUCCESS (5.778s)
[164.199606] [109/162] (844s left) kms_force_connector_basic (force-connector-state)
[164.318834] Starting subtest: force-connector-state
[164.954540] Subtest force-connector-state: SUCCESS (0.633s)
[166.243138] [110/162] (842s left) kms_force_connector_basic (force-edid)
[166.342917] Starting subtest: force-edid
[166.379451] Subtest force-edid: SUCCESS (0.037s)
[166.634470] [111/162] (842s left) kms_force_connector_basic (force-load-detect)
[166.728594] Starting subtest: force-load-detect
[166.730678] Subtest force-load-detect: SKIP (0.000s)
[166.909348] [112/162] (842s left) kms_force_connector_basic (prune-stale-modes)
[167.006180] Starting subtest: prune-stale-modes
[167.040195] Subtest prune-stale-modes: SUCCESS (0.036s)
[167.252022] [113/162] (841s left) kms_frontbuffer_tracking (basic)
[167.376494] Starting subtest: basic
[170.834455] Subtest basic: SUCCESS (3.458s)
[171.015401] [114/162] (838s left) kms_hdmi_inject (inject-audio)
[171.424694] Starting subtest: inject-audio
[171.564708] Subtest inject-audio: SUCCESS (0.139s)
[172.378117] [115/162] (837s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[172.506572] Starting subtest: compare-crc-sanitycheck-xr24
[172.509168] Starting dynamic subtest: pipe-A-eDP-1
[173.253397] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.738s)
[173.325073] Starting dynamic subtest: pipe-B-eDP-1
[174.726943] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.463s)
[174.800851] Starting dynamic subtest: pipe-C-eDP-1
[176.150846] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.409s)
[176.153706] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.646s)
[177.091027] [116/162] (832s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[177.212776] Starting subtest: compare-crc-sanitycheck-nv12
[177.215352] Starting dynamic subtest: pipe-A-eDP-1
[177.217129] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[177.234109] Starting dynamic subtest: pipe-B-eDP-1
[177.235929] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[177.250621] Starting dynamic subtest: pipe-C-eDP-1
[177.252411] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[177.254245] Subtest compare-crc-sanitycheck-nv12: SKIP (0.014s)
[177.346318] [117/162] (832s left) kms_pipe_crc_basic (hang-read-crc)
[177.468262] Starting subtest: hang-read-crc
[177.471237] Starting dynamic subtest: pipe-A-eDP-1
[180.054391] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.582s)
[180.121233] Starting dynamic subtest: pipe-B-eDP-1
[183.243304] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.172s)
[183.319962] Starting dynamic subtest: pipe-C-eDP-1
[186.402764] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.137s)
[186.412396] Subtest hang-read-crc: SUCCESS (8.929s)
[187.341486] [118/162] (822s left) kms_pipe_crc_basic (nonblocking-crc)
[187.481025] Starting subtest: nonblocking-crc
[187.483666] Starting dynamic subtest: pipe-A-eDP-1
[188.445530] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.959s)
[188.521828] Starting dynamic subtest: pipe-B-eDP-1
[190.180486] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.715s)
[190.258390] Starting dynamic subtest: pipe-C-eDP-1
[191.915507] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.720s)
[191.918112] Subtest nonblocking-crc: SUCCESS (4.433s)
[192.842030] [119/162] (817s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[192.966506] Starting subtest: nonblocking-crc-frame-sequence
[192.967874] Starting dynamic subtest: pipe-A-eDP-1
[193.970583] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.996s)
[194.056962] Starting dynamic subtest: pipe-B-eDP-1
[195.692339] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.707s)
[195.773493] Starting dynamic subtest: pipe-C-eDP-1
[197.425644] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.722s)
[197.428623] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.461s)
[198.376357] [120/162] (811s left) kms_pipe_crc_basic (read-crc)
[198.499935] Starting subtest: read-crc
[198.501150] Starting dynamic subtest: pipe-A-eDP-1
[199.402605] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.894s)
[199.483462] Starting dynamic subtest: pipe-B-eDP-1
[200.956090] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.532s)
[201.033441] Starting dynamic subtest: pipe-C-eDP-1
[202.518076] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.552s)
[202.520948] Subtest read-crc: SUCCESS (4.021s)
[203.501526] [121/162] (806s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[203.624012] Starting subtest: read-crc-frame-sequence
[203.627764] Starting dynamic subtest: pipe-A-eDP-1
[204.546570] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.911s)
[204.624177] Starting dynamic subtest: pipe-B-eDP-1
[206.250368] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.690s)
[206.331553] Starting dynamic subtest: pipe-C-eDP-1
[207.896104] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.620s)
[207.898589] Subtest read-crc-frame-sequence: SUCCESS (4.270s)
[208.825596] [122/162] (801s left) kms_pm_backlight (basic-brightness)
[208.944188] Starting subtest: basic-brightness
[209.019063] Starting dynamic subtest: eDP-1
[209.640496] Dynamic subtest eDP-1: SUCCESS (0.621s)
[209.653690] Subtest basic-brightness: SUCCESS (0.699s)
[210.590570] [123/162] (799s left) kms_pm_rpm (basic-pci-d3-state)
[212.284829] Starting subtest: basic-pci-d3-state
[212.899575] Subtest basic-pci-d3-state: SUCCESS (0.611s)
[213.092189] [124/162] (797s left) kms_pm_rpm (basic-rte)
[213.159452] Starting subtest: basic-rte
[215.405475] Subtest basic-rte: SUCCESS (2.241s)
[216.812104] [125/162] (793s left) kms_psr (psr-primary-page-flip)
[216.936549] Starting subtest: psr-primary-page-flip
[216.937514] Starting dynamic subtest: eDP-1
[218.967052] Dynamic subtest eDP-1: SUCCESS (2.021s)
[218.969595] Subtest psr-primary-page-flip: SUCCESS (2.028s)
[219.864893] [126/162] (790s left) kms_psr (psr-cursor-plane-move)
[219.993900] Starting subtest: psr-cursor-plane-move
[219.994864] Starting dynamic subtest: eDP-1
[222.071311] Dynamic subtest eDP-1: SUCCESS (2.068s)
[222.074269] Subtest psr-cursor-plane-move: SUCCESS (2.075s)
[223.007874] [127/162] (787s left) kms_psr (psr-sprite-plane-onoff)
[223.135428] Starting subtest: psr-sprite-plane-onoff
[223.138261] Starting dynamic subtest: eDP-1
[225.132518] Dynamic subtest eDP-1: SUCCESS (1.989s)
[225.134695] Subtest psr-sprite-plane-onoff: SUCCESS (1.998s)
[226.144817] [128/162] (784s left) kms_psr (psr-primary-mmap-gtt)
[226.275022] Starting subtest: psr-primary-mmap-gtt
[226.276030] Starting dynamic subtest: eDP-1
[228.226801] Dynamic subtest eDP-1: SUCCESS (1.946s)
[228.229899] Subtest psr-primary-mmap-gtt: SUCCESS (1.955s)
[229.105123] [129/162] (781s left) kms_setmode (basic-clone-single-crtc)
[229.175768] Starting subtest: basic-clone-single-crtc
[229.178807] Subtest basic-clone-single-crtc: SKIP (0.000s)
[229.306346] [130/162] (781s left) i915_pm_rps (basic-api)
[229.389418] Starting subtest: basic-api
[229.395063] Subtest basic-api: SUCCESS (0.006s)
[229.571854] [131/162] (781s left) prime_self_import (basic-llseek-bad)
[229.641185] Starting subtest: basic-llseek-bad
[229.646112] Subtest basic-llseek-bad: SUCCESS (0.003s)
[229.818171] [132/162] (781s left) prime_self_import (basic-llseek-size)
[229.884556] Starting subtest: basic-llseek-size
[229.889858] Subtest basic-llseek-size: SUCCESS (0.005s)
[230.070402] [133/162] (781s left) prime_self_import (basic-with_fd_dup)
[230.136775] Starting subtest: basic-with_fd_dup
[230.141652] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[230.326868] [134/162] (780s left) prime_self_import (basic-with_one_bo)
[230.394331] Starting subtest: basic-with_one_bo
[230.399410] Subtest basic-with_one_bo: SUCCESS (0.005s)
[230.524408] [135/162] (780s left) prime_self_import (basic-with_one_bo_two_files)
[230.590674] Starting subtest: basic-with_one_bo_two_files
[230.597278] Subtest basic-with_one_bo_two_files: SUCCESS (0.005s)
[230.739062] [136/162] (780s left) prime_self_import (basic-with_two_bos)
[230.805305] Starting subtest: basic-with_two_bos
[230.810156] Subtest basic-with_two_bos: SUCCESS (0.005s)
[230.983365] [137/162] (780s left) prime_vgem (basic-fence-flip)
[231.061947] Starting subtest: basic-fence-flip
[231.572906] Subtest basic-fence-flip: SUCCESS (0.509s)
[231.760226] [138/162] (779s left) prime_vgem (basic-fence-mmap)
[231.839158] Starting subtest: basic-fence-mmap
[231.872448] Subtest basic-fence-mmap: SUCCESS (0.033s)
[232.044919] [139/162] (779s left) prime_vgem (basic-fence-read)
[232.180387] Starting subtest: basic-fence-read
[232.275012] Subtest basic-fence-read: SUCCESS (0.094s)
[232.413051] [140/162] (779s left) prime_vgem (basic-gtt)
[232.489244] Starting subtest: basic-gtt
[232.504998] Subtest basic-gtt: SUCCESS (0.016s)
[232.696199] [141/162] (779s left) prime_vgem (basic-read)
[232.773322] Starting subtest: basic-read
[232.811157] Subtest basic-read: SUCCESS (0.038s)
[233.017906] [142/162] (778s left) prime_vgem (basic-write)
[233.097313] Starting subtest: basic-write
[233.138540] Subtest basic-write: SUCCESS (0.041s)
[233.267246] [143/162] (778s left) vgem_basic (setversion)
[233.336291] Starting subtest: setversion
[233.338304] Subtest setversion: SUCCESS (0.000s)
[233.463675] [144/162] (778s left) vgem_basic (create)
[233.532690] Starting subtest: create
[233.533805] Subtest create: SUCCESS (0.000s)
[233.676136] [145/162] (778s left) vgem_basic (debugfs)
[233.751915] Starting subtest: debugfs
[233.753383] Subtest debugfs: SUCCESS (0.001s)
[233.890323] [146/162] (778s left) vgem_basic (dmabuf-export)
[233.958426] Starting subtest: dmabuf-export
[233.960456] Subtest dmabuf-export: SUCCESS (0.003s)
[234.157972] [147/162] (778s left) vgem_basic (dmabuf-fence)
[234.226116] Starting subtest: dmabuf-fence
[234.227059] Subtest dmabuf-fence: SUCCESS (0.001s)
[234.328980] [148/162] (778s left) vgem_basic (dmabuf-fence-before)
[234.397393] Starting subtest: dmabuf-fence-before
[234.398445] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[234.520156] [149/162] (778s left) vgem_basic (dmabuf-mmap)
[234.589340] Starting subtest: dmabuf-mmap
[234.604651] Subtest dmabuf-mmap: SUCCESS (0.016s)
[234.725851] [150/162] (777s left) vgem_basic (mmap)
[234.794746] Starting subtest: mmap
[234.805380] Subtest mmap: SUCCESS (0.011s)
[234.954270] [151/162] (777s left) vgem_basic (second-client)
[235.021799] Starting subtest: second-client
[235.037825] Subtest second-client: SUCCESS (0.016s)
[235.149762] [152/162] (777s left) vgem_basic (sysfs)
[235.218912] Starting subtest: sysfs
[235.219979] Subtest sysfs: SUCCESS (0.001s)
[235.340647] [153/162] (777s left) core_hotunplug (unbind-rebind)
[235.480221] Starting subtest: unbind-rebind
[240.341418] Subtest unbind-rebind: SUCCESS (4.862s)
[240.419853] [154/162] (772s left) vgem_basic (unload)