[37.740309] Initializing watchdogs
[37.740442]   /dev/watchdog0
[37.772952] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:1916 Intel Skylake (Gen9) Skylake GT2 [HD Graphics 520]
[37.782536] [001/162] (960s left) i915_module_load (load)
[37.864994] Starting subtest: load
[39.989444] Subtest load: SUCCESS (2.124s)
[40.048034] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[40.051453] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[40.055681] [002/162] (958s left) core_auth (basic-auth)
[40.142826] Starting subtest: basic-auth
[40.145291] Subtest basic-auth: SUCCESS (0.001s)
[40.282467] [003/162] (958s left) core_debugfs (read-all-entries)
[40.350973] Starting subtest: read-all-entries
[40.513636] Subtest read-all-entries: SUCCESS (0.163s)
[40.628728] [004/162] (957s left) core_sysfs (read-all-entries)
[40.699823] Starting subtest: read-all-entries
[40.705720] Subtest read-all-entries: SUCCESS (0.007s)
[40.891704] [005/162] (957s left) fbdev (eof)
[40.963214] Starting subtest: eof
[40.964780] Subtest eof: SUCCESS (0.001s)
[41.078790] [006/162] (957s left) fbdev (info)
[41.144793] Starting subtest: info
[41.146659] Subtest info: SUCCESS (0.000s)
[41.289971] [007/162] (957s left) fbdev (nullptr)
[41.361972] Starting subtest: nullptr
[41.363208] Subtest nullptr: SUCCESS (0.000s)
[41.462977] [008/162] (957s left) fbdev (read)
[41.545526] Starting subtest: read
[42.433509] Subtest read: SUCCESS (0.888s)
[42.566976] [009/162] (956s left) fbdev (write)
[42.645494] Starting subtest: write
[43.126044] Subtest write: SUCCESS (0.472s)
[43.231366] [010/162] (955s left) gem_basic (bad-close)
[43.304063] Starting subtest: bad-close
[43.306279] Subtest bad-close: SUCCESS (0.000s)
[43.412584] [011/162] (955s left) gem_basic (create-close)
[43.487060] Starting subtest: create-close
[43.489227] Subtest create-close: SUCCESS (0.000s)
[43.626872] [012/162] (955s left) gem_basic (create-fd-close)
[43.696733] Starting subtest: create-fd-close
[43.698791] Subtest create-fd-close: SUCCESS (0.000s)
[43.882260] [013/162] (955s left) gem_busy (busy@all-engines)
[43.986576] Starting subtest: busy
[43.987647] Starting dynamic subtest: all-engines
[44.054495] Dynamic subtest all-engines: SUCCESS (0.069s)
[44.055637] Subtest busy: SUCCESS (0.069s)
[44.603436] [014/162] (954s left) gem_close_race (basic-process)
[44.698768] Starting subtest: basic-process
[44.766901] Subtest basic-process: SUCCESS (0.068s)
[45.031603] [015/162] (954s left) gem_close_race (basic-threads)
[45.124334] Starting subtest: basic-threads
[46.335963] Subtest basic-threads: SUCCESS (1.212s)
[46.625361] [016/162] (952s left) gem_ctx_create (basic)
[46.715471] Starting subtest: basic
[46.716855] Subtest basic: SUCCESS (0.000s)
[47.059301] [017/162] (952s left) gem_ctx_create (basic-files)
[47.149243] Starting subtest: basic-files
[49.172257] Subtest basic-files: SUCCESS (2.023s)
[49.482701] [018/162] (950s left) gem_ctx_exec (basic)
[49.557597] Starting subtest: basic
[49.566267] Subtest basic: SUCCESS (0.009s)
[49.790085] [019/162] (949s left) gem_exec_basic (basic)
[49.872386] Starting subtest: basic
[49.873408] Starting dynamic subtest: rcs0-smem
[49.875440] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[49.892345] Starting dynamic subtest: bcs0-smem
[49.893572] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[49.908078] Starting dynamic subtest: vcs0-smem
[49.909366] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[49.923693] Starting dynamic subtest: vecs0-smem
[49.924994] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[49.926309] Subtest basic: SUCCESS (0.012s)
[50.193523] [020/162] (949s left) gem_exec_create (basic)
[50.283076] Starting subtest: basic
[50.284148] Starting dynamic subtest: smem
[52.325231] Dynamic subtest smem: SUCCESS (2.044s)
[52.326343] Subtest basic: SUCCESS (2.044s)
[52.633586] [021/162] (947s left) gem_exec_fence (basic-busy)
[52.885129] Starting subtest: basic-busy
[52.886497] Starting dynamic subtest: rcs0
[52.916418] Dynamic subtest rcs0: SUCCESS (0.032s)
[52.933647] Starting dynamic subtest: bcs0
[52.943310] Dynamic subtest bcs0: SUCCESS (0.027s)
[52.958891] Starting dynamic subtest: vcs0
[52.964148] Dynamic subtest vcs0: SUCCESS (0.021s)
[52.978667] Starting dynamic subtest: vecs0
[52.989288] Dynamic subtest vecs0: SUCCESS (0.025s)
[52.990606] Subtest basic-busy: SUCCESS (0.107s)
[53.804813] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[53.809929] [022/162] (946s left) gem_exec_fence (basic-wait)
[54.038730] Starting subtest: basic-wait
[54.040044] Starting dynamic subtest: rcs0
[54.075701] Dynamic subtest rcs0: SUCCESS (0.036s)
[54.105466] Starting dynamic subtest: bcs0
[54.112389] Dynamic subtest bcs0: SUCCESS (0.038s)
[54.128344] Starting dynamic subtest: vcs0
[54.144368] Dynamic subtest vcs0: SUCCESS (0.032s)
[54.160021] Starting dynamic subtest: vecs0
[54.167288] Dynamic subtest vecs0: SUCCESS (0.023s)
[54.168332] Subtest basic-wait: SUCCESS (0.131s)
[54.984946] [023/162] (944s left) gem_exec_fence (basic-await)
[55.232136] Starting subtest: basic-await
[55.233457] Starting dynamic subtest: rcs0
[55.337412] Dynamic subtest rcs0: SUCCESS (0.106s)
[55.354851] Starting dynamic subtest: bcs0
[55.442437] Dynamic subtest bcs0: SUCCESS (0.105s)
[55.467314] Starting dynamic subtest: vcs0
[55.568480] Dynamic subtest vcs0: SUCCESS (0.127s)
[55.587465] Starting dynamic subtest: vecs0
[55.680224] Dynamic subtest vecs0: SUCCESS (0.112s)
[55.681396] Subtest basic-await: SUCCESS (0.453s)
[56.463947] [024/162] (943s left) gem_exec_fence (nb-await)
[56.704775] Starting subtest: nb-await
[56.706700] Starting dynamic subtest: rcs0
[56.780055] Dynamic subtest rcs0: SUCCESS (0.075s)
[56.810320] Starting dynamic subtest: bcs0
[56.859433] Dynamic subtest bcs0: SUCCESS (0.079s)
[56.918460] Starting dynamic subtest: vcs0
[56.959189] Dynamic subtest vcs0: SUCCESS (0.098s)
[57.012124] Starting dynamic subtest: vecs0
[57.055387] Dynamic subtest vecs0: SUCCESS (0.095s)
[57.061299] Subtest nb-await: SUCCESS (0.355s)
[57.897708] [025/162] (942s left) gem_exec_gttfill (basic)
[57.990644] Starting subtest: basic
[63.397922] Subtest basic: SUCCESS (5.434s)
[63.759430] [026/162] (936s left) gem_exec_parallel (engines)
[63.935097] Starting subtest: engines
[63.936159] Starting dynamic subtest: basic
[66.311912] Dynamic subtest basic: SUCCESS (2.383s)
[66.328413] Starting dynamic subtest: contexts
[70.390703] Dynamic subtest contexts: SUCCESS (4.077s)
[70.411775] Starting dynamic subtest: fds
[74.302675] Dynamic subtest fds: SUCCESS (3.924s)
[74.320687] Starting dynamic subtest: userptr
[76.880504] Dynamic subtest userptr: SUCCESS (2.563s)
[76.881796] Subtest engines: SUCCESS (12.950s)
[77.122251] [027/162] (923s left) gem_exec_store (basic)
[77.212440] Starting subtest: basic
[77.278627] Subtest basic: SUCCESS (0.067s)
[77.600245] [028/162] (922s left) gem_flink_basic (bad-flink)
[77.672039] Starting subtest: bad-flink
[77.673190] Subtest bad-flink: SUCCESS (0.000s)
[77.852721] [029/162] (922s left) gem_flink_basic (bad-open)
[77.924024] Starting subtest: bad-open
[77.925097] Subtest bad-open: SUCCESS (0.000s)
[78.060114] [030/162] (922s left) gem_flink_basic (basic)
[78.128360] Starting subtest: basic
[78.129441] Subtest basic: SUCCESS (0.000s)
[78.314550] [031/162] (922s left) gem_flink_basic (double-flink)
[78.386725] Starting subtest: double-flink
[78.387866] Subtest double-flink: SUCCESS (0.000s)
[78.582726] [032/162] (922s left) gem_flink_basic (flink-lifetime)
[78.653083] Starting subtest: flink-lifetime
[78.655337] Subtest flink-lifetime: SUCCESS (0.003s)
[78.796896] [033/162] (922s left) gem_huc_copy (huc-copy)
[78.883217] Starting subtest: huc-copy
[78.885495] Subtest huc-copy: SKIP (0.000s)
[79.105273] [034/162] (921s left) gem_linear_blits (basic)
[79.319276] Starting subtest: basic
[79.330397] Subtest basic: SUCCESS (0.013s)
[79.572264] [035/162] (921s left) gem_mmap (basic)
[79.720045] Starting subtest: basic
[79.725216] Subtest basic: SUCCESS (0.001s)
[79.897882] [036/162] (921s left) gem_mmap_gtt (basic)
[79.970932] Starting subtest: basic
[79.972132] Subtest basic: SUCCESS (0.001s)
[80.163029] [037/162] (921s left) gem_render_linear_blits (basic)
[80.248142] Starting subtest: basic
[80.296103] Subtest basic: SUCCESS (0.048s)
[80.393488] [038/162] (920s left) gem_render_tiled_blits (basic)
[80.474995] Starting subtest: basic
[80.528821] Subtest basic: SUCCESS (0.054s)
[80.626119] [039/162] (920s left) gem_ringfill (basic-all)
[80.906766] Starting subtest: basic-all
[82.082656] Subtest basic-all: SUCCESS (1.167s)
[82.226311] [040/162] (919s left) gem_softpin (allocator-basic)
[82.313129] Starting subtest: allocator-basic
[82.504050] Subtest allocator-basic: SUCCESS (0.182s)
[82.756966] [041/162] (918s left) gem_softpin (allocator-basic-reserve)
[82.841256] Starting subtest: allocator-basic-reserve
[83.015427] Subtest allocator-basic-reserve: SUCCESS (0.169s)
[83.210815] [042/162] (918s left) gem_softpin (safe-alignment)
[83.297674] Starting subtest: safe-alignment
[83.317595] Subtest safe-alignment: SUCCESS (0.020s)
[83.509559] [043/162] (918s left) gem_sync (basic-all)
[83.602684] Starting subtest: basic-all
[85.933512] Subtest basic-all: SUCCESS (2.331s)
[86.226966] [044/162] (915s left) gem_sync (basic-each)
[86.316419] Starting subtest: basic-each
[88.550987] Subtest basic-each: SUCCESS (2.235s)
[88.918556] [045/162] (913s left) gem_tiled_blits (basic)
[89.092541] Starting subtest: basic
[89.112820] Subtest basic: SUCCESS (0.020s)
[89.320622] [046/162] (912s left) gem_tiled_fence_blits (basic)
[89.596518] Starting subtest: basic
[89.610114] Subtest basic: SUCCESS (0.014s)
[89.847351] [047/162] (912s left) gem_tiled_pread_basic (basic)
[89.989137] Starting subtest: basic
[90.173190] Subtest basic: SUCCESS (0.175s)
[90.358823] [048/162] (911s left) gem_wait (busy@all-engines)
[90.462388] Starting subtest: busy
[90.463877] Starting dynamic subtest: all-engines
[90.983276] Dynamic subtest all-engines: SUCCESS (0.522s)
[90.984253] Subtest busy: SUCCESS (0.522s)
[91.307685] [049/162] (911s left) gem_wait (wait@all-engines)
[91.413094] Starting subtest: wait
[91.414269] Starting dynamic subtest: all-engines
[92.476049] Dynamic subtest all-engines: SUCCESS (1.061s)
[92.478541] Subtest wait: SUCCESS (1.063s)
[92.781232] [050/162] (909s left) i915_getparams_basic (basic-eu-total)
[92.852463] Starting subtest: basic-eu-total
[92.854382] Subtest basic-eu-total: SUCCESS (0.000s)
[93.022966] [051/162] (909s left) i915_getparams_basic (basic-subslice-total)
[93.095539] Starting subtest: basic-subslice-total
[93.097540] Subtest basic-subslice-total: SUCCESS (0.000s)
[93.271359] [052/162] (909s left) i915_hangman (error-state-basic)
[93.377324] Starting subtest: error-state-basic
[93.395428] Subtest error-state-basic: SUCCESS (0.016s)
[93.530291] [053/162] (909s left) i915_pciid
[93.770602] [054/162] (908s left) intel_hwmon (hwmon-read)
[93.842152] Subtest hwmon-read: SKIP (0.000s)
[94.006562] [055/162] (908s left) intel_hwmon (hwmon-write)
[94.077470] Subtest hwmon-write: SKIP (0.000s)
[94.176929] [056/162] (908s left) kms_addfb_basic (addfb25-4-tiled)
[94.318846] Starting subtest: addfb25-4-tiled
[94.320053] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[94.519349] [057/162] (908s left) kms_addfb_basic (addfb25-bad-modifier)
[94.652477] Starting subtest: addfb25-bad-modifier
[94.653449] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[94.807295] [058/162] (908s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[94.932649] Starting subtest: addfb25-framebuffer-vs-set-tiling
[94.933718] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[95.138272] [059/162] (908s left) kms_addfb_basic (addfb25-modifier-no-flag)
[95.264272] Starting subtest: addfb25-modifier-no-flag
[95.265401] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[95.425506] [060/162] (907s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[95.553268] Starting subtest: addfb25-x-tiled-legacy
[95.554307] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[95.707619] [061/162] (907s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[95.839214] Starting subtest: addfb25-x-tiled-mismatch-legacy
[95.840295] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[96.021896] [062/162] (907s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[96.157225] Starting subtest: addfb25-yf-tiled-legacy
[96.158194] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[96.338294] [063/162] (907s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[96.471220] Starting subtest: addfb25-y-tiled-legacy
[96.472123] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[96.662868] [064/162] (907s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[96.799657] Starting subtest: addfb25-y-tiled-small-legacy
[96.800733] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[96.936268] [065/162] (906s left) kms_addfb_basic (bad-pitch-0)
[97.007416] Starting subtest: bad-pitch-0
[97.008344] Subtest bad-pitch-0: SUCCESS (0.000s)
[97.236790] [066/162] (906s left) kms_addfb_basic (bad-pitch-1024)
[97.311433] Starting subtest: bad-pitch-1024
[97.312519] Subtest bad-pitch-1024: SUCCESS (0.000s)
[97.521617] [067/162] (906s left) kms_addfb_basic (bad-pitch-128)
[97.593646] Starting subtest: bad-pitch-128
[97.594673] Subtest bad-pitch-128: SUCCESS (0.000s)
[97.839753] [068/162] (906s left) kms_addfb_basic (bad-pitch-256)
[97.911318] Starting subtest: bad-pitch-256
[97.912369] Subtest bad-pitch-256: SUCCESS (0.000s)
[98.133087] [069/162] (906s left) kms_addfb_basic (bad-pitch-32)
[98.204457] Starting subtest: bad-pitch-32
[98.205426] Subtest bad-pitch-32: SUCCESS (0.000s)
[98.494302] [070/162] (905s left) kms_addfb_basic (bad-pitch-63)
[98.566627] Starting subtest: bad-pitch-63
[98.567740] Subtest bad-pitch-63: SUCCESS (0.000s)
[98.788537] [071/162] (905s left) kms_addfb_basic (bad-pitch-65536)
[98.881662] Starting subtest: bad-pitch-65536
[98.882748] Subtest bad-pitch-65536: SUCCESS (0.001s)
[99.127271] [072/162] (905s left) kms_addfb_basic (bad-pitch-999)
[99.203154] Starting subtest: bad-pitch-999
[99.204269] Subtest bad-pitch-999: SUCCESS (0.000s)
[99.419894] [073/162] (905s left) kms_addfb_basic (basic)
[99.493657] Starting subtest: basic
[99.494824] Subtest basic: SUCCESS (0.000s)
[99.716821] [074/162] (904s left) kms_addfb_basic (basic-x-tiled-legacy)
[99.793175] Starting subtest: basic-x-tiled-legacy
[99.794197] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[100.055717] [075/162] (904s left) kms_addfb_basic (basic-y-tiled-legacy)
[100.128864] Starting subtest: basic-y-tiled-legacy
[100.130041] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[100.324458] [076/162] (904s left) kms_addfb_basic (bo-too-small)
[100.448943] Starting subtest: bo-too-small
[100.450088] Subtest bo-too-small: SUCCESS (0.000s)
[100.641248] [077/162] (904s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[100.765979] Starting subtest: bo-too-small-due-to-tiling
[100.767353] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[100.960301] [078/162] (903s left) kms_addfb_basic (clobberred-modifier)
[101.035223] Starting subtest: clobberred-modifier
[101.036179] Subtest clobberred-modifier: SUCCESS (0.000s)
[101.224962] [079/162] (903s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[101.297907] Starting subtest: framebuffer-vs-set-tiling
[101.299092] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[101.523443] [080/162] (903s left) kms_addfb_basic (invalid-get-prop)
[101.606620] Starting subtest: invalid-get-prop
[101.607656] Subtest invalid-get-prop: SUCCESS (0.000s)
[101.871982] [081/162] (903s left) kms_addfb_basic (invalid-get-prop-any)
[101.944584] Starting subtest: invalid-get-prop-any
[101.945622] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[102.160491] [082/162] (903s left) kms_addfb_basic (invalid-set-prop)
[102.232384] Starting subtest: invalid-set-prop
[102.233392] Subtest invalid-set-prop: SUCCESS (0.000s)
[102.488022] [083/162] (902s left) kms_addfb_basic (invalid-set-prop-any)
[102.559446] Starting subtest: invalid-set-prop-any
[102.560526] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[102.754214] [084/162] (902s left) kms_addfb_basic (no-handle)
[102.825475] Starting subtest: no-handle
[102.826679] Subtest no-handle: SUCCESS (0.000s)
[103.069572] [085/162] (902s left) kms_addfb_basic (size-max)
[103.200368] Starting subtest: size-max
[103.201346] Subtest size-max: SUCCESS (0.000s)
[103.374482] [086/162] (902s left) kms_addfb_basic (small-bo)
[103.500015] Starting subtest: small-bo
[103.501161] Subtest small-bo: SUCCESS (0.000s)
[103.687579] [087/162] (902s left) kms_addfb_basic (tile-pitch-mismatch)
[103.759965] Starting subtest: tile-pitch-mismatch
[103.761162] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[103.967623] [088/162] (901s left) kms_addfb_basic (too-high)
[104.097472] Starting subtest: too-high
[104.098525] Subtest too-high: SUCCESS (0.001s)
[104.266194] [089/162] (901s left) kms_addfb_basic (too-wide)
[104.391955] Starting subtest: too-wide
[104.393210] Subtest too-wide: SUCCESS (0.000s)
[104.582057] [090/162] (901s left) kms_addfb_basic (unused-handle)
[104.655368] Starting subtest: unused-handle
[104.656360] Subtest unused-handle: SUCCESS (0.000s)
[104.867815] [091/162] (901s left) kms_addfb_basic (unused-modifier)
[104.942141] Starting subtest: unused-modifier
[104.943142] Subtest unused-modifier: SUCCESS (0.000s)
[105.162671] [092/162] (900s left) kms_addfb_basic (unused-offsets)
[105.234269] Starting subtest: unused-offsets
[105.235250] Subtest unused-offsets: SUCCESS (0.000s)
[105.473361] [093/162] (900s left) kms_addfb_basic (unused-pitches)
[105.544329] Starting subtest: unused-pitches
[105.545294] Subtest unused-pitches: SUCCESS (0.000s)
[105.756070] [094/162] (900s left) kms_busy (basic)
[105.888341] Starting subtest: basic
[105.890252] Starting dynamic subtest: flip
[106.838951] Dynamic subtest flip: SUCCESS (0.947s)
[106.892357] Starting dynamic subtest: modeset
[110.687720] Dynamic subtest modeset: SUCCESS (3.840s)
[110.690340] Subtest basic: SUCCESS (4.798s)
[111.624844] [095/162] (894s left) kms_prop_blob (basic)
[111.697502] Starting subtest: basic
[111.698460] Subtest basic: SUCCESS (0.000s)
[111.878261] [096/162] (894s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[112.002780] Starting subtest: basic-busy-flip-before-cursor-atomic
[112.005084] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[112.144593] [097/162] (894s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[112.270238] Starting subtest: basic-busy-flip-before-cursor-legacy
[112.272212] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[112.454886] [098/162] (894s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[112.579456] Starting subtest: basic-flip-after-cursor-atomic
[113.805090] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.224s)
[114.849571] [099/162] (891s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[114.978439] Starting subtest: basic-flip-after-cursor-legacy
[116.307606] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.327s)
[117.250977] [100/162] (889s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[117.376195] Starting subtest: basic-flip-after-cursor-varying-size
[118.632297] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.255s)
[119.639417] [101/162] (887s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[119.765240] Starting subtest: basic-flip-before-cursor-atomic
[121.112536] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.344s)
[122.086257] [102/162] (884s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[122.213642] Starting subtest: basic-flip-before-cursor-legacy
[123.547615] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.331s)
[124.489806] [103/162] (882s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[124.619328] Starting subtest: basic-flip-before-cursor-varying-size
[125.988230] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.364s)
[126.979774] [104/162] (880s left) kms_dsc (dsc-basic)
[127.108196] Subtest dsc-basic: SKIP (0.000s)
[127.200061] [105/162] (880s left) kms_flip (basic-flip-vs-dpms)
[127.326096] Starting subtest: basic-flip-vs-dpms
[127.329592] Starting dynamic subtest: A-eDP1
[130.131721] Dynamic subtest A-eDP1: SUCCESS (2.800s)
[130.207931] Starting dynamic subtest: B-eDP1
[132.488413] Dynamic subtest B-eDP1: SUCCESS (2.348s)
[132.572749] Starting dynamic subtest: C-eDP1
[134.807341] Dynamic subtest C-eDP1: SUCCESS (2.310s)
[134.819844] Subtest basic-flip-vs-dpms: SUCCESS (7.492s)
[135.762686] [106/162] (871s left) kms_flip (basic-flip-vs-modeset)
[135.889375] Starting subtest: basic-flip-vs-modeset
[135.892181] Starting dynamic subtest: A-eDP1
[138.669222] Dynamic subtest A-eDP1: SUCCESS (2.775s)
[138.706410] Starting dynamic subtest: B-eDP1
[141.132978] Dynamic subtest B-eDP1: SUCCESS (2.453s)
[141.211993] Starting dynamic subtest: C-eDP1
[143.468810] Dynamic subtest C-eDP1: SUCCESS (2.334s)
[143.490549] Subtest basic-flip-vs-modeset: SUCCESS (7.595s)
[144.380349] [107/162] (863s left) kms_flip (basic-flip-vs-wf_vblank)
[144.514768] Starting subtest: basic-flip-vs-wf_vblank
[144.517736] Starting dynamic subtest: A-eDP1
[146.721503] Dynamic subtest A-eDP1: SUCCESS (2.204s)
[146.744253] Starting dynamic subtest: B-eDP1
[148.899947] Dynamic subtest B-eDP1: SUCCESS (2.176s)
[148.921151] Starting dynamic subtest: C-eDP1
[151.038264] Dynamic subtest C-eDP1: SUCCESS (2.134s)
[151.048602] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.534s)
[152.381331] [108/162] (855s left) kms_flip (basic-plain-flip)
[152.512236] Starting subtest: basic-plain-flip
[152.516474] Starting dynamic subtest: A-eDP1
[154.445159] Dynamic subtest A-eDP1: SUCCESS (1.925s)
[154.505370] Starting dynamic subtest: B-eDP1
[156.416678] Dynamic subtest B-eDP1: SUCCESS (1.965s)
[156.495520] Starting dynamic subtest: C-eDP1
[158.391473] Dynamic subtest C-eDP1: SUCCESS (1.961s)
[158.403371] Subtest basic-plain-flip: SUCCESS (5.891s)
[159.838909] [109/162] (847s left) kms_force_connector_basic (force-connector-state)
[159.944868] Starting subtest: force-connector-state
[160.631669] Subtest force-connector-state: SUCCESS (0.686s)
[161.896887] [110/162] (845s left) kms_force_connector_basic (force-edid)
[162.002166] Starting subtest: force-edid
[162.039220] Subtest force-edid: SUCCESS (0.037s)
[162.268727] [111/162] (845s left) kms_force_connector_basic (force-load-detect)
[162.373292] Starting subtest: force-load-detect
[162.375344] Subtest force-load-detect: SKIP (0.000s)
[162.572218] [112/162] (845s left) kms_force_connector_basic (prune-stale-modes)
[162.674208] Starting subtest: prune-stale-modes
[162.715325] Subtest prune-stale-modes: SUCCESS (0.041s)
[162.968091] [113/162] (845s left) kms_frontbuffer_tracking (basic)
[163.101594] Starting subtest: basic
[167.028574] Subtest basic: SUCCESS (3.927s)
[167.194754] [114/162] (841s left) kms_hdmi_inject (inject-audio)
[167.647393] Starting subtest: inject-audio
[167.827977] Subtest inject-audio: SUCCESS (0.189s)
[168.567223] [115/162] (839s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[168.698278] Starting subtest: compare-crc-sanitycheck-xr24
[168.701742] Starting dynamic subtest: pipe-A-eDP-1
[169.453436] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.746s)
[169.535382] Starting dynamic subtest: pipe-B-eDP-1
[171.037593] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.558s)
[171.117502] Starting dynamic subtest: pipe-C-eDP-1
[172.380555] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.339s)
[172.383615] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.683s)
[173.388172] [116/162] (835s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[173.517162] Starting subtest: compare-crc-sanitycheck-nv12
[173.519970] Starting dynamic subtest: pipe-A-eDP-1
[173.521974] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[173.542493] Starting dynamic subtest: pipe-B-eDP-1
[173.544390] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[173.562102] Starting dynamic subtest: pipe-C-eDP-1
[173.564480] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[173.567076] Subtest compare-crc-sanitycheck-nv12: SKIP (0.014s)
[173.657658] [117/162] (834s left) kms_pipe_crc_basic (hang-read-crc)
[173.787490] Starting subtest: hang-read-crc
[173.790941] Starting dynamic subtest: pipe-A-eDP-1
[176.363296] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.570s)
[176.443892] Starting dynamic subtest: pipe-B-eDP-1
[179.743548] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.369s)
[179.809881] Starting dynamic subtest: pipe-C-eDP-1
[183.005076] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.242s)
[183.007784] Subtest hang-read-crc: SUCCESS (9.218s)
[183.930447] [118/162] (824s left) kms_pipe_crc_basic (nonblocking-crc)
[184.059998] Starting subtest: nonblocking-crc
[184.062959] Starting dynamic subtest: pipe-A-eDP-1
[185.177957] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.108s)
[185.268033] Starting dynamic subtest: pipe-B-eDP-1
[186.923567] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.735s)
[187.010771] Starting dynamic subtest: pipe-C-eDP-1
[188.686622] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.743s)
[188.689260] Subtest nonblocking-crc: SUCCESS (4.626s)
[189.688737] [119/162] (819s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[189.822260] Starting subtest: nonblocking-crc-frame-sequence
[189.825238] Starting dynamic subtest: pipe-A-eDP-1
[190.901593] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.073s)
[190.996702] Starting dynamic subtest: pipe-B-eDP-1
[192.629270] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.711s)
[192.718652] Starting dynamic subtest: pipe-C-eDP-1
[194.340602] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.696s)
[194.352350] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.521s)
[195.277372] [120/162] (813s left) kms_pipe_crc_basic (read-crc)
[195.405660] Starting subtest: read-crc
[195.406629] Starting dynamic subtest: pipe-A-eDP-1
[196.319265] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.910s)
[196.342846] Starting dynamic subtest: pipe-B-eDP-1
[197.904212] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.579s)
[197.984989] Starting dynamic subtest: pipe-C-eDP-1
[199.571791] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.646s)
[199.574241] Subtest read-crc: SUCCESS (4.168s)
[200.535784] [121/162] (808s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[200.667073] Starting subtest: read-crc-frame-sequence
[200.671416] Starting dynamic subtest: pipe-A-eDP-1
[201.637980] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.964s)
[201.721062] Starting dynamic subtest: pipe-B-eDP-1
[203.225268] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.567s)
[203.319190] Starting dynamic subtest: pipe-C-eDP-1
[204.822686] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.578s)
[204.825571] Subtest read-crc-frame-sequence: SUCCESS (4.157s)
[205.846065] [122/162] (803s left) kms_pm_backlight (basic-brightness)
[205.977590] Starting subtest: basic-brightness
[206.051434] Starting dynamic subtest: eDP-1
[206.663060] Dynamic subtest eDP-1: SUCCESS (0.608s)
[206.665862] Subtest basic-brightness: SUCCESS (0.686s)
[207.640871] [123/162] (801s left) kms_pm_rpm (basic-pci-d3-state)
[209.425612] Starting subtest: basic-pci-d3-state
[210.010465] Subtest basic-pci-d3-state: SUCCESS (0.586s)
[210.228893] [124/162] (799s left) kms_pm_rpm (basic-rte)
[210.302510] Starting subtest: basic-rte
[212.602644] Subtest basic-rte: SUCCESS (2.294s)
[214.019464] [125/162] (795s left) kms_psr (psr-primary-page-flip)
[214.155866] Starting subtest: psr-primary-page-flip
[214.159111] Starting dynamic subtest: eDP-1
[216.297527] Dynamic subtest eDP-1: SUCCESS (2.129s)
[216.299950] Subtest psr-primary-page-flip: SUCCESS (2.138s)
[217.278631] [126/162] (792s left) kms_psr (psr-cursor-plane-move)
[217.405881] Starting subtest: psr-cursor-plane-move
[217.409597] Starting dynamic subtest: eDP-1
[219.332669] Dynamic subtest eDP-1: SUCCESS (1.922s)
[219.345060] Subtest psr-cursor-plane-move: SUCCESS (1.929s)
[220.272644] [127/162] (789s left) kms_psr (psr-sprite-plane-onoff)
[220.402815] Starting subtest: psr-sprite-plane-onoff
[220.403826] Starting dynamic subtest: eDP-1
[222.446175] Dynamic subtest eDP-1: SUCCESS (2.035s)
[222.448604] Subtest psr-sprite-plane-onoff: SUCCESS (2.041s)
[223.419899] [128/162] (786s left) kms_psr (psr-primary-mmap-gtt)
[223.551409] Starting subtest: psr-primary-mmap-gtt
[223.554525] Starting dynamic subtest: eDP-1
[225.579137] Dynamic subtest eDP-1: SUCCESS (2.023s)
[225.592042] Subtest psr-primary-mmap-gtt: SUCCESS (2.031s)
[226.489360] [129/162] (783s left) kms_setmode (basic-clone-single-crtc)
[226.564272] Starting subtest: basic-clone-single-crtc
[226.567311] Subtest basic-clone-single-crtc: SKIP (0.000s)
[226.688571] [130/162] (783s left) i915_pm_rps (basic-api)
[226.774909] Starting subtest: basic-api
[226.781885] Subtest basic-api: SUCCESS (0.007s)
[227.010291] [131/162] (782s left) prime_self_import (basic-llseek-bad)
[227.080222] Starting subtest: basic-llseek-bad
[227.084434] Subtest basic-llseek-bad: SUCCESS (0.004s)
[227.222250] [132/162] (782s left) prime_self_import (basic-llseek-size)
[227.294269] Starting subtest: basic-llseek-size
[227.299808] Subtest basic-llseek-size: SUCCESS (0.008s)
[227.490581] [133/162] (782s left) prime_self_import (basic-with_fd_dup)
[227.559875] Starting subtest: basic-with_fd_dup
[227.565979] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[227.743205] [134/162] (782s left) prime_self_import (basic-with_one_bo)
[227.813885] Starting subtest: basic-with_one_bo
[227.819221] Subtest basic-with_one_bo: SUCCESS (0.005s)
[228.003657] [135/162] (782s left) prime_self_import (basic-with_one_bo_two_files)
[228.073988] Starting subtest: basic-with_one_bo_two_files
[228.079115] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[228.248385] [136/162] (782s left) prime_self_import (basic-with_two_bos)
[228.317321] Starting subtest: basic-with_two_bos
[228.322404] Subtest basic-with_two_bos: SUCCESS (0.006s)
[228.494622] [137/162] (782s left) prime_vgem (basic-fence-flip)
[228.597447] Starting subtest: basic-fence-flip
[229.119306] Subtest basic-fence-flip: SUCCESS (0.520s)
[229.291471] [138/162] (781s left) prime_vgem (basic-fence-mmap)
[229.390620] Starting subtest: basic-fence-mmap
[229.432272] Subtest basic-fence-mmap: SUCCESS (0.042s)
[229.621146] [139/162] (781s left) prime_vgem (basic-fence-read)
[229.736357] Starting subtest: basic-fence-read
[229.823170] Subtest basic-fence-read: SUCCESS (0.086s)
[229.957873] [140/162] (780s left) prime_vgem (basic-gtt)
[230.051707] Starting subtest: basic-gtt
[230.073763] Subtest basic-gtt: SUCCESS (0.022s)
[230.203949] [141/162] (780s left) prime_vgem (basic-read)
[230.310092] Starting subtest: basic-read
[230.354478] Subtest basic-read: SUCCESS (0.044s)
[230.487766] [142/162] (780s left) prime_vgem (basic-write)
[230.593930] Starting subtest: basic-write
[230.632213] Subtest basic-write: SUCCESS (0.041s)
[230.775271] [143/162] (780s left) vgem_basic (setversion)
[230.861105] Starting subtest: setversion
[230.863186] Subtest setversion: SUCCESS (0.000s)
[230.971921] [144/162] (780s left) vgem_basic (create)
[231.060802] Starting subtest: create
[231.061982] Subtest create: SUCCESS (0.001s)
[231.171684] [145/162] (780s left) vgem_basic (debugfs)
[231.260980] Starting subtest: debugfs
[231.262083] Subtest debugfs: SUCCESS (0.001s)
[231.373269] [146/162] (779s left) vgem_basic (dmabuf-export)
[231.461467] Starting subtest: dmabuf-export
[231.463402] Subtest dmabuf-export: SUCCESS (0.004s)
[231.676177] [147/162] (779s left) vgem_basic (dmabuf-fence)
[231.761084] Starting subtest: dmabuf-fence
[231.762213] Subtest dmabuf-fence: SUCCESS (0.001s)
[231.871534] [148/162] (779s left) vgem_basic (dmabuf-fence-before)
[231.961205] Starting subtest: dmabuf-fence-before
[231.962310] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[232.071574] [149/162] (779s left) vgem_basic (dmabuf-mmap)
[232.161276] Starting subtest: dmabuf-mmap
[232.175464] Subtest dmabuf-mmap: SUCCESS (0.016s)
[232.291506] [150/162] (779s left) vgem_basic (mmap)
[232.377842] Starting subtest: mmap
[232.388428] Subtest mmap: SUCCESS (0.012s)
[232.506482] [151/162] (779s left) vgem_basic (second-client)
[232.594905] Starting subtest: second-client
[232.627985] Subtest second-client: SUCCESS (0.032s)
[232.749233] [152/162] (779s left) vgem_basic (sysfs)
[232.827210] Starting subtest: sysfs
[232.828157] Subtest sysfs: SUCCESS (0.001s)
[232.939595] [153/162] (778s left) core_hotunplug (unbind-rebind)
[233.103646] Starting subtest: unbind-rebind
[238.302326] Subtest unbind-rebind: SUCCESS (5.199s)
[238.385483] [154/162] (773s left) vgem_basic (unload)