[46.896606] Initializing watchdogs
[46.896733]   /dev/watchdog0
[46.927545] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:1916 Intel Skylake (Gen9) Skylake GT2 [HD Graphics 520]
[46.935517] [001/162] (960s left) i915_module_load (load)
[47.193408] Starting subtest: load
[48.950057] Subtest load: SUCCESS (1.756s)
[49.066168] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[49.072154] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[49.081339] [002/162] (958s left) core_auth (basic-auth)
[49.183504] Starting subtest: basic-auth
[49.184487] Subtest basic-auth: SUCCESS (0.001s)
[49.391680] [003/162] (958s left) core_debugfs (read-all-entries)
[49.458356] Starting subtest: read-all-entries
[49.560643] Subtest read-all-entries: SUCCESS (0.103s)
[49.672183] [004/162] (958s left) core_sysfs (read-all-entries)
[49.740461] Starting subtest: read-all-entries
[49.746677] Subtest read-all-entries: SUCCESS (0.007s)
[49.856560] [005/162] (957s left) fbdev (eof)
[49.926522] Starting subtest: eof
[49.928655] Subtest eof: SUCCESS (0.001s)
[50.125225] [006/162] (957s left) fbdev (info)
[50.190979] Starting subtest: info
[50.192060] Subtest info: SUCCESS (0.000s)
[50.292238] [007/162] (957s left) fbdev (nullptr)
[50.376485] Starting subtest: nullptr
[50.377520] Subtest nullptr: SUCCESS (0.000s)
[50.473655] [008/162] (957s left) fbdev (read)
[50.542431] Starting subtest: read
[51.445571] Subtest read: SUCCESS (0.891s)
[51.558633] [009/162] (956s left) fbdev (write)
[51.631037] Starting subtest: write
[52.142489] Subtest write: SUCCESS (0.478s)
[52.239499] [010/162] (955s left) gem_basic (bad-close)
[52.314944] Starting subtest: bad-close
[52.316981] Subtest bad-close: SUCCESS (0.000s)
[52.436077] [011/162] (955s left) gem_basic (create-close)
[52.506594] Starting subtest: create-close
[52.509086] Subtest create-close: SUCCESS (0.000s)
[52.655725] [012/162] (955s left) gem_basic (create-fd-close)
[52.726023] Starting subtest: create-fd-close
[52.728174] Subtest create-fd-close: SUCCESS (0.001s)
[52.935984] [013/162] (955s left) gem_busy (busy@all-engines)
[53.031942] Starting subtest: busy
[53.033032] Starting dynamic subtest: all-engines
[53.101372] Dynamic subtest all-engines: SUCCESS (0.066s)
[53.102435] Subtest busy: SUCCESS (0.067s)
[53.590717] [014/162] (954s left) gem_close_race (basic-process)
[53.685602] Starting subtest: basic-process
[53.747837] Subtest basic-process: SUCCESS (0.060s)
[54.037708] [015/162] (954s left) gem_close_race (basic-threads)
[54.131466] Starting subtest: basic-threads
[55.390257] Subtest basic-threads: SUCCESS (1.219s)
[55.702841] [016/162] (952s left) gem_ctx_create (basic)
[55.797002] Starting subtest: basic
[55.798213] Subtest basic: SUCCESS (0.000s)
[56.027393] [017/162] (952s left) gem_ctx_create (basic-files)
[56.117704] Starting subtest: basic-files
[58.137552] Subtest basic-files: SUCCESS (1.972s)
[58.450751] [018/162] (950s left) gem_ctx_exec (basic)
[58.524134] Starting subtest: basic
[58.531428] Subtest basic: SUCCESS (0.007s)
[58.716665] [019/162] (950s left) gem_exec_basic (basic)
[58.795237] Starting subtest: basic
[58.796234] Starting dynamic subtest: rcs0-smem
[58.799184] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[58.814635] Starting dynamic subtest: bcs0-smem
[58.815883] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[58.829051] Starting dynamic subtest: vcs0-smem
[58.830398] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[58.843794] Starting dynamic subtest: vecs0-smem
[58.845081] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[58.846380] Subtest basic: SUCCESS (0.011s)
[59.096082] [020/162] (949s left) gem_exec_create (basic)
[59.181870] Starting subtest: basic
[59.182928] Starting dynamic subtest: smem
[61.226144] Dynamic subtest smem: SUCCESS (2.012s)
[61.227425] Subtest basic: SUCCESS (2.012s)
[61.508713] [021/162] (947s left) gem_exec_fence (basic-busy)
[61.751240] Starting subtest: basic-busy
[61.752189] Starting dynamic subtest: rcs0
[61.792339] Dynamic subtest rcs0: SUCCESS (0.041s)
[61.809898] Starting dynamic subtest: bcs0
[61.816247] Dynamic subtest bcs0: SUCCESS (0.023s)
[61.830108] Starting dynamic subtest: vcs0
[61.837329] Dynamic subtest vcs0: SUCCESS (0.020s)
[61.851866] Starting dynamic subtest: vecs0
[61.865240] Dynamic subtest vecs0: SUCCESS (0.027s)
[61.866213] Subtest basic-busy: SUCCESS (0.114s)
[62.656947] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[62.661745] [022/162] (946s left) gem_exec_fence (basic-wait)
[62.907100] Starting subtest: basic-wait
[62.908537] Starting dynamic subtest: rcs0
[62.949346] Dynamic subtest rcs0: SUCCESS (0.042s)
[62.965467] Starting dynamic subtest: bcs0
[62.973411] Dynamic subtest bcs0: SUCCESS (0.023s)
[62.988783] Starting dynamic subtest: vcs0
[62.997214] Dynamic subtest vcs0: SUCCESS (0.023s)
[63.011630] Starting dynamic subtest: vecs0
[63.021285] Dynamic subtest vecs0: SUCCESS (0.023s)
[63.022357] Subtest basic-wait: SUCCESS (0.114s)
[63.786793] [023/162] (945s left) gem_exec_fence (basic-await)
[64.010456] Starting subtest: basic-await
[64.012236] Starting dynamic subtest: rcs0
[64.121239] Dynamic subtest rcs0: SUCCESS (0.110s)
[64.142224] Starting dynamic subtest: bcs0
[64.234182] Dynamic subtest bcs0: SUCCESS (0.111s)
[64.252981] Starting dynamic subtest: vcs0
[64.369495] Dynamic subtest vcs0: SUCCESS (0.104s)
[64.387375] Starting dynamic subtest: vecs0
[64.490114] Dynamic subtest vecs0: SUCCESS (0.124s)
[64.491117] Subtest basic-await: SUCCESS (0.476s)
[65.309042] [024/162] (943s left) gem_exec_fence (nb-await)
[65.553893] Starting subtest: nb-await
[65.555111] Starting dynamic subtest: rcs0
[65.624427] Dynamic subtest rcs0: SUCCESS (0.069s)
[65.647996] Starting dynamic subtest: bcs0
[65.702184] Dynamic subtest bcs0: SUCCESS (0.074s)
[65.763395] Starting dynamic subtest: vcs0
[65.810239] Dynamic subtest vcs0: SUCCESS (0.105s)
[65.871651] Starting dynamic subtest: vecs0
[65.919413] Dynamic subtest vecs0: SUCCESS (0.104s)
[65.922714] Subtest nb-await: SUCCESS (0.360s)
[66.710224] [025/162] (942s left) gem_exec_gttfill (basic)
[66.803668] Starting subtest: basic
[73.385936] Subtest basic: SUCCESS (6.554s)
[73.746924] [026/162] (935s left) gem_exec_parallel (engines)
[73.932759] Starting subtest: engines
[73.933792] Starting dynamic subtest: basic
[76.563457] Dynamic subtest basic: SUCCESS (2.612s)
[76.578053] Starting dynamic subtest: contexts
[83.063825] Dynamic subtest contexts: SUCCESS (6.496s)
[83.079738] Starting dynamic subtest: fds
[88.365677] Dynamic subtest fds: SUCCESS (5.305s)
[88.382706] Starting dynamic subtest: userptr
[90.937453] Dynamic subtest userptr: SUCCESS (2.569s)
[90.938788] Subtest engines: SUCCESS (16.984s)
[91.244606] [027/162] (918s left) gem_exec_store (basic)
[91.332427] Starting subtest: basic
[91.391771] Subtest basic: SUCCESS (0.059s)
[91.678694] [028/162] (917s left) gem_flink_basic (bad-flink)
[91.747852] Starting subtest: bad-flink
[91.749031] Subtest bad-flink: SUCCESS (0.000s)
[91.943817] [029/162] (917s left) gem_flink_basic (bad-open)
[92.011141] Starting subtest: bad-open
[92.012169] Subtest bad-open: SUCCESS (0.000s)
[92.135384] [030/162] (917s left) gem_flink_basic (basic)
[92.201932] Starting subtest: basic
[92.203168] Subtest basic: SUCCESS (0.000s)
[92.344699] [031/162] (917s left) gem_flink_basic (double-flink)
[92.412955] Starting subtest: double-flink
[92.414149] Subtest double-flink: SUCCESS (0.000s)
[92.606408] [032/162] (917s left) gem_flink_basic (flink-lifetime)
[92.676117] Starting subtest: flink-lifetime
[92.679755] Subtest flink-lifetime: SUCCESS (0.003s)
[92.856689] [033/162] (917s left) gem_huc_copy (huc-copy)
[92.941148] Starting subtest: huc-copy
[92.943052] Subtest huc-copy: SKIP (0.000s)
[93.125998] [034/162] (916s left) gem_linear_blits (basic)
[93.335524] Starting subtest: basic
[93.346535] Subtest basic: SUCCESS (0.011s)
[93.562969] [035/162] (916s left) gem_mmap (basic)
[93.704994] Starting subtest: basic
[93.710838] Subtest basic: SUCCESS (0.000s)
[93.861017] [036/162] (916s left) gem_mmap_gtt (basic)
[93.932219] Starting subtest: basic
[93.933272] Subtest basic: SUCCESS (0.001s)
[94.123216] [037/162] (916s left) gem_render_linear_blits (basic)
[94.200506] Starting subtest: basic
[94.255728] Subtest basic: SUCCESS (0.055s)
[94.428731] [038/162] (916s left) gem_render_tiled_blits (basic)
[94.518857] Starting subtest: basic
[94.572249] Subtest basic: SUCCESS (0.051s)
[94.677500] [039/162] (915s left) gem_ringfill (basic-all)
[94.965429] Starting subtest: basic-all
[96.135842] Subtest basic-all: SUCCESS (1.161s)
[96.400602] [040/162] (914s left) gem_softpin (allocator-basic)
[96.476748] Starting subtest: allocator-basic
[96.672384] Subtest allocator-basic: SUCCESS (0.187s)
[96.886223] [041/162] (913s left) gem_softpin (allocator-basic-reserve)
[96.968340] Starting subtest: allocator-basic-reserve
[97.159250] Subtest allocator-basic-reserve: SUCCESS (0.181s)
[97.323594] [042/162] (913s left) gem_softpin (safe-alignment)
[97.403152] Starting subtest: safe-alignment
[97.422234] Subtest safe-alignment: SUCCESS (0.019s)
[97.622203] [043/162] (913s left) gem_sync (basic-all)
[97.713566] Starting subtest: basic-all
[100.286047] Subtest basic-all: SUCCESS (2.559s)
[100.533697] [044/162] (910s left) gem_sync (basic-each)
[100.642365] Starting subtest: basic-each
[102.904728] Subtest basic-each: SUCCESS (2.266s)
[103.218484] [045/162] (907s left) gem_tiled_blits (basic)
[103.391961] Starting subtest: basic
[103.411983] Subtest basic: SUCCESS (0.023s)
[103.625842] [046/162] (907s left) gem_tiled_fence_blits (basic)
[103.867833] Starting subtest: basic
[103.882658] Subtest basic: SUCCESS (0.015s)
[104.099205] [047/162] (907s left) gem_tiled_pread_basic (basic)
[104.227193] Starting subtest: basic
[104.404950] Subtest basic: SUCCESS (0.169s)
[104.587316] [048/162] (906s left) gem_wait (busy@all-engines)
[104.683051] Starting subtest: busy
[104.684079] Starting dynamic subtest: all-engines
[105.232339] Dynamic subtest all-engines: SUCCESS (0.549s)
[105.233378] Subtest busy: SUCCESS (0.550s)
[105.512302] [049/162] (905s left) gem_wait (wait@all-engines)
[105.609357] Starting subtest: wait
[105.610354] Starting dynamic subtest: all-engines
[106.660924] Dynamic subtest all-engines: SUCCESS (1.049s)
[106.663197] Subtest wait: SUCCESS (1.052s)
[106.977176] [050/162] (904s left) i915_getparams_basic (basic-eu-total)
[107.046104] Starting subtest: basic-eu-total
[107.048173] Subtest basic-eu-total: SUCCESS (0.000s)
[107.159929] [051/162] (904s left) i915_getparams_basic (basic-subslice-total)
[107.228214] Starting subtest: basic-subslice-total
[107.230218] Subtest basic-subslice-total: SUCCESS (0.000s)
[107.345685] [052/162] (904s left) i915_hangman (error-state-basic)
[107.436273] Starting subtest: error-state-basic
[107.449988] Subtest error-state-basic: SUCCESS (0.013s)
[107.587655] [053/162] (904s left) i915_pciid
[107.771811] [054/162] (904s left) intel_hwmon (hwmon-read)
[107.842589] Subtest hwmon-read: SKIP (0.000s)
[107.931955] [055/162] (904s left) intel_hwmon (hwmon-write)
[108.000197] Subtest hwmon-write: SKIP (0.000s)
[108.098498] [056/162] (903s left) kms_addfb_basic (addfb25-4-tiled)
[108.235571] Starting subtest: addfb25-4-tiled
[108.236581] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[108.373384] [057/162] (903s left) kms_addfb_basic (addfb25-bad-modifier)
[108.500129] Starting subtest: addfb25-bad-modifier
[108.501019] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[108.696282] [058/162] (903s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[108.824231] Starting subtest: addfb25-framebuffer-vs-set-tiling
[108.825222] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[108.960323] [059/162] (903s left) kms_addfb_basic (addfb25-modifier-no-flag)
[109.091098] Starting subtest: addfb25-modifier-no-flag
[109.092129] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[109.264726] [060/162] (903s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[109.464611] Starting subtest: addfb25-x-tiled-legacy
[109.465758] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[109.641546] [061/162] (902s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[109.766068] Starting subtest: addfb25-x-tiled-mismatch-legacy
[109.767173] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[109.936885] [062/162] (902s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[110.058299] Starting subtest: addfb25-yf-tiled-legacy
[110.059242] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[110.219901] [063/162] (902s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[110.350204] Starting subtest: addfb25-y-tiled-legacy
[110.351087] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[110.524874] [064/162] (902s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[110.645104] Starting subtest: addfb25-y-tiled-small-legacy
[110.646218] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[110.832265] [065/162] (901s left) kms_addfb_basic (bad-pitch-0)
[110.907109] Starting subtest: bad-pitch-0
[110.908208] Subtest bad-pitch-0: SUCCESS (0.000s)
[111.105763] [066/162] (901s left) kms_addfb_basic (bad-pitch-1024)
[111.175060] Starting subtest: bad-pitch-1024
[111.176192] Subtest bad-pitch-1024: SUCCESS (0.000s)
[111.385762] [067/162] (901s left) kms_addfb_basic (bad-pitch-128)
[111.454528] Starting subtest: bad-pitch-128
[111.455475] Subtest bad-pitch-128: SUCCESS (0.000s)
[111.697455] [068/162] (901s left) kms_addfb_basic (bad-pitch-256)
[111.767979] Starting subtest: bad-pitch-256
[111.768952] Subtest bad-pitch-256: SUCCESS (0.000s)
[112.009811] [069/162] (901s left) kms_addfb_basic (bad-pitch-32)
[112.078869] Starting subtest: bad-pitch-32
[112.079991] Subtest bad-pitch-32: SUCCESS (0.000s)
[112.326620] [070/162] (900s left) kms_addfb_basic (bad-pitch-63)
[112.398457] Starting subtest: bad-pitch-63
[112.399420] Subtest bad-pitch-63: SUCCESS (0.000s)
[112.594382] [071/162] (900s left) kms_addfb_basic (bad-pitch-65536)
[112.663727] Starting subtest: bad-pitch-65536
[112.664752] Subtest bad-pitch-65536: SUCCESS (0.000s)
[112.880675] [072/162] (900s left) kms_addfb_basic (bad-pitch-999)
[112.949609] Starting subtest: bad-pitch-999
[112.950644] Subtest bad-pitch-999: SUCCESS (0.000s)
[113.187211] [073/162] (900s left) kms_addfb_basic (basic)
[113.256511] Starting subtest: basic
[113.257404] Subtest basic: SUCCESS (0.000s)
[113.464377] [074/162] (900s left) kms_addfb_basic (basic-x-tiled-legacy)
[113.541349] Starting subtest: basic-x-tiled-legacy
[113.542342] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[113.776963] [075/162] (899s left) kms_addfb_basic (basic-y-tiled-legacy)
[113.855995] Starting subtest: basic-y-tiled-legacy
[113.857337] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[114.116069] [076/162] (899s left) kms_addfb_basic (bo-too-small)
[114.243411] Starting subtest: bo-too-small
[114.244404] Subtest bo-too-small: SUCCESS (0.001s)
[114.423049] [077/162] (899s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[114.548369] Starting subtest: bo-too-small-due-to-tiling
[114.549481] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[114.706294] [078/162] (899s left) kms_addfb_basic (clobberred-modifier)
[114.775524] Starting subtest: clobberred-modifier
[114.776579] Subtest clobberred-modifier: SUCCESS (0.000s)
[114.992092] [079/162] (899s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[115.061683] Starting subtest: framebuffer-vs-set-tiling
[115.062724] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[115.279274] [080/162] (898s left) kms_addfb_basic (invalid-get-prop)
[115.349667] Starting subtest: invalid-get-prop
[115.350988] Subtest invalid-get-prop: SUCCESS (0.000s)
[115.591143] [081/162] (898s left) kms_addfb_basic (invalid-get-prop-any)
[115.664880] Starting subtest: invalid-get-prop-any
[115.665986] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[115.855617] [082/162] (898s left) kms_addfb_basic (invalid-set-prop)
[115.925447] Starting subtest: invalid-set-prop
[115.926464] Subtest invalid-set-prop: SUCCESS (0.000s)
[116.154257] [083/162] (898s left) kms_addfb_basic (invalid-set-prop-any)
[116.223089] Starting subtest: invalid-set-prop-any
[116.224151] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[116.460695] [084/162] (897s left) kms_addfb_basic (no-handle)
[116.530448] Starting subtest: no-handle
[116.531470] Subtest no-handle: SUCCESS (0.000s)
[116.765987] [085/162] (897s left) kms_addfb_basic (size-max)
[116.894886] Starting subtest: size-max
[116.895926] Subtest size-max: SUCCESS (0.000s)
[117.035319] [086/162] (897s left) kms_addfb_basic (small-bo)
[117.158230] Starting subtest: small-bo
[117.159215] Subtest small-bo: SUCCESS (0.000s)
[117.345340] [087/162] (897s left) kms_addfb_basic (tile-pitch-mismatch)
[117.416294] Starting subtest: tile-pitch-mismatch
[117.417239] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[117.659982] [088/162] (897s left) kms_addfb_basic (too-high)
[117.798640] Starting subtest: too-high
[117.799811] Subtest too-high: SUCCESS (0.001s)
[117.941397] [089/162] (896s left) kms_addfb_basic (too-wide)
[118.066337] Starting subtest: too-wide
[118.067281] Subtest too-wide: SUCCESS (0.000s)
[118.227716] [090/162] (896s left) kms_addfb_basic (unused-handle)
[118.298078] Starting subtest: unused-handle
[118.299088] Subtest unused-handle: SUCCESS (0.000s)
[118.507468] [091/162] (896s left) kms_addfb_basic (unused-modifier)
[118.577525] Starting subtest: unused-modifier
[118.578448] Subtest unused-modifier: SUCCESS (0.000s)
[118.814981] [092/162] (896s left) kms_addfb_basic (unused-offsets)
[118.886429] Starting subtest: unused-offsets
[118.887412] Subtest unused-offsets: SUCCESS (0.000s)
[119.105131] [093/162] (896s left) kms_addfb_basic (unused-pitches)
[119.173420] Starting subtest: unused-pitches
[119.174439] Subtest unused-pitches: SUCCESS (0.000s)
[119.406404] [094/162] (895s left) kms_busy (basic)
[119.533317] Starting subtest: basic
[119.534722] Starting dynamic subtest: flip
[120.370647] Dynamic subtest flip: SUCCESS (0.833s)
[120.396552] Starting dynamic subtest: modeset
[123.954299] Dynamic subtest modeset: SUCCESS (3.575s)
[123.956890] Subtest basic: SUCCESS (4.413s)
[124.945458] [095/162] (890s left) kms_prop_blob (basic)
[125.015396] Starting subtest: basic
[125.016333] Subtest basic: SUCCESS (0.000s)
[125.133659] [096/162] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[125.254199] Starting subtest: basic-busy-flip-before-cursor-atomic
[125.256069] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[125.437682] [097/162] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[125.562738] Starting subtest: basic-busy-flip-before-cursor-legacy
[125.564639] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[125.687244] [098/162] (889s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[125.810370] Starting subtest: basic-flip-after-cursor-atomic
[127.096343] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.285s)
[127.977914] [099/162] (887s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[128.104134] Starting subtest: basic-flip-after-cursor-legacy
[129.396691] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.290s)
[130.242967] [100/162] (885s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[130.366182] Starting subtest: basic-flip-after-cursor-varying-size
[131.659836] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.292s)
[132.582680] [101/162] (883s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[132.704076] Starting subtest: basic-flip-before-cursor-atomic
[133.980956] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.276s)
[134.956654] [102/162] (881s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[135.076133] Starting subtest: basic-flip-before-cursor-legacy
[136.358243] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.280s)
[137.292695] [103/162] (878s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[137.411226] Starting subtest: basic-flip-before-cursor-varying-size
[138.696063] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.281s)
[139.615920] [104/162] (876s left) kms_dsc (dsc-basic)
[139.737466] Subtest dsc-basic: SKIP (0.000s)
[139.828314] [105/162] (876s left) kms_flip (basic-flip-vs-dpms)
[139.953044] Starting subtest: basic-flip-vs-dpms
[139.957071] Starting dynamic subtest: A-eDP1
[142.642160] Dynamic subtest A-eDP1: SUCCESS (2.681s)
[142.703738] Starting dynamic subtest: B-eDP1
[145.002239] Dynamic subtest B-eDP1: SUCCESS (2.348s)
[145.067620] Starting dynamic subtest: C-eDP1
[147.252635] Dynamic subtest C-eDP1: SUCCESS (2.248s)
[147.263513] Subtest basic-flip-vs-dpms: SUCCESS (7.309s)
[148.134942] [106/162] (868s left) kms_flip (basic-flip-vs-modeset)
[148.260203] Starting subtest: basic-flip-vs-modeset
[148.262817] Starting dynamic subtest: A-eDP1
[150.927960] Dynamic subtest A-eDP1: SUCCESS (2.661s)
[150.993296] Starting dynamic subtest: B-eDP1
[153.284556] Dynamic subtest B-eDP1: SUCCESS (2.351s)
[153.356857] Starting dynamic subtest: C-eDP1
[155.620339] Dynamic subtest C-eDP1: SUCCESS (2.329s)
[155.630859] Subtest basic-flip-vs-modeset: SUCCESS (7.371s)
[156.588187] [107/162] (859s left) kms_flip (basic-flip-vs-wf_vblank)
[156.713926] Starting subtest: basic-flip-vs-wf_vblank
[156.718655] Starting dynamic subtest: A-eDP1
[158.860938] Dynamic subtest A-eDP1: SUCCESS (2.142s)
[158.882060] Starting dynamic subtest: B-eDP1
[161.074294] Dynamic subtest B-eDP1: SUCCESS (2.212s)
[161.096689] Starting dynamic subtest: C-eDP1
[163.252767] Dynamic subtest C-eDP1: SUCCESS (2.176s)
[163.256572] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.543s)
[164.512038] [108/162] (852s left) kms_flip (basic-plain-flip)
[164.632235] Starting subtest: basic-plain-flip
[164.641516] Starting dynamic subtest: A-eDP1
[166.481782] Dynamic subtest A-eDP1: SUCCESS (1.847s)
[166.500806] Starting dynamic subtest: B-eDP1
[168.415506] Dynamic subtest B-eDP1: SUCCESS (1.927s)
[168.448231] Starting dynamic subtest: C-eDP1
[170.311826] Dynamic subtest C-eDP1: SUCCESS (1.883s)
[170.317972] Subtest basic-plain-flip: SUCCESS (5.685s)
[171.737429] [109/162] (844s left) kms_force_connector_basic (force-connector-state)
[171.841483] Starting subtest: force-connector-state
[172.448383] Subtest force-connector-state: SUCCESS (0.606s)
[173.637430] [110/162] (843s left) kms_force_connector_basic (force-edid)
[173.734739] Starting subtest: force-edid
[173.770750] Subtest force-edid: SUCCESS (0.035s)
[173.987505] [111/162] (842s left) kms_force_connector_basic (force-load-detect)
[174.084859] Starting subtest: force-load-detect
[174.087057] Subtest force-load-detect: SKIP (0.000s)
[174.267340] [112/162] (842s left) kms_force_connector_basic (prune-stale-modes)
[174.365120] Starting subtest: prune-stale-modes
[174.400883] Subtest prune-stale-modes: SUCCESS (0.036s)
[174.616690] [113/162] (842s left) kms_frontbuffer_tracking (basic)
[174.744955] Starting subtest: basic
[178.321632] Subtest basic: SUCCESS (3.576s)
[178.495513] [114/162] (838s left) kms_hdmi_inject (inject-audio)
[178.902153] Starting subtest: inject-audio
[179.040305] Subtest inject-audio: SUCCESS (0.139s)
[179.797915] [115/162] (837s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[179.924202] Starting subtest: compare-crc-sanitycheck-xr24
[179.925217] Starting dynamic subtest: pipe-A-eDP-1
[180.642208] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[180.714984] Starting dynamic subtest: pipe-B-eDP-1
[182.152094] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.490s)
[182.230274] Starting dynamic subtest: pipe-C-eDP-1
[183.526958] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.360s)
[183.527958] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.603s)
[184.520514] [116/162] (832s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[184.641083] Starting subtest: compare-crc-sanitycheck-nv12
[184.642173] Starting dynamic subtest: pipe-A-eDP-1
[184.644079] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[184.663879] Starting dynamic subtest: pipe-B-eDP-1
[184.666397] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[184.683012] Starting dynamic subtest: pipe-C-eDP-1
[184.685457] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[184.687554] Subtest compare-crc-sanitycheck-nv12: SKIP (0.011s)
[184.779773] [117/162] (832s left) kms_pipe_crc_basic (hang-read-crc)
[184.903251] Starting subtest: hang-read-crc
[184.904264] Starting dynamic subtest: pipe-A-eDP-1
[187.579650] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.672s)
[187.655743] Starting dynamic subtest: pipe-B-eDP-1
[190.788248] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.182s)
[190.866698] Starting dynamic subtest: pipe-C-eDP-1
[194.054354] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.258s)
[194.067185] Subtest hang-read-crc: SUCCESS (9.154s)
[195.011650] [118/162] (822s left) kms_pipe_crc_basic (nonblocking-crc)
[195.136786] Starting subtest: nonblocking-crc
[195.138202] Starting dynamic subtest: pipe-A-eDP-1
[196.160402] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.012s)
[196.246282] Starting dynamic subtest: pipe-B-eDP-1
[197.832841] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.663s)
[197.920587] Starting dynamic subtest: pipe-C-eDP-1
[199.552113] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.707s)
[199.556935] Subtest nonblocking-crc: SUCCESS (4.418s)
[200.449673] [119/162] (816s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[200.572323] Starting subtest: nonblocking-crc-frame-sequence
[200.573660] Starting dynamic subtest: pipe-A-eDP-1
[201.575267] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.999s)
[201.665599] Starting dynamic subtest: pipe-B-eDP-1
[203.268234] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.678s)
[203.353173] Starting dynamic subtest: pipe-C-eDP-1
[204.930089] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.637s)
[204.932245] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.357s)
[205.878132] [120/162] (811s left) kms_pipe_crc_basic (read-crc)
[206.006483] Starting subtest: read-crc
[206.007709] Starting dynamic subtest: pipe-A-eDP-1
[206.910468] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.898s)
[206.993306] Starting dynamic subtest: pipe-B-eDP-1
[208.608065] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.673s)
[208.695550] Starting dynamic subtest: pipe-C-eDP-1
[210.224850] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.596s)
[210.227631] Subtest read-crc: SUCCESS (4.219s)
[211.141725] [121/162] (806s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[211.267701] Starting subtest: read-crc-frame-sequence
[211.269078] Starting dynamic subtest: pipe-A-eDP-1
[212.149080] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.878s)
[212.242514] Starting dynamic subtest: pipe-B-eDP-1
[213.710395] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.544s)
[213.796372] Starting dynamic subtest: pipe-C-eDP-1
[215.350773] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.615s)
[215.353521] Subtest read-crc-frame-sequence: SUCCESS (4.085s)
[216.306502] [122/162] (801s left) kms_pm_backlight (basic-brightness)
[216.439220] Starting subtest: basic-brightness
[216.515742] Starting dynamic subtest: eDP-1
[217.122462] Dynamic subtest eDP-1: SUCCESS (0.605s)
[217.134385] Subtest basic-brightness: SUCCESS (0.689s)
[218.114523] [123/162] (799s left) kms_pm_rpm (basic-pci-d3-state)
[219.851125] Starting subtest: basic-pci-d3-state
[220.454498] Subtest basic-pci-d3-state: SUCCESS (0.607s)
[220.651477] [124/162] (797s left) kms_pm_rpm (basic-rte)
[220.722995] Starting subtest: basic-rte
[222.978578] Subtest basic-rte: SUCCESS (2.256s)
[224.375820] [125/162] (793s left) kms_psr (psr-primary-page-flip)
[224.499678] Starting subtest: psr-primary-page-flip
[224.500648] Starting dynamic subtest: eDP-1
[226.557381] Dynamic subtest eDP-1: SUCCESS (2.054s)
[226.569549] Subtest psr-primary-page-flip: SUCCESS (2.062s)
[227.473500] [126/162] (790s left) kms_psr (psr-cursor-plane-move)
[227.597190] Starting subtest: psr-cursor-plane-move
[227.598144] Starting dynamic subtest: eDP-1
[229.527089] Dynamic subtest eDP-1: SUCCESS (1.927s)
[229.528374] Subtest psr-cursor-plane-move: SUCCESS (1.931s)
[230.443072] [127/162] (787s left) kms_psr (psr-sprite-plane-onoff)
[230.564885] Starting subtest: psr-sprite-plane-onoff
[230.568340] Starting dynamic subtest: eDP-1
[232.653275] Dynamic subtest eDP-1: SUCCESS (2.081s)
[232.655636] Subtest psr-sprite-plane-onoff: SUCCESS (2.089s)
[233.538861] [128/162] (784s left) kms_psr (psr-primary-mmap-gtt)
[233.660650] Starting subtest: psr-primary-mmap-gtt
[233.664195] Starting dynamic subtest: eDP-1
[235.698348] Dynamic subtest eDP-1: SUCCESS (2.024s)
[235.700532] Subtest psr-primary-mmap-gtt: SUCCESS (2.031s)
[236.624424] [129/162] (781s left) kms_setmode (basic-clone-single-crtc)
[236.697266] Starting subtest: basic-clone-single-crtc
[236.700077] Subtest basic-clone-single-crtc: SKIP (0.000s)
[236.818503] [130/162] (781s left) i915_pm_rps (basic-api)
[236.901446] Starting subtest: basic-api
[236.907837] Subtest basic-api: SUCCESS (0.006s)
[237.097644] [131/162] (781s left) prime_self_import (basic-llseek-bad)
[237.168946] Starting subtest: basic-llseek-bad
[237.174134] Subtest basic-llseek-bad: SUCCESS (0.003s)
[237.354553] [132/162] (781s left) prime_self_import (basic-llseek-size)
[237.426458] Starting subtest: basic-llseek-size
[237.431248] Subtest basic-llseek-size: SUCCESS (0.006s)
[237.608515] [133/162] (781s left) prime_self_import (basic-with_fd_dup)
[237.675931] Starting subtest: basic-with_fd_dup
[237.682286] Subtest basic-with_fd_dup: SUCCESS (0.004s)
[237.872719] [134/162] (781s left) prime_self_import (basic-with_one_bo)
[237.941244] Starting subtest: basic-with_one_bo
[237.946660] Subtest basic-with_one_bo: SUCCESS (0.005s)
[238.109492] [135/162] (780s left) prime_self_import (basic-with_one_bo_two_files)
[238.178825] Starting subtest: basic-with_one_bo_two_files
[238.180822] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[238.329610] [136/162] (780s left) prime_self_import (basic-with_two_bos)
[238.399556] Starting subtest: basic-with_two_bos
[238.403459] Subtest basic-with_two_bos: SUCCESS (0.006s)
[238.596441] [137/162] (780s left) prime_vgem (basic-fence-flip)
[238.703241] Starting subtest: basic-fence-flip
[239.208834] Subtest basic-fence-flip: SUCCESS (0.505s)
[239.390345] [138/162] (779s left) prime_vgem (basic-fence-mmap)
[239.515394] Starting subtest: basic-fence-mmap
[239.555210] Subtest basic-fence-mmap: SUCCESS (0.043s)
[239.700486] [139/162] (779s left) prime_vgem (basic-fence-read)
[239.803205] Starting subtest: basic-fence-read
[239.889963] Subtest basic-fence-read: SUCCESS (0.086s)
[240.034634] [140/162] (779s left) prime_vgem (basic-gtt)
[240.139590] Starting subtest: basic-gtt
[240.160249] Subtest basic-gtt: SUCCESS (0.021s)
[240.296556] [141/162] (779s left) prime_vgem (basic-read)
[240.400388] Starting subtest: basic-read
[240.444256] Subtest basic-read: SUCCESS (0.044s)
[240.579239] [142/162] (779s left) prime_vgem (basic-write)
[240.675510] Starting subtest: basic-write
[240.717582] Subtest basic-write: SUCCESS (0.042s)
[240.842569] [143/162] (778s left) vgem_basic (setversion)
[240.918759] Starting subtest: setversion
[240.920692] Subtest setversion: SUCCESS (0.000s)
[241.025758] [144/162] (778s left) vgem_basic (create)
[241.101945] Starting subtest: create
[241.103065] Subtest create: SUCCESS (0.000s)
[241.213726] [145/162] (778s left) vgem_basic (debugfs)
[241.301906] Starting subtest: debugfs
[241.302928] Subtest debugfs: SUCCESS (0.001s)
[241.409027] [146/162] (778s left) vgem_basic (dmabuf-export)
[241.488178] Starting subtest: dmabuf-export
[241.490225] Subtest dmabuf-export: SUCCESS (0.003s)
[241.637441] [147/162] (778s left) vgem_basic (dmabuf-fence)
[241.718913] Starting subtest: dmabuf-fence
[241.719954] Subtest dmabuf-fence: SUCCESS (0.001s)
[241.828544] [148/162] (778s left) vgem_basic (dmabuf-fence-before)
[241.918812] Starting subtest: dmabuf-fence-before
[241.919880] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[242.026908] [149/162] (778s left) vgem_basic (dmabuf-mmap)
[242.102190] Starting subtest: dmabuf-mmap
[242.115517] Subtest dmabuf-mmap: SUCCESS (0.015s)
[242.228214] [150/162] (778s left) vgem_basic (mmap)
[242.302061] Starting subtest: mmap
[242.311353] Subtest mmap: SUCCESS (0.010s)
[242.427584] [151/162] (777s left) vgem_basic (second-client)
[242.502274] Starting subtest: second-client
[242.535956] Subtest second-client: SUCCESS (0.032s)
[242.646370] [152/162] (777s left) vgem_basic (sysfs)
[242.735123] Starting subtest: sysfs
[242.736066] Subtest sysfs: SUCCESS (0.001s)
[242.844230] [153/162] (777s left) core_hotunplug (unbind-rebind)
[243.007070] Starting subtest: unbind-rebind
[248.112257] Subtest unbind-rebind: SUCCESS (5.106s)
[248.195531] [154/162] (772s left) vgem_basic (unload)
[248.268370] Starting subtest: unload
[277.374891] Subtest unload: SUCCESS (29.072s)
[277.539398] [155/162] (743s left) i915_module_load (reload)
[277.606500] Starting subtest: reload
[281.549897] Subtest reload: SUCCESS (3.945s)
[281.663751] [156/162] (739s left) gem_lmem_swapping (basic)
[281.752403] Subtest basic: SKIP (0.000s)
[281.849365] [157/162] (739s left) gem_lmem_swapping (parallel-random-engines)
[281.926717] Subtest parallel-random-engines: SKIP (0.000s)
[282.016658] [158/162] (738s left) gem_lmem_swapping (random-engines)
[282.098847] Subtest random-engines: SKIP (0.000s)
[282.185327] [159/162] (738s left) gem_lmem_swapping (verify-random)
[282.263721] Subtest verify-random: SKIP (0.000s)
[282.349786] [160/162] (738s left) i915_pm_rpm (module-reload)
[284.736247] Starting subtest: module-reload
[297.465897] Subtest module-reload: SUCCESS (12.725s)
[297.510932] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[297.513355] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[297.518178] [161/162] (723s left) i915_selftest (live)
[297.594320] Starting subtest: live
[297.598431] Starting dynamic subtest: sanitycheck
[300.257592] Dynamic subtest sanitycheck: SUCCESS (2.653s)
[300.270266] Starting dynamic subtest: uncore
[302.830820] Dynamic subtest uncore: SUCCESS (2.571s)
[302.844069] Starting dynamic subtest: workarounds
[305.716556] Dynamic subtest workarounds: SUCCESS (2.884s)
[305.730368] Starting dynamic subtest: gt_engines
[308.648685] Dynamic subtest gt_engines: SUCCESS (2.930s)
[308.666062] Starting dynamic subtest: gt_timelines
[321.600811] Dynamic subtest gt_timelines: SUCCESS (12.950s)
[321.616597] Starting dynamic subtest: gt_contexts
[324.278726] Dynamic subtest gt_contexts: SUCCESS (2.676s)
[324.291922] Starting dynamic subtest: gt_lrc
[329.171621] Dynamic subtest gt_lrc: SUCCESS (4.891s)
[329.187045] Starting dynamic subtest: gt_mocs
[331.885688] Dynamic subtest gt_mocs: SUCCESS (2.712s)
[331.898911] Starting dynamic subtest: gt_pm
[341.429691] Dynamic subtest gt_pm: SUCCESS (9.541s)
[341.447332] Starting dynamic subtest: gt_heartbeat
[344.040561] Dynamic subtest gt_heartbeat: SUCCESS (2.608s)
[344.053939] Starting dynamic subtest: gt_tlb
[348.208357] Dynamic subtest gt_tlb: SUCCESS (4.165s)
[348.222099] Starting dynamic subtest: requests
[356.767931] Dynamic subtest requests: SUCCESS (8.557s)
[356.781598] Starting dynamic subtest: migrate
[361.821586] Dynamic subtest migrate: SUCCESS (5.051s)
[361.839674] Starting dynamic subtest: active
[364.405661] Dynamic subtest active: SUCCESS (2.589s)
[364.473053] Starting dynamic subtest: objects
[368.032638] Dynamic subtest objects: SUCCESS (3.615s)
[368.049044] Starting dynamic subtest: mman
[373.755673] Dynamic subtest mman: SUCCESS (5.721s)
[373.770355] Starting dynamic subtest: dmabuf
[376.293731] Dynamic subtest dmabuf: SUCCESS (2.536s)
[376.307961] Starting dynamic subtest: vma
[378.962723] Dynamic subtest vma: SUCCESS (2.666s)
[378.980259] Starting dynamic subtest: coherency
[383.017578] Dynamic subtest coherency: SUCCESS (4.052s)
[383.030976] Starting dynamic subtest: gtt
[392.649673] Dynamic subtest gtt: SUCCESS (9.629s)
[392.663468] Starting dynamic subtest: gem