[41.774481] Initializing watchdogs
[41.774611]   /dev/watchdog0
[41.805832] [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.814271] [001/162] (960s left) i915_module_load (load)
[42.063034] Starting subtest: load
[43.879631] Subtest load: SUCCESS (1.816s)
[43.940762] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[43.944450] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[43.948683] [002/162] (958s left) core_auth (basic-auth)
[44.030315] Starting subtest: basic-auth
[44.031312] Subtest basic-auth: SUCCESS (0.001s)
[44.196057] [003/162] (958s left) core_debugfs (read-all-entries)
[44.265134] Starting subtest: read-all-entries
[44.380192] Subtest read-all-entries: SUCCESS (0.116s)
[44.556440] [004/162] (958s left) core_sysfs (read-all-entries)
[44.628034] Starting subtest: read-all-entries
[44.633253] Subtest read-all-entries: SUCCESS (0.007s)
[44.747150] [005/162] (957s left) fbdev (eof)
[44.829903] Starting subtest: eof
[44.830951] Subtest eof: SUCCESS (0.001s)
[44.967661] [006/162] (957s left) fbdev (info)
[45.034835] Starting subtest: info
[45.035921] Subtest info: SUCCESS (0.000s)
[45.208415] [007/162] (957s left) fbdev (nullptr)
[45.280349] Starting subtest: nullptr
[45.281405] Subtest nullptr: SUCCESS (0.000s)
[45.377695] [008/162] (957s left) fbdev (read)
[45.445940] Starting subtest: read
[46.337642] Subtest read: SUCCESS (0.892s)
[46.445590] [009/162] (956s left) fbdev (write)
[46.529718] Starting subtest: write
[47.011570] Subtest write: SUCCESS (0.473s)
[47.112792] [010/162] (955s left) gem_basic (bad-close)
[47.182183] Starting subtest: bad-close
[47.184231] Subtest bad-close: SUCCESS (0.000s)
[47.294838] [011/162] (955s left) gem_basic (create-close)
[47.366085] Starting subtest: create-close
[47.367739] Subtest create-close: SUCCESS (0.000s)
[47.504613] [012/162] (955s left) gem_basic (create-fd-close)
[47.574115] Starting subtest: create-fd-close
[47.576176] Subtest create-fd-close: SUCCESS (0.000s)
[47.704735] [013/162] (955s left) gem_busy (busy@all-engines)
[47.799666] Starting subtest: busy
[47.800637] Starting dynamic subtest: all-engines
[47.865174] Dynamic subtest all-engines: SUCCESS (0.066s)
[47.866177] Subtest busy: SUCCESS (0.067s)
[48.364345] [014/162] (954s left) gem_close_race (basic-process)
[48.456694] Starting subtest: basic-process
[48.528513] Subtest basic-process: SUCCESS (0.072s)
[48.816886] [015/162] (954s left) gem_close_race (basic-threads)
[48.906826] Starting subtest: basic-threads
[50.057786] Subtest basic-threads: SUCCESS (1.151s)
[50.352648] [016/162] (953s left) gem_ctx_create (basic)
[50.441460] Starting subtest: basic
[50.442514] Subtest basic: SUCCESS (0.000s)
[50.730610] [017/162] (952s left) gem_ctx_create (basic-files)
[50.817188] Starting subtest: basic-files
[52.840883] Subtest basic-files: SUCCESS (1.996s)
[53.134632] [018/162] (950s left) gem_ctx_exec (basic)
[53.210090] Starting subtest: basic
[53.215943] Subtest basic: SUCCESS (0.006s)
[53.398351] [019/162] (950s left) gem_exec_basic (basic)
[53.481343] Starting subtest: basic
[53.482431] Starting dynamic subtest: rcs0-smem
[53.484738] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[53.500450] Starting dynamic subtest: bcs0-smem
[53.501638] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[53.515415] Starting dynamic subtest: vcs0-smem
[53.516813] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[53.537639] Starting dynamic subtest: vecs0-smem
[53.538920] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[53.540302] Subtest basic: SUCCESS (0.010s)
[53.794059] [020/162] (949s left) gem_exec_create (basic)
[53.888679] Starting subtest: basic
[53.889749] Starting dynamic subtest: smem
[55.938545] Dynamic subtest smem: SUCCESS (1.981s)
[55.939548] Subtest basic: SUCCESS (1.981s)
[56.240826] [021/162] (947s left) gem_exec_fence (basic-busy)
[56.501245] Starting subtest: basic-busy
[56.502931] Starting dynamic subtest: rcs0
[56.545367] Dynamic subtest rcs0: SUCCESS (0.043s)
[56.563127] Starting dynamic subtest: bcs0
[56.576365] Dynamic subtest bcs0: SUCCESS (0.030s)
[56.591760] Starting dynamic subtest: vcs0
[56.601434] Dynamic subtest vcs0: SUCCESS (0.024s)
[56.616146] Starting dynamic subtest: vecs0
[56.621609] Dynamic subtest vecs0: SUCCESS (0.019s)
[56.622612] Subtest basic-busy: SUCCESS (0.118s)
[57.424512] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[57.428351] [022/162] (946s left) gem_exec_fence (basic-wait)
[57.680813] Starting subtest: basic-wait
[57.682100] Starting dynamic subtest: rcs0
[57.710394] Dynamic subtest rcs0: SUCCESS (0.030s)
[57.728359] Starting dynamic subtest: bcs0
[57.737388] Dynamic subtest bcs0: SUCCESS (0.026s)
[57.753645] Starting dynamic subtest: vcs0
[57.761374] Dynamic subtest vcs0: SUCCESS (0.023s)
[57.776290] Starting dynamic subtest: vecs0
[57.788342] Dynamic subtest vecs0: SUCCESS (0.026s)
[57.789416] Subtest basic-wait: SUCCESS (0.107s)
[58.555282] [023/162] (945s left) gem_exec_fence (basic-await)
[58.805204] Starting subtest: basic-await
[58.806461] Starting dynamic subtest: rcs0
[58.911652] Dynamic subtest rcs0: SUCCESS (0.104s)
[58.928041] Starting dynamic subtest: bcs0
[59.019664] Dynamic subtest bcs0: SUCCESS (0.105s)
[59.043673] Starting dynamic subtest: vcs0
[59.150296] Dynamic subtest vcs0: SUCCESS (0.126s)
[59.166643] Starting dynamic subtest: vecs0
[59.256406] Dynamic subtest vecs0: SUCCESS (0.104s)
[59.257599] Subtest basic-await: SUCCESS (0.442s)
[60.084291] [024/162] (944s left) gem_exec_fence (nb-await)
[60.327774] Starting subtest: nb-await
[60.328921] Starting dynamic subtest: rcs0
[60.397928] Dynamic subtest rcs0: SUCCESS (0.070s)
[60.427570] Starting dynamic subtest: bcs0
[60.492150] Dynamic subtest bcs0: SUCCESS (0.086s)
[60.527437] Starting dynamic subtest: vcs0
[60.579151] Dynamic subtest vcs0: SUCCESS (0.084s)
[60.610362] Starting dynamic subtest: vecs0
[60.657756] Dynamic subtest vecs0: SUCCESS (0.075s)
[60.660443] Subtest nb-await: SUCCESS (0.324s)
[61.436953] [025/162] (942s left) gem_exec_gttfill (basic)
[61.528606] Starting subtest: basic
[67.879742] Subtest basic: SUCCESS (6.282s)
[68.206937] [026/162] (936s left) gem_exec_parallel (engines)
[68.431919] Starting subtest: engines
[68.433027] Starting dynamic subtest: basic
[71.107503] Dynamic subtest basic: SUCCESS (2.665s)
[71.123384] Starting dynamic subtest: contexts
[81.616575] Dynamic subtest contexts: SUCCESS (10.486s)
[81.632770] Starting dynamic subtest: fds
[87.489659] Dynamic subtest fds: SUCCESS (5.867s)
[87.506212] Starting dynamic subtest: userptr
[90.186088] Dynamic subtest userptr: SUCCESS (2.694s)
[90.187456] Subtest engines: SUCCESS (21.713s)
[90.522136] [027/162] (913s left) gem_exec_store (basic)
[90.610326] Starting subtest: basic
[90.672470] Subtest basic: SUCCESS (0.062s)
[90.964195] [028/162] (913s left) gem_flink_basic (bad-flink)
[91.033132] Starting subtest: bad-flink
[91.034182] Subtest bad-flink: SUCCESS (0.000s)
[91.186169] [029/162] (913s left) gem_flink_basic (bad-open)
[91.256171] Starting subtest: bad-open
[91.257252] Subtest bad-open: SUCCESS (0.000s)
[91.384482] [030/162] (913s left) gem_flink_basic (basic)
[91.454752] Starting subtest: basic
[91.455806] Subtest basic: SUCCESS (0.000s)
[91.653095] [031/162] (913s left) gem_flink_basic (double-flink)
[91.730221] Starting subtest: double-flink
[91.731195] Subtest double-flink: SUCCESS (0.000s)
[91.884944] [032/162] (912s left) gem_flink_basic (flink-lifetime)
[91.954518] Starting subtest: flink-lifetime
[91.956444] Subtest flink-lifetime: SUCCESS (0.003s)
[92.163572] [033/162] (912s left) gem_huc_copy (huc-copy)
[92.245596] Starting subtest: huc-copy
[92.248999] Subtest huc-copy: SKIP (0.000s)
[92.418246] [034/162] (912s left) gem_linear_blits (basic)
[92.638789] Starting subtest: basic
[92.653029] Subtest basic: SUCCESS (0.015s)
[92.868090] [035/162] (912s left) gem_mmap (basic)
[93.006423] Starting subtest: basic
[93.010947] Subtest basic: SUCCESS (0.001s)
[93.178936] [036/162] (912s left) gem_mmap_gtt (basic)
[93.248269] Starting subtest: basic
[93.249419] Subtest basic: SUCCESS (0.001s)
[93.455210] [037/162] (911s left) gem_render_linear_blits (basic)
[93.558172] Starting subtest: basic
[93.605257] Subtest basic: SUCCESS (0.047s)
[93.707774] [038/162] (911s left) gem_render_tiled_blits (basic)
[93.787252] Starting subtest: basic
[93.841165] Subtest basic: SUCCESS (0.053s)
[93.941137] [039/162] (911s left) gem_ringfill (basic-all)
[94.232676] Starting subtest: basic-all
[95.407390] Subtest basic-all: SUCCESS (1.164s)
[95.596510] [040/162] (910s left) gem_softpin (allocator-basic)
[95.674730] Starting subtest: allocator-basic
[95.866230] Subtest allocator-basic: SUCCESS (0.183s)
[96.089491] [041/162] (909s left) gem_softpin (allocator-basic-reserve)
[96.165033] Starting subtest: allocator-basic-reserve
[96.361321] Subtest allocator-basic-reserve: SUCCESS (0.187s)
[96.544938] [042/162] (909s left) gem_softpin (safe-alignment)
[96.624515] Starting subtest: safe-alignment
[96.644124] Subtest safe-alignment: SUCCESS (0.019s)
[96.854188] [043/162] (909s left) gem_sync (basic-all)
[96.946815] Starting subtest: basic-all
[99.260718] Subtest basic-all: SUCCESS (2.301s)
[99.527825] [044/162] (906s left) gem_sync (basic-each)
[99.616319] Starting subtest: basic-each
[101.927888] Subtest basic-each: SUCCESS (2.310s)
[102.283218] [045/162] (903s left) gem_tiled_blits (basic)
[102.447042] Starting subtest: basic
[102.477930] Subtest basic: SUCCESS (0.024s)
[102.683601] [046/162] (903s left) gem_tiled_fence_blits (basic)
[102.937542] Starting subtest: basic
[102.950313] Subtest basic: SUCCESS (0.014s)
[103.165983] [047/162] (903s left) gem_tiled_pread_basic (basic)
[103.292143] Starting subtest: basic
[103.470589] Subtest basic: SUCCESS (0.170s)
[103.642550] [048/162] (902s left) gem_wait (busy@all-engines)
[103.738478] Starting subtest: busy
[103.739585] Starting dynamic subtest: all-engines
[104.269219] Dynamic subtest all-engines: SUCCESS (0.530s)
[104.270260] Subtest busy: SUCCESS (0.531s)
[104.609126] [049/162] (901s left) gem_wait (wait@all-engines)
[104.703641] Starting subtest: wait
[104.704789] Starting dynamic subtest: all-engines
[105.764187] Dynamic subtest all-engines: SUCCESS (1.057s)
[105.767701] Subtest wait: SUCCESS (1.060s)
[106.100707] [050/162] (900s left) i915_getparams_basic (basic-eu-total)
[106.170293] Starting subtest: basic-eu-total
[106.172240] Subtest basic-eu-total: SUCCESS (0.000s)
[106.325273] [051/162] (900s left) i915_getparams_basic (basic-subslice-total)
[106.398936] Starting subtest: basic-subslice-total
[106.400949] Subtest basic-subslice-total: SUCCESS (0.000s)
[106.527723] [052/162] (900s left) i915_hangman (error-state-basic)
[106.618752] Starting subtest: error-state-basic
[106.628704] Subtest error-state-basic: SUCCESS (0.010s)
[106.771353] [053/162] (899s left) i915_pciid
[106.980328] [054/162] (899s left) intel_hwmon (hwmon-read)
[107.051362] Subtest hwmon-read: SKIP (0.000s)
[107.149056] [055/162] (899s left) intel_hwmon (hwmon-write)
[107.221240] Subtest hwmon-write: SKIP (0.000s)
[107.314471] [056/162] (899s left) kms_addfb_basic (addfb25-4-tiled)
[107.455211] Starting subtest: addfb25-4-tiled
[107.456247] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[107.606218] [057/162] (899s left) kms_addfb_basic (addfb25-bad-modifier)
[107.737269] Starting subtest: addfb25-bad-modifier
[107.738158] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[107.893958] [058/162] (899s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[108.022215] Starting subtest: addfb25-framebuffer-vs-set-tiling
[108.023141] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[108.211746] [059/162] (899s left) kms_addfb_basic (addfb25-modifier-no-flag)
[108.360299] Starting subtest: addfb25-modifier-no-flag
[108.362049] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[108.573801] [060/162] (898s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[108.702201] Starting subtest: addfb25-x-tiled-legacy
[108.703165] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[108.853704] [061/162] (898s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[108.976349] Starting subtest: addfb25-x-tiled-mismatch-legacy
[108.977448] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[109.171306] [062/162] (898s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[109.297176] Starting subtest: addfb25-yf-tiled-legacy
[109.298120] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[109.488498] [063/162] (898s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[109.618319] Starting subtest: addfb25-y-tiled-legacy
[109.619283] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[109.785321] [064/162] (897s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[109.908518] Starting subtest: addfb25-y-tiled-small-legacy
[109.909551] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[110.120036] [065/162] (897s left) kms_addfb_basic (bad-pitch-0)
[110.189658] Starting subtest: bad-pitch-0
[110.190695] Subtest bad-pitch-0: SUCCESS (0.000s)
[110.398730] [066/162] (897s left) kms_addfb_basic (bad-pitch-1024)
[110.469377] Starting subtest: bad-pitch-1024
[110.470413] Subtest bad-pitch-1024: SUCCESS (0.000s)
[110.748776] [067/162] (897s left) kms_addfb_basic (bad-pitch-128)
[110.819281] Starting subtest: bad-pitch-128
[110.820347] Subtest bad-pitch-128: SUCCESS (0.000s)
[111.012042] [068/162] (897s left) kms_addfb_basic (bad-pitch-256)
[111.082998] Starting subtest: bad-pitch-256
[111.083959] Subtest bad-pitch-256: SUCCESS (0.000s)
[111.359793] [069/162] (896s left) kms_addfb_basic (bad-pitch-32)
[111.430685] Starting subtest: bad-pitch-32
[111.431735] Subtest bad-pitch-32: SUCCESS (0.000s)
[111.653991] [070/162] (896s left) kms_addfb_basic (bad-pitch-63)
[111.724250] Starting subtest: bad-pitch-63
[111.725151] Subtest bad-pitch-63: SUCCESS (0.000s)
[111.974409] [071/162] (896s left) kms_addfb_basic (bad-pitch-65536)
[112.052070] Starting subtest: bad-pitch-65536
[112.053115] Subtest bad-pitch-65536: SUCCESS (0.000s)
[112.247730] [072/162] (896s left) kms_addfb_basic (bad-pitch-999)
[112.317977] Starting subtest: bad-pitch-999
[112.319367] Subtest bad-pitch-999: SUCCESS (0.000s)
[112.541609] [073/162] (895s left) kms_addfb_basic (basic)
[112.613623] Starting subtest: basic
[112.614695] Subtest basic: SUCCESS (0.000s)
[112.829964] [074/162] (895s left) kms_addfb_basic (basic-x-tiled-legacy)
[112.900744] Starting subtest: basic-x-tiled-legacy
[112.901830] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[113.142305] [075/162] (895s left) kms_addfb_basic (basic-y-tiled-legacy)
[113.218488] Starting subtest: basic-y-tiled-legacy
[113.219432] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[113.414365] [076/162] (895s left) kms_addfb_basic (bo-too-small)
[113.537227] Starting subtest: bo-too-small
[113.538297] Subtest bo-too-small: SUCCESS (0.000s)
[113.722094] [077/162] (895s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[113.859889] Starting subtest: bo-too-small-due-to-tiling
[113.861013] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[114.050190] [078/162] (894s left) kms_addfb_basic (clobberred-modifier)
[114.119201] Starting subtest: clobberred-modifier
[114.120243] Subtest clobberred-modifier: SUCCESS (0.000s)
[114.342407] [079/162] (894s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[114.415448] Starting subtest: framebuffer-vs-set-tiling
[114.416429] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[114.664145] [080/162] (894s left) kms_addfb_basic (invalid-get-prop)
[114.736853] Starting subtest: invalid-get-prop
[114.737941] Subtest invalid-get-prop: SUCCESS (0.000s)
[114.922048] [081/162] (894s left) kms_addfb_basic (invalid-get-prop-any)
[114.990855] Starting subtest: invalid-get-prop-any
[114.992040] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[115.244267] [082/162] (894s left) kms_addfb_basic (invalid-set-prop)
[115.314464] Starting subtest: invalid-set-prop
[115.315479] Subtest invalid-set-prop: SUCCESS (0.000s)
[115.509948] [083/162] (893s left) kms_addfb_basic (invalid-set-prop-any)
[115.580161] Starting subtest: invalid-set-prop-any
[115.581136] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[115.826391] [084/162] (893s left) kms_addfb_basic (no-handle)
[115.896511] Starting subtest: no-handle
[115.897532] Subtest no-handle: SUCCESS (0.000s)
[116.096522] [085/162] (893s left) kms_addfb_basic (size-max)
[116.226011] Starting subtest: size-max
[116.227064] Subtest size-max: SUCCESS (0.000s)
[116.409987] [086/162] (893s left) kms_addfb_basic (small-bo)
[116.537966] Starting subtest: small-bo
[116.539246] Subtest small-bo: SUCCESS (0.000s)
[116.695469] [087/162] (892s left) kms_addfb_basic (tile-pitch-mismatch)
[116.767444] Starting subtest: tile-pitch-mismatch
[116.768363] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[116.976860] [088/162] (892s left) kms_addfb_basic (too-high)
[117.101301] Starting subtest: too-high
[117.102279] Subtest too-high: SUCCESS (0.001s)
[117.276721] [089/162] (892s left) kms_addfb_basic (too-wide)
[117.401216] Starting subtest: too-wide
[117.402123] Subtest too-wide: SUCCESS (0.000s)
[117.605674] [090/162] (892s left) kms_addfb_basic (unused-handle)
[117.676359] Starting subtest: unused-handle
[117.677499] Subtest unused-handle: SUCCESS (0.000s)
[117.899450] [091/162] (892s left) kms_addfb_basic (unused-modifier)
[117.968779] Starting subtest: unused-modifier
[117.969987] Subtest unused-modifier: SUCCESS (0.000s)
[118.208253] [092/162] (891s left) kms_addfb_basic (unused-offsets)
[118.283420] Starting subtest: unused-offsets
[118.284367] Subtest unused-offsets: SUCCESS (0.000s)
[118.499181] [093/162] (891s left) kms_addfb_basic (unused-pitches)
[118.569205] Starting subtest: unused-pitches
[118.570186] Subtest unused-pitches: SUCCESS (0.000s)
[118.791493] [094/162] (891s left) kms_busy (basic)
[118.925625] Starting subtest: basic
[118.928263] Starting dynamic subtest: flip
[119.770456] Dynamic subtest flip: SUCCESS (0.840s)
[119.789442] Starting dynamic subtest: modeset
[123.478718] Dynamic subtest modeset: SUCCESS (3.704s)
[123.479824] Subtest basic: SUCCESS (4.548s)
[124.467104] [095/162] (885s left) kms_prop_blob (basic)
[124.544465] Starting subtest: basic
[124.545450] Subtest basic: SUCCESS (0.000s)
[124.674091] [096/162] (885s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[124.798602] Starting subtest: basic-busy-flip-before-cursor-atomic
[124.800530] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[124.985990] [097/162] (885s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[125.113056] Starting subtest: basic-busy-flip-before-cursor-legacy
[125.114938] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[125.232577] [098/162] (885s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[125.358220] Starting subtest: basic-flip-after-cursor-atomic
[126.671622] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.309s)
[127.542862] [099/162] (883s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[127.665393] Starting subtest: basic-flip-after-cursor-legacy
[128.971909] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.304s)
[129.912542] [100/162] (880s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[130.040221] Starting subtest: basic-flip-after-cursor-varying-size
[131.306075] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.261s)
[132.272927] [101/162] (878s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[132.396182] Starting subtest: basic-flip-before-cursor-atomic
[133.735061] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.336s)
[134.672317] [102/162] (876s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[134.796328] Starting subtest: basic-flip-before-cursor-legacy
[136.072316] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.271s)
[137.016144] [103/162] (874s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[137.138317] Starting subtest: basic-flip-before-cursor-varying-size
[138.482141] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.344s)
[139.387164] [104/162] (871s left) kms_dsc (dsc-basic)
[139.513183] Subtest dsc-basic: SKIP (0.000s)
[139.601045] [105/162] (871s left) kms_flip (basic-flip-vs-dpms)
[139.724130] Starting subtest: basic-flip-vs-dpms
[139.726866] Starting dynamic subtest: A-eDP1
[142.531914] Dynamic subtest A-eDP1: SUCCESS (2.803s)
[142.603395] Starting dynamic subtest: B-eDP1
[144.861116] Dynamic subtest B-eDP1: SUCCESS (2.316s)
[144.927478] Starting dynamic subtest: C-eDP1
[147.087855] Dynamic subtest C-eDP1: SUCCESS (2.226s)
[147.095827] Subtest basic-flip-vs-dpms: SUCCESS (7.370s)
[148.055742] [106/162] (863s left) kms_flip (basic-flip-vs-modeset)
[148.179505] Starting subtest: basic-flip-vs-modeset
[148.181883] Starting dynamic subtest: A-eDP1
[150.961210] Dynamic subtest A-eDP1: SUCCESS (2.775s)
[151.036900] Starting dynamic subtest: B-eDP1
[153.397398] Dynamic subtest B-eDP1: SUCCESS (2.421s)
[153.443702] Starting dynamic subtest: C-eDP1
[155.711160] Dynamic subtest C-eDP1: SUCCESS (2.311s)
[155.719438] Subtest basic-flip-vs-modeset: SUCCESS (7.540s)
[156.643952] [107/162] (854s left) kms_flip (basic-flip-vs-wf_vblank)
[156.771791] Starting subtest: basic-flip-vs-wf_vblank
[156.773989] Starting dynamic subtest: A-eDP1
[158.964450] Dynamic subtest A-eDP1: SUCCESS (2.191s)
[158.989384] Starting dynamic subtest: B-eDP1
[161.171448] Dynamic subtest B-eDP1: SUCCESS (2.205s)
[161.190881] Starting dynamic subtest: C-eDP1
[163.377402] Dynamic subtest C-eDP1: SUCCESS (2.204s)
[163.380942] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.611s)
[164.671971] [108/162] (847s left) kms_flip (basic-plain-flip)
[164.800739] Starting subtest: basic-plain-flip
[164.803796] Starting dynamic subtest: A-eDP1
[166.703227] Dynamic subtest A-eDP1: SUCCESS (1.894s)
[166.767163] Starting dynamic subtest: B-eDP1
[168.627808] Dynamic subtest B-eDP1: SUCCESS (1.920s)
[168.650273] Starting dynamic subtest: C-eDP1
[170.553553] Dynamic subtest C-eDP1: SUCCESS (1.920s)
[170.567849] Subtest basic-plain-flip: SUCCESS (5.767s)
[172.041383] [109/162] (839s left) kms_force_connector_basic (force-connector-state)
[172.144016] Starting subtest: force-connector-state
[172.665432] Subtest force-connector-state: SUCCESS (0.521s)
[173.897665] [110/162] (838s left) kms_force_connector_basic (force-edid)
[173.999625] Starting subtest: force-edid
[174.040828] Subtest force-edid: SUCCESS (0.040s)
[174.266805] [111/162] (837s left) kms_force_connector_basic (force-load-detect)
[174.368320] Starting subtest: force-load-detect
[174.370360] Subtest force-load-detect: SKIP (0.000s)
[174.603671] [112/162] (837s left) kms_force_connector_basic (prune-stale-modes)
[174.701698] Starting subtest: prune-stale-modes
[174.737941] Subtest prune-stale-modes: SUCCESS (0.037s)
[174.998109] [113/162] (837s left) kms_frontbuffer_tracking (basic)
[175.132173] Starting subtest: basic
[178.959442] Subtest basic: SUCCESS (3.827s)
[179.148687] [114/162] (833s left) kms_hdmi_inject (inject-audio)
[179.564677] Starting subtest: inject-audio
[179.708994] Subtest inject-audio: SUCCESS (0.147s)
[180.556474] [115/162] (831s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[180.683174] Starting subtest: compare-crc-sanitycheck-xr24
[180.684166] Starting dynamic subtest: pipe-A-eDP-1
[181.463959] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.772s)
[181.530885] Starting dynamic subtest: pipe-B-eDP-1
[182.916976] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.439s)
[183.002854] Starting dynamic subtest: pipe-C-eDP-1
[184.310756] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.381s)
[184.320729] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.632s)
[185.304566] [116/162] (827s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[185.432223] Starting subtest: compare-crc-sanitycheck-nv12
[185.433275] Starting dynamic subtest: pipe-A-eDP-1
[185.435260] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[185.456578] Starting dynamic subtest: pipe-B-eDP-1
[185.458828] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[185.476072] Starting dynamic subtest: pipe-C-eDP-1
[185.478571] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[185.480992] Subtest compare-crc-sanitycheck-nv12: SKIP (0.012s)
[185.569108] [117/162] (826s left) kms_pipe_crc_basic (hang-read-crc)
[185.691673] Starting subtest: hang-read-crc
[185.694996] Starting dynamic subtest: pipe-A-eDP-1
[188.210704] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.516s)
[188.292116] Starting dynamic subtest: pipe-B-eDP-1
[191.508182] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.274s)
[191.584145] Starting dynamic subtest: pipe-C-eDP-1
[194.663530] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.151s)
[194.671180] Subtest hang-read-crc: SUCCESS (8.977s)
[195.590897] [118/162] (817s left) kms_pipe_crc_basic (nonblocking-crc)
[195.716167] Starting subtest: nonblocking-crc
[195.719453] Starting dynamic subtest: pipe-A-eDP-1
[196.760205] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.032s)
[196.846572] Starting dynamic subtest: pipe-B-eDP-1
[198.518272] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.751s)
[198.538450] Starting dynamic subtest: pipe-C-eDP-1
[200.180117] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.651s)
[200.182552] Subtest nonblocking-crc: SUCCESS (4.462s)
[201.197997] [119/162] (811s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[201.321918] Starting subtest: nonblocking-crc-frame-sequence
[201.322976] Starting dynamic subtest: pipe-A-eDP-1
[202.355747] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.031s)
[202.444975] Starting dynamic subtest: pipe-B-eDP-1
[204.089334] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.714s)
[204.174138] Starting dynamic subtest: pipe-C-eDP-1
[205.848364] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.740s)
[205.856325] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.530s)
[206.814557] [120/162] (806s left) kms_pipe_crc_basic (read-crc)
[206.943184] Starting subtest: read-crc
[206.946237] Starting dynamic subtest: pipe-A-eDP-1
[207.895942] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.941s)
[207.983678] Starting dynamic subtest: pipe-B-eDP-1
[209.470698] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.556s)
[209.551939] Starting dynamic subtest: pipe-C-eDP-1
[211.050930] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.568s)
[211.067466] Subtest read-crc: SUCCESS (4.110s)
[212.022532] [121/162] (800s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[212.147420] Starting subtest: read-crc-frame-sequence
[212.149156] Starting dynamic subtest: pipe-A-eDP-1
[213.095516] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.936s)
[213.175506] Starting dynamic subtest: pipe-B-eDP-1
[214.746511] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.636s)
[214.833211] Starting dynamic subtest: pipe-C-eDP-1
[216.440509] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.678s)
[216.442982] Subtest read-crc-frame-sequence: SUCCESS (4.294s)
[217.427523] [122/162] (795s left) kms_pm_backlight (basic-brightness)
[217.558971] Starting subtest: basic-brightness
[217.640418] Starting dynamic subtest: eDP-1
[218.219893] Dynamic subtest eDP-1: SUCCESS (0.576s)
[218.222563] Subtest basic-brightness: SUCCESS (0.661s)
[219.179352] [123/162] (793s left) kms_pm_rpm (basic-pci-d3-state)
[220.922486] Starting subtest: basic-pci-d3-state
[221.503221] Subtest basic-pci-d3-state: SUCCESS (0.574s)
[221.685635] [124/162] (791s left) kms_pm_rpm (basic-rte)
[221.758352] Starting subtest: basic-rte
[224.037646] Subtest basic-rte: SUCCESS (2.280s)
[225.438567] [125/162] (787s left) kms_psr (psr-primary-page-flip)
[225.573400] Starting subtest: psr-primary-page-flip
[225.574377] Starting dynamic subtest: eDP-1
[227.608079] Dynamic subtest eDP-1: SUCCESS (2.027s)
[227.610680] Subtest psr-primary-page-flip: SUCCESS (2.036s)
[228.605174] [126/162] (784s left) kms_psr (psr-cursor-plane-move)
[228.749971] Starting subtest: psr-cursor-plane-move
[228.750998] Starting dynamic subtest: eDP-1
[230.802328] Dynamic subtest eDP-1: SUCCESS (2.047s)
[230.805395] Subtest psr-cursor-plane-move: SUCCESS (2.055s)
[231.754495] [127/162] (781s left) kms_psr (psr-sprite-plane-onoff)
[231.881576] Starting subtest: psr-sprite-plane-onoff
[231.885220] Starting dynamic subtest: eDP-1
[233.971134] Dynamic subtest eDP-1: SUCCESS (2.082s)
[233.973567] Subtest psr-sprite-plane-onoff: SUCCESS (2.089s)
[234.898279] [128/162] (778s left) kms_psr (psr-primary-mmap-gtt)
[235.025736] Starting subtest: psr-primary-mmap-gtt
[235.026800] Starting dynamic subtest: eDP-1
[236.958504] Dynamic subtest eDP-1: SUCCESS (1.927s)
[236.968037] Subtest psr-primary-mmap-gtt: SUCCESS (1.936s)
[237.902937] [129/162] (775s left) kms_setmode (basic-clone-single-crtc)
[237.978260] Starting subtest: basic-clone-single-crtc
[237.981114] Subtest basic-clone-single-crtc: SKIP (0.000s)
[238.127893] [130/162] (775s left) i915_pm_rps (basic-api)
[238.213340] Starting subtest: basic-api
[238.219713] Subtest basic-api: SUCCESS (0.006s)
[238.371733] [131/162] (775s left) prime_self_import (basic-llseek-bad)
[238.443380] Starting subtest: basic-llseek-bad
[238.445220] Subtest basic-llseek-bad: SUCCESS (0.004s)
[238.636096] [132/162] (775s left) prime_self_import (basic-llseek-size)
[238.704531] Starting subtest: basic-llseek-size
[238.709568] Subtest basic-llseek-size: SUCCESS (0.005s)
[238.878441] [133/162] (775s left) prime_self_import (basic-with_fd_dup)
[238.946367] Starting subtest: basic-with_fd_dup
[238.951414] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[239.115205] [134/162] (775s left) prime_self_import (basic-with_one_bo)
[239.185271] Starting subtest: basic-with_one_bo
[239.190391] Subtest basic-with_one_bo: SUCCESS (0.005s)
[239.394528] [135/162] (774s left) prime_self_import (basic-with_one_bo_two_files)
[239.466778] Starting subtest: basic-with_one_bo_two_files
[239.468750] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[239.609285] [136/162] (774s left) prime_self_import (basic-with_two_bos)
[239.679275] Starting subtest: basic-with_two_bos
[239.683355] Subtest basic-with_two_bos: SUCCESS (0.006s)
[239.880085] [137/162] (774s left) prime_vgem (basic-fence-flip)
[239.986837] Starting subtest: basic-fence-flip
[240.508382] Subtest basic-fence-flip: SUCCESS (0.522s)
[240.693869] [138/162] (773s left) prime_vgem (basic-fence-mmap)
[240.794416] Starting subtest: basic-fence-mmap
[240.833812] Subtest basic-fence-mmap: SUCCESS (0.039s)
[240.973057] [139/162] (773s left) prime_vgem (basic-fence-read)
[241.075355] Starting subtest: basic-fence-read
[241.160811] Subtest basic-fence-read: SUCCESS (0.085s)
[241.286083] [140/162] (773s left) prime_vgem (basic-gtt)
[241.383360] Starting subtest: basic-gtt
[241.403953] Subtest basic-gtt: SUCCESS (0.020s)
[241.535211] [141/162] (773s left) prime_vgem (basic-read)
[241.633988] Starting subtest: basic-read
[241.676900] Subtest basic-read: SUCCESS (0.043s)
[241.864266] [142/162] (773s left) prime_vgem (basic-write)
[241.975637] Starting subtest: basic-write
[242.016725] Subtest basic-write: SUCCESS (0.041s)
[242.140310] [143/162] (772s left) vgem_basic (setversion)
[242.213950] Starting subtest: setversion
[242.215990] Subtest setversion: SUCCESS (0.000s)
[242.324767] [144/162] (772s left) vgem_basic (create)
[242.415656] Starting subtest: create
[242.416721] Subtest create: SUCCESS (0.000s)
[242.524916] [145/162] (772s left) vgem_basic (debugfs)
[242.597738] Starting subtest: debugfs
[242.598822] Subtest debugfs: SUCCESS (0.001s)
[242.707432] [146/162] (772s left) vgem_basic (dmabuf-export)
[242.783111] Starting subtest: dmabuf-export
[242.798716] Subtest dmabuf-export: SUCCESS (0.016s)
[242.970311] [147/162] (772s left) vgem_basic (dmabuf-fence)
[243.071215] Starting subtest: dmabuf-fence
[243.072258] Subtest dmabuf-fence: SUCCESS (0.001s)
[243.180444] [148/162] (772s left) vgem_basic (dmabuf-fence-before)
[243.271824] Starting subtest: dmabuf-fence-before
[243.272987] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[243.445597] [149/162] (772s left) vgem_basic (dmabuf-mmap)
[243.543324] Starting subtest: dmabuf-mmap
[243.557367] Subtest dmabuf-mmap: SUCCESS (0.015s)
[243.670668] [150/162] (772s left) vgem_basic (mmap)
[243.760225] Starting subtest: mmap
[243.768832] Subtest mmap: SUCCESS (0.011s)
[243.887725] [151/162] (771s left) vgem_basic (second-client)
[243.977442] Starting subtest: second-client
[244.007596] Subtest second-client: SUCCESS (0.032s)
[244.129579] [152/162] (771s left) vgem_basic (sysfs)
[244.209679] Starting subtest: sysfs
[244.210691] Subtest sysfs: SUCCESS (0.001s)
[244.319577] [153/162] (771s left) core_hotunplug (unbind-rebind)
[244.482194] Starting subtest: unbind-rebind
[249.572411] Subtest unbind-rebind: SUCCESS (5.090s)
[249.656090] [154/162] (766s left) vgem_basic (unload)