[41.535943] Initializing watchdogs
[41.536088]   /dev/watchdog0
[41.565978] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:1916 Intel Skylake (Gen9) Skylake GT2 [HD Graphics 520]
[41.574627] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[41.582399] [001/162] (960s left) i915_module_load (load)
[41.658860] Starting subtest: load
[43.392351] Subtest load: SUCCESS (1.733s)
[43.450227] [FACT i915_module_load (load)] changed: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 -> card1
[43.453442] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[43.457538] [002/162] (958s left) core_auth (basic-auth)
[43.539307] Starting subtest: basic-auth
[43.540381] Subtest basic-auth: SUCCESS (0.001s)
[43.704715] [003/162] (958s left) core_debugfs (read-all-entries)
[43.819035] Starting subtest: read-all-entries
[43.939217] Subtest read-all-entries: SUCCESS (0.121s)
[44.049900] [004/162] (958s left) core_sysfs (read-all-entries)
[44.121435] Starting subtest: read-all-entries
[44.127953] Subtest read-all-entries: SUCCESS (0.007s)
[44.254358] [005/162] (958s left) fbdev (eof)
[44.327445] Starting subtest: eof
[44.328621] Subtest eof: SUCCESS (0.001s)
[44.421668] [006/162] (958s left) fbdev (info)
[44.487438] Starting subtest: info
[44.488553] Subtest info: SUCCESS (0.000s)
[44.589152] [007/162] (957s left) fbdev (nullptr)
[44.659846] Starting subtest: nullptr
[44.661317] Subtest nullptr: SUCCESS (0.000s)
[44.755303] [008/162] (957s left) fbdev (read)
[44.826492] Starting subtest: read
[45.735226] Subtest read: SUCCESS (0.900s)
[45.823611] [009/162] (956s left) fbdev (write)
[45.893686] Starting subtest: write
[46.370239] Subtest write: SUCCESS (0.476s)
[46.465205] [010/162] (956s left) gem_basic (bad-close)
[46.533232] Starting subtest: bad-close
[46.535556] Subtest bad-close: SUCCESS (0.000s)
[46.634310] [011/162] (956s left) gem_basic (create-close)
[46.701791] Starting subtest: create-close
[46.704200] Subtest create-close: SUCCESS (0.000s)
[46.868440] [012/162] (955s left) gem_basic (create-fd-close)
[46.935498] Starting subtest: create-fd-close
[46.937366] Subtest create-fd-close: SUCCESS (0.000s)
[47.094231] [013/162] (955s left) gem_busy (busy@all-engines)
[47.189018] Starting subtest: busy
[47.190064] Starting dynamic subtest: all-engines
[47.260859] Dynamic subtest all-engines: SUCCESS (0.072s)
[47.261973] Subtest busy: SUCCESS (0.072s)
[47.725891] [014/162] (955s left) gem_close_race (basic-process)
[47.817722] Starting subtest: basic-process
[47.879501] Subtest basic-process: SUCCESS (0.062s)
[48.147715] [015/162] (954s left) gem_close_race (basic-threads)
[48.238518] Starting subtest: basic-threads
[49.450920] Subtest basic-threads: SUCCESS (1.212s)
[49.749819] [016/162] (953s left) gem_ctx_create (basic)
[49.847872] Starting subtest: basic
[49.850161] Subtest basic: SUCCESS (0.001s)
[50.079791] [017/162] (953s left) gem_ctx_create (basic-files)
[50.165326] Starting subtest: basic-files
[52.184276] Subtest basic-files: SUCCESS (2.013s)
[52.493509] [018/162] (950s left) gem_ctx_exec (basic)
[52.568464] Starting subtest: basic
[52.574855] Subtest basic: SUCCESS (0.006s)
[52.753054] [019/162] (950s left) gem_exec_basic (basic)
[52.834983] Starting subtest: basic
[52.836460] Starting dynamic subtest: rcs0-smem
[52.838958] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[52.854597] Starting dynamic subtest: bcs0-smem
[52.855936] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[52.868089] Starting dynamic subtest: vcs0-smem
[52.869536] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[52.881395] Starting dynamic subtest: vecs0-smem
[52.882739] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[52.884060] Subtest basic: SUCCESS (0.008s)
[53.130524] [020/162] (950s left) gem_exec_create (basic)
[53.221405] Starting subtest: basic
[53.222484] Starting dynamic subtest: smem
[55.266661] Dynamic subtest smem: SUCCESS (2.004s)
[55.267874] Subtest basic: SUCCESS (2.004s)
[55.545611] [021/162] (947s left) gem_exec_fence (basic-busy)
[55.780695] Starting subtest: basic-busy
[55.782822] Starting dynamic subtest: rcs0
[55.815448] Dynamic subtest rcs0: SUCCESS (0.034s)
[55.834002] Starting dynamic subtest: bcs0
[55.845306] Dynamic subtest bcs0: SUCCESS (0.029s)
[55.858756] Starting dynamic subtest: vcs0
[55.865390] Dynamic subtest vcs0: SUCCESS (0.019s)
[55.878683] Starting dynamic subtest: vecs0
[55.890443] Dynamic subtest vecs0: SUCCESS (0.024s)
[55.891508] Subtest basic-busy: SUCCESS (0.109s)
[56.749446] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[56.753958] [022/162] (946s left) gem_exec_fence (basic-wait)
[56.993523] Starting subtest: basic-wait
[56.995199] Starting dynamic subtest: rcs0
[57.034287] Dynamic subtest rcs0: SUCCESS (0.040s)
[57.048346] Starting dynamic subtest: bcs0
[57.077819] Dynamic subtest bcs0: SUCCESS (0.042s)
[57.095558] Starting dynamic subtest: vcs0
[57.111365] Dynamic subtest vcs0: SUCCESS (0.033s)
[57.129079] Starting dynamic subtest: vecs0
[57.144437] Dynamic subtest vecs0: SUCCESS (0.032s)
[57.145725] Subtest basic-wait: SUCCESS (0.149s)
[57.868619] [023/162] (945s left) gem_exec_fence (basic-await)
[58.103035] Starting subtest: basic-await
[58.104505] Starting dynamic subtest: rcs0
[58.204368] Dynamic subtest rcs0: SUCCESS (0.100s)
[58.219888] Starting dynamic subtest: bcs0
[58.303869] Dynamic subtest bcs0: SUCCESS (0.098s)
[58.319654] Starting dynamic subtest: vcs0
[58.402825] Dynamic subtest vcs0: SUCCESS (0.097s)
[58.419965] Starting dynamic subtest: vecs0
[58.508233] Dynamic subtest vecs0: SUCCESS (0.103s)
[58.509453] Subtest basic-await: SUCCESS (0.400s)
[59.350328] [024/162] (944s left) gem_exec_fence (nb-await)
[59.593213] Starting subtest: nb-await
[59.594534] Starting dynamic subtest: rcs0
[59.668270] Dynamic subtest rcs0: SUCCESS (0.073s)
[59.695487] Starting dynamic subtest: bcs0
[59.746770] Dynamic subtest bcs0: SUCCESS (0.075s)
[59.804927] Starting dynamic subtest: vcs0
[59.850304] Dynamic subtest vcs0: SUCCESS (0.100s)
[59.910642] Starting dynamic subtest: vecs0
[59.957630] Dynamic subtest vecs0: SUCCESS (0.103s)
[59.961605] Subtest nb-await: SUCCESS (0.358s)
[60.782624] [025/162] (942s left) gem_exec_gttfill (basic)
[60.871343] Starting subtest: basic
[66.284034] Subtest basic: SUCCESS (5.379s)
[66.560751] [026/162] (937s left) gem_exec_parallel (engines)
[66.716319] Starting subtest: engines
[66.717547] Starting dynamic subtest: basic
[69.689131] Dynamic subtest basic: SUCCESS (2.945s)
[69.706101] Starting dynamic subtest: contexts
[78.281677] Dynamic subtest contexts: SUCCESS (8.597s)
[78.296664] Starting dynamic subtest: fds
[86.439896] Dynamic subtest fds: SUCCESS (8.153s)
[86.454968] Starting dynamic subtest: userptr
[88.889134] Dynamic subtest userptr: SUCCESS (2.448s)
[88.890557] Subtest engines: SUCCESS (22.145s)
[89.164597] [027/162] (914s left) gem_exec_store (basic)
[89.251921] Starting subtest: basic
[89.319605] Subtest basic: SUCCESS (0.068s)
[89.604925] [028/162] (914s left) gem_flink_basic (bad-flink)
[89.673949] Starting subtest: bad-flink
[89.675524] Subtest bad-flink: SUCCESS (0.000s)
[89.808610] [029/162] (914s left) gem_flink_basic (bad-open)
[89.879127] Starting subtest: bad-open
[89.880287] Subtest bad-open: SUCCESS (0.000s)
[90.023845] [030/162] (914s left) gem_flink_basic (basic)
[90.091705] Starting subtest: basic
[90.092735] Subtest basic: SUCCESS (0.000s)
[90.282988] [031/162] (913s left) gem_flink_basic (double-flink)
[90.349342] Starting subtest: double-flink
[90.350261] Subtest double-flink: SUCCESS (0.000s)
[90.524196] [032/162] (913s left) gem_flink_basic (flink-lifetime)
[90.592639] Starting subtest: flink-lifetime
[90.597121] Subtest flink-lifetime: SUCCESS (0.004s)
[90.770709] [033/162] (913s left) gem_huc_copy (huc-copy)
[90.851865] Starting subtest: huc-copy
[90.853946] Subtest huc-copy: SKIP (0.000s)
[91.036717] [034/162] (913s left) gem_linear_blits (basic)
[91.236386] Starting subtest: basic
[91.251271] Subtest basic: SUCCESS (0.015s)
[91.458981] [035/162] (913s left) gem_mmap (basic)
[91.598284] Starting subtest: basic
[91.602900] Subtest basic: SUCCESS (0.001s)
[91.732691] [036/162] (912s left) gem_mmap_gtt (basic)
[91.803236] Starting subtest: basic
[91.805892] Subtest basic: SUCCESS (0.002s)
[91.928968] [037/162] (912s left) gem_render_linear_blits (basic)
[92.005179] Starting subtest: basic
[92.046856] Subtest basic: SUCCESS (0.041s)
[92.145051] [038/162] (912s left) gem_render_tiled_blits (basic)
[92.224987] Starting subtest: basic
[92.276389] Subtest basic: SUCCESS (0.051s)
[92.388698] [039/162] (912s left) gem_ringfill (basic-all)
[92.669201] Starting subtest: basic-all
[93.841802] Subtest basic-all: SUCCESS (1.163s)
[94.016271] [040/162] (910s left) gem_softpin (allocator-basic)
[94.093117] Starting subtest: allocator-basic
[94.264848] Subtest allocator-basic: SUCCESS (0.164s)
[94.442368] [041/162] (910s left) gem_softpin (allocator-basic-reserve)
[94.521164] Starting subtest: allocator-basic-reserve
[94.694958] Subtest allocator-basic-reserve: SUCCESS (0.165s)
[94.884782] [042/162] (910s left) gem_softpin (safe-alignment)
[94.964262] Starting subtest: safe-alignment
[94.981201] Subtest safe-alignment: SUCCESS (0.018s)
[95.211197] [043/162] (910s left) gem_sync (basic-all)
[95.314223] Starting subtest: basic-all
[97.425465] Subtest basic-all: SUCCESS (2.111s)
[97.710290] [044/162] (907s left) gem_sync (basic-each)
[97.797390] Starting subtest: basic-each
[100.060802] Subtest basic-each: SUCCESS (2.263s)
[100.416979] [045/162] (904s left) gem_tiled_blits (basic)
[100.578345] Starting subtest: basic
[100.598804] Subtest basic: SUCCESS (0.020s)
[100.805338] [046/162] (904s left) gem_tiled_fence_blits (basic)
[101.044358] Starting subtest: basic
[101.057667] Subtest basic: SUCCESS (0.013s)
[101.257042] [047/162] (904s left) gem_tiled_pread_basic
[101.691802] [048/162] (903s left) gem_wait (busy@all-engines)
[101.788574] Starting subtest: busy
[101.789681] Starting dynamic subtest: all-engines
[102.318301] Dynamic subtest all-engines: SUCCESS (0.530s)
[102.319363] Subtest busy: SUCCESS (0.530s)
[102.618185] [049/162] (903s left) gem_wait (wait@all-engines)
[102.713907] Starting subtest: wait
[102.715150] Starting dynamic subtest: all-engines
[103.763939] Dynamic subtest all-engines: SUCCESS (1.047s)
[103.767400] Subtest wait: SUCCESS (1.049s)
[104.096564] [050/162] (901s left) i915_getparams_basic (basic-eu-total)
[104.163738] Starting subtest: basic-eu-total
[104.166108] Subtest basic-eu-total: SUCCESS (0.000s)
[104.307204] [051/162] (901s left) i915_getparams_basic (basic-subslice-total)
[104.374103] Starting subtest: basic-subslice-total
[104.376852] Subtest basic-subslice-total: SUCCESS (0.000s)
[104.512140] [052/162] (901s left) i915_hangman (error-state-basic)
[104.599377] Starting subtest: error-state-basic
[104.608315] Subtest error-state-basic: SUCCESS (0.008s)
[104.742362] [053/162] (901s left) i915_pciid
[104.971232] [054/162] (901s left) intel_hwmon (hwmon-read)
[105.041606] Subtest hwmon-read: SKIP (0.000s)
[105.128902] [055/162] (901s left) intel_hwmon (hwmon-write)
[105.197480] Subtest hwmon-write: SKIP (0.000s)
[105.277743] [056/162] (900s left) kms_addfb_basic (addfb25-4-tiled)
[105.424213] Starting subtest: addfb25-4-tiled
[105.425206] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[105.553823] [057/162] (900s left) kms_addfb_basic (addfb25-bad-modifier)
[105.676841] Starting subtest: addfb25-bad-modifier
[105.678149] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[105.858623] [058/162] (900s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[105.982472] Starting subtest: addfb25-framebuffer-vs-set-tiling
[105.983631] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[106.126714] [059/162] (900s left) kms_addfb_basic (addfb25-modifier-no-flag)
[106.256493] Starting subtest: addfb25-modifier-no-flag
[106.257630] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[106.403562] [060/162] (900s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[106.532134] Starting subtest: addfb25-x-tiled-legacy
[106.533147] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[106.678529] [061/162] (899s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[106.802470] Starting subtest: addfb25-x-tiled-mismatch-legacy
[106.803581] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[106.987248] [062/162] (899s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[107.108430] Starting subtest: addfb25-yf-tiled-legacy
[107.109511] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[107.256331] [063/162] (899s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[107.380537] Starting subtest: addfb25-y-tiled-legacy
[107.381624] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[107.569340] [064/162] (899s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[107.691412] Starting subtest: addfb25-y-tiled-small-legacy
[107.692526] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[107.873472] [065/162] (899s left) kms_addfb_basic (bad-pitch-0)
[107.943361] Starting subtest: bad-pitch-0
[107.944321] Subtest bad-pitch-0: SUCCESS (0.000s)
[108.168308] [066/162] (898s left) kms_addfb_basic (bad-pitch-1024)
[108.237368] Starting subtest: bad-pitch-1024
[108.238356] Subtest bad-pitch-1024: SUCCESS (0.000s)
[108.460651] [067/162] (898s left) kms_addfb_basic (bad-pitch-128)
[108.528706] Starting subtest: bad-pitch-128
[108.529839] Subtest bad-pitch-128: SUCCESS (0.000s)
[108.762821] [068/162] (898s left) kms_addfb_basic (bad-pitch-256)
[108.832389] Starting subtest: bad-pitch-256
[108.833354] Subtest bad-pitch-256: SUCCESS (0.000s)
[109.078277] [069/162] (898s left) kms_addfb_basic (bad-pitch-32)
[109.147388] Starting subtest: bad-pitch-32
[109.148442] Subtest bad-pitch-32: SUCCESS (0.000s)
[109.354282] [070/162] (897s left) kms_addfb_basic (bad-pitch-63)
[109.423190] Starting subtest: bad-pitch-63
[109.424260] Subtest bad-pitch-63: SUCCESS (0.000s)
[109.649079] [071/162] (897s left) kms_addfb_basic (bad-pitch-65536)
[109.717289] Starting subtest: bad-pitch-65536
[109.718389] Subtest bad-pitch-65536: SUCCESS (0.000s)
[109.943469] [072/162] (897s left) kms_addfb_basic (bad-pitch-999)
[110.015569] Starting subtest: bad-pitch-999
[110.017265] Subtest bad-pitch-999: SUCCESS (0.000s)
[110.272356] [073/162] (897s left) kms_addfb_basic (basic)
[110.343415] Starting subtest: basic
[110.344483] Subtest basic: SUCCESS (0.000s)
[110.518991] [074/162] (897s left) kms_addfb_basic (basic-x-tiled-legacy)
[110.589642] Starting subtest: basic-x-tiled-legacy
[110.590650] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[110.802635] [075/162] (896s left) kms_addfb_basic (basic-y-tiled-legacy)
[110.872663] Starting subtest: basic-y-tiled-legacy
[110.873850] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[111.114935] [076/162] (896s left) kms_addfb_basic (bo-too-small)
[111.238793] Starting subtest: bo-too-small
[111.239992] Subtest bo-too-small: SUCCESS (0.000s)
[111.375969] [077/162] (896s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[111.500868] Starting subtest: bo-too-small-due-to-tiling
[111.502018] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[111.644339] [078/162] (896s left) kms_addfb_basic (clobberred-modifier)
[111.712531] Starting subtest: clobberred-modifier
[111.713830] Subtest clobberred-modifier: SUCCESS (0.000s)
[111.935881] [079/162] (896s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[112.005058] Starting subtest: framebuffer-vs-set-tiling
[112.006294] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[112.239754] [080/162] (895s left) kms_addfb_basic (invalid-get-prop)
[112.310205] Starting subtest: invalid-get-prop
[112.311165] Subtest invalid-get-prop: SUCCESS (0.000s)
[112.543615] [081/162] (895s left) kms_addfb_basic (invalid-get-prop-any)
[112.611298] Starting subtest: invalid-get-prop-any
[112.612279] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[112.823061] [082/162] (895s left) kms_addfb_basic (invalid-set-prop)
[112.893471] Starting subtest: invalid-set-prop
[112.894528] Subtest invalid-set-prop: SUCCESS (0.000s)
[113.121686] [083/162] (895s left) kms_addfb_basic (invalid-set-prop-any)
[113.190483] Starting subtest: invalid-set-prop-any
[113.191563] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[113.430678] [084/162] (895s left) kms_addfb_basic (no-handle)
[113.498130] Starting subtest: no-handle
[113.499233] Subtest no-handle: SUCCESS (0.000s)
[113.737415] [085/162] (894s left) kms_addfb_basic (size-max)
[113.861093] Starting subtest: size-max
[113.862120] Subtest size-max: SUCCESS (0.000s)
[114.019142] [086/162] (894s left) kms_addfb_basic (small-bo)
[114.144156] Starting subtest: small-bo
[114.145098] Subtest small-bo: SUCCESS (0.000s)
[114.299310] [087/162] (894s left) kms_addfb_basic (tile-pitch-mismatch)
[114.369095] Starting subtest: tile-pitch-mismatch
[114.370211] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[114.596999] [088/162] (894s left) kms_addfb_basic (too-high)
[114.722743] Starting subtest: too-high
[114.723829] Subtest too-high: SUCCESS (0.001s)
[114.902793] [089/162] (893s left) kms_addfb_basic (too-wide)
[115.026273] Starting subtest: too-wide
[115.027399] Subtest too-wide: SUCCESS (0.000s)
[115.206768] [090/162] (893s left) kms_addfb_basic (unused-handle)
[115.274787] Starting subtest: unused-handle
[115.275923] Subtest unused-handle: SUCCESS (0.000s)
[115.500882] [091/162] (893s left) kms_addfb_basic (unused-modifier)
[115.569288] Starting subtest: unused-modifier
[115.570411] Subtest unused-modifier: SUCCESS (0.000s)
[115.799594] [092/162] (893s left) kms_addfb_basic (unused-offsets)
[115.866671] Starting subtest: unused-offsets
[115.867642] Subtest unused-offsets: SUCCESS (0.000s)
[116.096421] [093/162] (893s left) kms_addfb_basic (unused-pitches)
[116.164490] Starting subtest: unused-pitches
[116.165515] Subtest unused-pitches: SUCCESS (0.000s)
[116.387798] [094/162] (892s left) kms_busy (basic)
[116.517474] Starting subtest: basic
[116.518523] Starting dynamic subtest: flip
[117.390165] Dynamic subtest flip: SUCCESS (0.871s)
[117.413834] Starting dynamic subtest: modeset
[121.101351] Dynamic subtest modeset: SUCCESS (3.708s)
[121.104480] Subtest basic: SUCCESS (4.583s)
[122.071855] [095/162] (887s left) kms_prop_blob (basic)
[122.140517] Starting subtest: basic
[122.141634] Subtest basic: SUCCESS (0.000s)
[122.281970] [096/162] (887s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[122.405248] Starting subtest: basic-busy-flip-before-cursor-atomic
[122.407235] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[122.550310] [097/162] (887s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[122.676216] Starting subtest: basic-busy-flip-before-cursor-legacy
[122.678486] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[122.805574] [098/162] (886s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[122.928477] Starting subtest: basic-flip-after-cursor-atomic
[124.191725] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.258s)
[125.135836] [099/162] (884s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[125.319496] Starting subtest: basic-flip-after-cursor-legacy
[126.662524] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.339s)
[127.615255] [100/162] (882s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[127.740231] Starting subtest: basic-flip-after-cursor-varying-size
[129.017916] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.266s)
[130.015221] [101/162] (879s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[130.137257] Starting subtest: basic-flip-before-cursor-atomic
[131.416325] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.276s)
[132.348186] [102/162] (877s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[132.470294] Starting subtest: basic-flip-before-cursor-legacy
[133.774351] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.301s)
[134.712439] [103/162] (875s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[134.837259] Starting subtest: basic-flip-before-cursor-varying-size
[136.113148] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.271s)
[137.014604] [104/162] (873s left) kms_dsc (dsc-basic)
[137.135340] Subtest dsc-basic: SKIP (0.000s)
[137.219546] [105/162] (873s left) kms_flip (basic-flip-vs-dpms)
[137.343416] Starting subtest: basic-flip-vs-dpms
[137.345954] Starting dynamic subtest: A-eDP1
[140.163920] Dynamic subtest A-eDP1: SUCCESS (2.813s)
[140.193470] Starting dynamic subtest: B-eDP1
[142.571581] Dynamic subtest B-eDP1: SUCCESS (2.401s)
[142.640701] Starting dynamic subtest: C-eDP1
[144.896675] Dynamic subtest C-eDP1: SUCCESS (2.322s)
[144.907266] Subtest basic-flip-vs-dpms: SUCCESS (7.563s)
[145.888139] [106/162] (864s left) kms_flip (basic-flip-vs-modeset)
[146.015230] Starting subtest: basic-flip-vs-modeset
[146.019481] Starting dynamic subtest: A-eDP1
[148.727897] Dynamic subtest A-eDP1: SUCCESS (2.706s)
[148.783286] Starting dynamic subtest: B-eDP1
[151.066329] Dynamic subtest B-eDP1: SUCCESS (2.331s)
[151.101424] Starting dynamic subtest: C-eDP1
[153.381530] Dynamic subtest C-eDP1: SUCCESS (2.310s)
[153.391732] Subtest basic-flip-vs-modeset: SUCCESS (7.376s)
[154.262789] [107/162] (856s left) kms_flip (basic-flip-vs-wf_vblank)
[154.387607] Starting subtest: basic-flip-vs-wf_vblank
[154.389920] Starting dynamic subtest: A-eDP1
[156.541279] Dynamic subtest A-eDP1: SUCCESS (2.151s)
[156.567564] Starting dynamic subtest: B-eDP1
[158.731956] Dynamic subtest B-eDP1: SUCCESS (2.189s)
[158.754143] Starting dynamic subtest: C-eDP1
[160.917753] Dynamic subtest C-eDP1: SUCCESS (2.184s)
[160.921322] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.535s)
[162.229275] [108/162] (848s left) kms_flip (basic-plain-flip)
[162.354824] Starting subtest: basic-plain-flip
[162.357394] Starting dynamic subtest: A-eDP1
[164.307566] Dynamic subtest A-eDP1: SUCCESS (1.949s)
[164.368662] Starting dynamic subtest: B-eDP1
[166.264673] Dynamic subtest B-eDP1: SUCCESS (1.944s)
[166.326669] Starting dynamic subtest: C-eDP1
[168.234283] Dynamic subtest C-eDP1: SUCCESS (1.965s)
[168.245639] Subtest basic-plain-flip: SUCCESS (5.891s)
[169.610152] [109/162] (841s left) kms_force_connector_basic (force-connector-state)
[169.713105] Starting subtest: force-connector-state
[170.252561] Subtest force-connector-state: SUCCESS (0.538s)
[171.510997] [110/162] (839s left) kms_force_connector_basic (force-edid)
[171.609299] Starting subtest: force-edid
[171.644268] Subtest force-edid: SUCCESS (0.034s)
[171.860942] [111/162] (838s left) kms_force_connector_basic (force-load-detect)
[171.960071] Starting subtest: force-load-detect
[171.962133] Subtest force-load-detect: SKIP (0.000s)
[172.141438] [112/162] (838s left) kms_force_connector_basic (prune-stale-modes)
[172.242517] Starting subtest: prune-stale-modes
[172.280264] Subtest prune-stale-modes: SUCCESS (0.038s)
[172.498998] [113/162] (838s left) kms_frontbuffer_tracking (basic)
[172.630218] Starting subtest: basic
[176.257958] Subtest basic: SUCCESS (3.626s)
[176.457474] [114/162] (834s left) kms_hdmi_inject (inject-audio)
[176.845332] Starting subtest: inject-audio
[176.975296] Subtest inject-audio: SUCCESS (0.130s)
[177.812216] [115/162] (833s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[177.937333] Starting subtest: compare-crc-sanitycheck-xr24
[177.940758] Starting dynamic subtest: pipe-A-eDP-1
[178.710089] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.765s)
[178.786108] Starting dynamic subtest: pipe-B-eDP-1
[180.125306] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.392s)
[180.209144] Starting dynamic subtest: pipe-C-eDP-1
[181.538632] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.392s)
[181.542640] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.598s)
[182.454037] [116/162] (828s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[182.580669] Starting subtest: compare-crc-sanitycheck-nv12
[182.581702] Starting dynamic subtest: pipe-A-eDP-1
[182.583697] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[182.599630] Starting dynamic subtest: pipe-B-eDP-1
[182.602086] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[182.616979] Starting dynamic subtest: pipe-C-eDP-1
[182.619605] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[182.622057] Subtest compare-crc-sanitycheck-nv12: SKIP (0.009s)
[182.703967] [117/162] (828s left) kms_pipe_crc_basic (hang-read-crc)
[182.827649] Starting subtest: hang-read-crc
[182.830142] Starting dynamic subtest: pipe-A-eDP-1
[185.424676] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.590s)
[185.509884] Starting dynamic subtest: pipe-B-eDP-1
[188.686360] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.240s)
[188.764751] Starting dynamic subtest: pipe-C-eDP-1
[191.958908] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.265s)
[191.968563] Subtest hang-read-crc: SUCCESS (9.133s)
[192.899447] [118/162] (818s left) kms_pipe_crc_basic (nonblocking-crc)
[193.026348] Starting subtest: nonblocking-crc
[193.027452] Starting dynamic subtest: pipe-A-eDP-1
[194.058100] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.024s)
[194.138756] Starting dynamic subtest: pipe-B-eDP-1
[195.718135] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.652s)
[195.770849] Starting dynamic subtest: pipe-C-eDP-1
[197.476620] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.743s)
[197.479427] Subtest nonblocking-crc: SUCCESS (4.451s)
[198.424817] [119/162] (813s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[198.547412] Starting subtest: nonblocking-crc-frame-sequence
[198.548566] Starting dynamic subtest: pipe-A-eDP-1
[199.528928] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.975s)
[199.592491] Starting dynamic subtest: pipe-B-eDP-1
[201.205492] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.658s)
[201.278660] Starting dynamic subtest: pipe-C-eDP-1
[202.876528] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.653s)
[202.879352] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.328s)
[203.847278] [120/162] (807s left) kms_pipe_crc_basic (read-crc)
[203.973720] Starting subtest: read-crc
[203.974843] Starting dynamic subtest: pipe-A-eDP-1
[204.866819] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.885s)
[204.946885] Starting dynamic subtest: pipe-B-eDP-1
[206.510222] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.626s)
[206.599455] Starting dynamic subtest: pipe-C-eDP-1
[208.068661] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.541s)
[208.071554] Subtest read-crc: SUCCESS (4.094s)
[209.045759] [121/162] (802s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[209.171614] Starting subtest: read-crc-frame-sequence
[209.172727] Starting dynamic subtest: pipe-A-eDP-1
[210.096702] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.920s)
[210.174385] Starting dynamic subtest: pipe-B-eDP-1
[211.733304] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.616s)
[211.820346] Starting dynamic subtest: pipe-C-eDP-1
[213.265277] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.515s)
[213.275553] Subtest read-crc-frame-sequence: SUCCESS (4.098s)
[214.183665] [122/162] (797s left) kms_pm_backlight (basic-brightness)
[214.312737] Starting subtest: basic-brightness
[214.362152] Starting dynamic subtest: eDP-1
[214.936835] Dynamic subtest eDP-1: SUCCESS (0.571s)
[214.939511] Subtest basic-brightness: SUCCESS (0.625s)
[215.910633] [123/162] (795s left) kms_pm_rpm (basic-pci-d3-state)
[217.656582] Starting subtest: basic-pci-d3-state
[218.287276] Subtest basic-pci-d3-state: SUCCESS (0.629s)
[218.474761] [124/162] (793s left) kms_pm_rpm (basic-rte)
[218.544459] Starting subtest: basic-rte
[220.790557] Subtest basic-rte: SUCCESS (2.246s)
[222.197112] [125/162] (789s left) kms_psr (psr-primary-page-flip)
[222.326529] Starting subtest: psr-primary-page-flip
[222.329395] Starting dynamic subtest: eDP-1
[224.427680] Dynamic subtest eDP-1: SUCCESS (2.097s)
[224.440508] Subtest psr-primary-page-flip: SUCCESS (2.105s)
[225.374885] [126/162] (786s left) kms_psr (psr-cursor-plane-move)
[225.501783] Starting subtest: psr-cursor-plane-move
[225.502945] Starting dynamic subtest: eDP-1
[227.459764] Dynamic subtest eDP-1: SUCCESS (1.956s)
[227.472192] Subtest psr-cursor-plane-move: SUCCESS (1.962s)
[228.384663] [127/162] (783s left) kms_psr (psr-sprite-plane-onoff)
[228.510739] Starting subtest: psr-sprite-plane-onoff
[228.512015] Starting dynamic subtest: eDP-1
[230.464295] Dynamic subtest eDP-1: SUCCESS (1.951s)
[230.476291] Subtest psr-sprite-plane-onoff: SUCCESS (1.957s)
[231.418441] [128/162] (780s left) kms_psr (psr-primary-mmap-gtt)
[231.545582] Starting subtest: psr-primary-mmap-gtt
[231.546622] Starting dynamic subtest: eDP-1
[233.500855] Dynamic subtest eDP-1: SUCCESS (1.949s)
[233.503881] Subtest psr-primary-mmap-gtt: SUCCESS (1.957s)
[234.431578] [129/162] (778s left) kms_setmode (basic-clone-single-crtc)
[234.505223] Starting subtest: basic-clone-single-crtc
[234.508632] Subtest basic-clone-single-crtc: SKIP (0.000s)
[234.624030] [130/162] (777s left) i915_pm_rps (basic-api)
[234.706112] Starting subtest: basic-api
[234.711960] Subtest basic-api: SUCCESS (0.006s)
[234.856044] [131/162] (777s left) prime_self_import (basic-llseek-bad)
[234.924368] Starting subtest: basic-llseek-bad
[234.928320] Subtest basic-llseek-bad: SUCCESS (0.004s)
[235.096555] [132/162] (777s left) prime_self_import (basic-llseek-size)
[235.162977] Starting subtest: basic-llseek-size
[235.168486] Subtest basic-llseek-size: SUCCESS (0.005s)
[235.342691] [133/162] (777s left) prime_self_import (basic-with_fd_dup)
[235.408985] Starting subtest: basic-with_fd_dup
[235.414161] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[235.594082] [134/162] (777s left) prime_self_import (basic-with_one_bo)
[235.660982] Starting subtest: basic-with_one_bo
[235.667282] Subtest basic-with_one_bo: SUCCESS (0.006s)
[235.775037] [135/162] (777s left) prime_self_import (basic-with_one_bo_two_files)
[235.842509] Starting subtest: basic-with_one_bo_two_files
[235.847540] Subtest basic-with_one_bo_two_files: SUCCESS (0.005s)
[235.996589] [136/162] (777s left) prime_self_import (basic-with_two_bos)
[236.062574] Starting subtest: basic-with_two_bos
[236.068204] Subtest basic-with_two_bos: SUCCESS (0.005s)
[236.227349] [137/162] (776s left) prime_vgem (basic-fence-flip)
[236.309111] Starting subtest: basic-fence-flip
[236.824237] Subtest basic-fence-flip: SUCCESS (0.513s)
[237.003129] [138/162] (776s left) prime_vgem (basic-fence-mmap)
[237.081326] Starting subtest: basic-fence-mmap
[237.115345] Subtest basic-fence-mmap: SUCCESS (0.034s)
[237.303566] [139/162] (776s left) prime_vgem (basic-fence-read)
[237.383781] Starting subtest: basic-fence-read
[237.461591] Subtest basic-fence-read: SUCCESS (0.078s)
[237.613555] [140/162] (775s left) prime_vgem (basic-gtt)
[237.690998] Starting subtest: basic-gtt
[237.706797] Subtest basic-gtt: SUCCESS (0.016s)
[237.897363] [141/162] (775s left) prime_vgem (basic-read)
[237.973463] Starting subtest: basic-read
[238.011520] Subtest basic-read: SUCCESS (0.038s)
[238.139670] [142/162] (775s left) prime_vgem (basic-write)
[238.216502] Starting subtest: basic-write
[238.256219] Subtest basic-write: SUCCESS (0.040s)
[238.408236] [143/162] (775s left) vgem_basic (setversion)
[238.477586] Starting subtest: setversion
[238.479728] Subtest setversion: SUCCESS (0.000s)
[238.596871] [144/162] (775s left) vgem_basic (create)
[238.664465] Starting subtest: create
[238.665587] Subtest create: SUCCESS (0.000s)
[238.784470] [145/162] (775s left) vgem_basic (debugfs)
[238.851946] Starting subtest: debugfs
[238.853062] Subtest debugfs: SUCCESS (0.001s)
[238.979483] [146/162] (774s left) vgem_basic (dmabuf-export)
[239.045504] Starting subtest: dmabuf-export
[239.050453] Subtest dmabuf-export: SUCCESS (0.004s)
[239.207406] [147/162] (774s left) vgem_basic (dmabuf-fence)
[239.276489] Starting subtest: dmabuf-fence
[239.277477] Subtest dmabuf-fence: SUCCESS (0.001s)
[239.398340] [148/162] (774s left) vgem_basic (dmabuf-fence-before)
[239.470360] Starting subtest: dmabuf-fence-before
[239.471448] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[239.592488] [149/162] (774s left) vgem_basic (dmabuf-mmap)
[239.662459] Starting subtest: dmabuf-mmap
[239.677329] Subtest dmabuf-mmap: SUCCESS (0.015s)
[239.806419] [150/162] (774s left) vgem_basic (mmap)
[239.876177] Starting subtest: mmap
[239.884537] Subtest mmap: SUCCESS (0.010s)
[240.009779] [151/162] (774s left) vgem_basic (second-client)
[240.075409] Starting subtest: second-client
[240.103411] Subtest second-client: SUCCESS (0.027s)
[240.210662] [152/162] (774s left) vgem_basic (sysfs)
[240.278408] Starting subtest: sysfs
[240.279362] Subtest sysfs: SUCCESS (0.001s)
[240.399633] [153/162] (774s left) core_hotunplug (unbind-rebind)
[240.563123] Starting subtest: unbind-rebind
[245.566067] Subtest unbind-rebind: SUCCESS (5.003s)
[245.647060] [154/162] (768s left) vgem_basic (unload)
[245.723101] Starting subtest: unload
[274.804841] Subtest unload: SUCCESS (29.045s)
[274.947148] [155/162] (739s left) i915_module_load (reload)
[275.011779] Starting subtest: reload
[278.997778] Subtest reload: SUCCESS (3.983s)
[279.073942] [156/162] (735s left) gem_lmem_swapping (basic)
[279.160059] Subtest basic: SKIP (0.000s)
[279.293720] [157/162] (735s left) gem_lmem_swapping (parallel-random-engines)
[279.417239] Subtest parallel-random-engines: SKIP (0.000s)
[279.500503] [158/162] (735s left) gem_lmem_swapping (random-engines)
[279.578319] Subtest random-engines: SKIP (0.000s)
[279.664308] [159/162] (735s left) gem_lmem_swapping (verify-random)
[279.740278] Subtest verify-random: SKIP (0.000s)
[279.831946] [160/162] (735s left) i915_pm_rpm (module-reload)
[282.166688] Starting subtest: module-reload
[294.869829] Subtest module-reload: SUCCESS (12.699s)
[294.917459] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card1
[294.919539] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[294.925191] [161/162] (720s left) i915_selftest (live)
[294.998709] Starting subtest: live
[295.001836] Starting dynamic subtest: sanitycheck
[297.612726] Dynamic subtest sanitycheck: SUCCESS (2.603s)
[297.628354] Starting dynamic subtest: uncore
[300.168719] Dynamic subtest uncore: SUCCESS (2.554s)
[300.180987] Starting dynamic subtest: workarounds
[302.986572] Dynamic subtest workarounds: SUCCESS (2.816s)
[302.999293] Starting dynamic subtest: gt_engines
[306.142342] Dynamic subtest gt_engines: SUCCESS (3.153s)
[306.166190] Starting dynamic subtest: gt_timelines
[318.769801] Dynamic subtest gt_timelines: SUCCESS (12.625s)
[318.789805] Starting dynamic subtest: gt_contexts
[321.726994] Dynamic subtest gt_contexts: SUCCESS (2.955s)
[321.744699] Starting dynamic subtest: gt_lrc