[41.081304] Initializing watchdogs
[41.081444]   /dev/watchdog0
[41.121854] [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.130735] [001/162] (960s left) i915_module_load (load)
[41.207518] Starting subtest: load
[42.994553] Subtest load: SUCCESS (1.786s)
[43.053148] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[43.056862] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[43.061511] [002/162] (958s left) core_auth (basic-auth)
[43.146403] Starting subtest: basic-auth
[43.147903] Subtest basic-auth: SUCCESS (0.001s)
[43.306289] [003/162] (958s left) core_debugfs (read-all-entries)
[43.379608] Starting subtest: read-all-entries
[43.484366] Subtest read-all-entries: SUCCESS (0.105s)
[43.633345] [004/162] (958s left) core_sysfs (read-all-entries)
[43.704043] Starting subtest: read-all-entries
[43.712093] Subtest read-all-entries: SUCCESS (0.008s)
[43.891992] [005/162] (958s left) fbdev (eof)
[43.978148] Starting subtest: eof
[43.980448] Subtest eof: SUCCESS (0.001s)
[44.153814] [006/162] (957s left) fbdev (info)
[44.221465] Starting subtest: info
[44.222720] Subtest info: SUCCESS (0.000s)
[44.357780] [007/162] (957s left) fbdev (nullptr)
[44.426633] Starting subtest: nullptr
[44.427764] Subtest nullptr: SUCCESS (0.000s)
[44.525319] [008/162] (957s left) fbdev (read)
[44.593392] Starting subtest: read
[45.494270] Subtest read: SUCCESS (0.901s)
[45.611340] [009/162] (956s left) fbdev (write)
[45.693721] Starting subtest: write
[46.182421] Subtest write: SUCCESS (0.480s)
[46.295569] [010/162] (956s left) gem_basic (bad-close)
[46.366468] Starting subtest: bad-close
[46.368546] Subtest bad-close: SUCCESS (0.000s)
[46.508394] [011/162] (955s left) gem_basic (create-close)
[46.578327] Starting subtest: create-close
[46.580439] Subtest create-close: SUCCESS (0.000s)
[46.770399] [012/162] (955s left) gem_basic (create-fd-close)
[46.842692] Starting subtest: create-fd-close
[46.845016] Subtest create-fd-close: SUCCESS (0.000s)
[47.025876] [013/162] (955s left) gem_busy (busy@all-engines)
[47.120924] Starting subtest: busy
[47.122216] Starting dynamic subtest: all-engines
[47.177427] Dynamic subtest all-engines: SUCCESS (0.056s)
[47.178545] Subtest busy: SUCCESS (0.057s)
[47.725871] [014/162] (954s left) gem_close_race (basic-process)
[47.822713] Starting subtest: basic-process
[47.903881] Subtest basic-process: SUCCESS (0.081s)
[48.195689] [015/162] (954s left) gem_close_race (basic-threads)
[48.290477] Starting subtest: basic-threads
[49.471061] Subtest basic-threads: SUCCESS (1.181s)
[49.862825] [016/162] (952s left) gem_ctx_create (basic)
[49.947952] Starting subtest: basic
[49.949168] Subtest basic: SUCCESS (0.000s)
[50.236225] [017/162] (952s left) gem_ctx_create (basic-files)
[50.321383] Starting subtest: basic-files
[52.342561] Subtest basic-files: SUCCESS (1.981s)
[52.699215] [018/162] (950s left) gem_ctx_exec (basic)
[52.780193] Starting subtest: basic
[52.786446] Subtest basic: SUCCESS (0.006s)
[52.994519] [019/162] (950s left) gem_exec_basic (basic)
[53.074743] Starting subtest: basic
[53.075846] Starting dynamic subtest: rcs0-smem
[53.078444] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[53.093706] Starting dynamic subtest: bcs0-smem
[53.095062] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[53.108217] Starting dynamic subtest: vcs0-smem
[53.109663] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[53.122564] Starting dynamic subtest: vecs0-smem
[53.123890] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[53.125251] Subtest basic: SUCCESS (0.010s)
[53.447588] [020/162] (949s left) gem_exec_create (basic)
[53.542676] Starting subtest: basic
[53.543816] Starting dynamic subtest: smem
[55.601551] Dynamic subtest smem: SUCCESS (2.012s)
[55.602596] Subtest basic: SUCCESS (2.012s)
[55.902901] [021/162] (947s left) gem_exec_fence (basic-busy)
[56.142402] Starting subtest: basic-busy
[56.144231] Starting dynamic subtest: rcs0
[56.177496] Dynamic subtest rcs0: SUCCESS (0.035s)
[56.194220] Starting dynamic subtest: bcs0
[56.209517] Dynamic subtest bcs0: SUCCESS (0.031s)
[56.227344] Starting dynamic subtest: vcs0
[56.241351] Dynamic subtest vcs0: SUCCESS (0.031s)
[56.256185] Starting dynamic subtest: vecs0
[56.266232] Dynamic subtest vecs0: SUCCESS (0.024s)
[56.267504] Subtest basic-busy: SUCCESS (0.123s)
[57.121964] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[57.125435] [022/162] (946s left) gem_exec_fence (basic-wait)
[57.353261] Starting subtest: basic-wait
[57.354553] Starting dynamic subtest: rcs0
[57.383342] Dynamic subtest rcs0: SUCCESS (0.031s)
[57.400769] Starting dynamic subtest: bcs0
[57.407448] Dynamic subtest bcs0: SUCCESS (0.023s)
[57.423722] Starting dynamic subtest: vcs0
[57.431275] Dynamic subtest vcs0: SUCCESS (0.023s)
[57.446837] Starting dynamic subtest: vecs0
[57.454283] Dynamic subtest vecs0: SUCCESS (0.022s)
[57.455470] Subtest basic-wait: SUCCESS (0.102s)
[58.216342] [023/162] (945s left) gem_exec_fence (basic-await)
[58.444168] Starting subtest: basic-await
[58.446407] Starting dynamic subtest: rcs0
[58.570507] Dynamic subtest rcs0: SUCCESS (0.116s)
[58.584377] Starting dynamic subtest: bcs0
[58.659762] Dynamic subtest bcs0: SUCCESS (0.096s)
[58.677896] Starting dynamic subtest: vcs0
[58.770507] Dynamic subtest vcs0: SUCCESS (0.109s)
[58.786889] Starting dynamic subtest: vecs0
[58.887270] Dynamic subtest vecs0: SUCCESS (0.114s)
[58.888511] Subtest basic-await: SUCCESS (0.438s)
[59.770541] [024/162] (943s left) gem_exec_fence (nb-await)
[60.006759] Starting subtest: nb-await
[60.008166] Starting dynamic subtest: rcs0
[60.080859] Dynamic subtest rcs0: SUCCESS (0.073s)
[60.109595] Starting dynamic subtest: bcs0
[60.163468] Dynamic subtest bcs0: SUCCESS (0.077s)
[60.233605] Starting dynamic subtest: vcs0
[60.280155] Dynamic subtest vcs0: SUCCESS (0.115s)
[60.322693] Starting dynamic subtest: vecs0
[60.377637] Dynamic subtest vecs0: SUCCESS (0.090s)
[60.381856] Subtest nb-await: SUCCESS (0.364s)
[61.214990] [025/162] (942s left) gem_exec_gttfill (basic)
[61.307914] Starting subtest: basic
[67.826545] Subtest basic: SUCCESS (6.468s)
[68.162609] [026/162] (935s left) gem_exec_parallel (engines)
[68.378665] Starting subtest: engines
[68.379962] Starting dynamic subtest: basic
[71.078714] Dynamic subtest basic: SUCCESS (2.669s)
[71.092726] Starting dynamic subtest: contexts
[76.901441] Dynamic subtest contexts: SUCCESS (5.836s)
[76.916094] Starting dynamic subtest: fds
[83.297363] Dynamic subtest fds: SUCCESS (6.385s)
[83.312840] Starting dynamic subtest: userptr
[85.876033] Dynamic subtest userptr: SUCCESS (2.559s)
[85.877368] Subtest engines: SUCCESS (17.451s)
[86.169263] [027/162] (917s left) gem_exec_store (basic)
[86.256632] Starting subtest: basic
[86.330909] Subtest basic: SUCCESS (0.074s)
[86.647532] [028/162] (917s left) gem_flink_basic (bad-flink)
[86.718298] Starting subtest: bad-flink
[86.719261] Subtest bad-flink: SUCCESS (0.000s)
[86.895047] [029/162] (917s left) gem_flink_basic (bad-open)
[86.964257] Starting subtest: bad-open
[86.965266] Subtest bad-open: SUCCESS (0.000s)
[87.107876] [030/162] (916s left) gem_flink_basic (basic)
[87.175317] Starting subtest: basic
[87.176602] Subtest basic: SUCCESS (0.000s)
[87.378115] [031/162] (916s left) gem_flink_basic (double-flink)
[87.446938] Starting subtest: double-flink
[87.448151] Subtest double-flink: SUCCESS (0.000s)
[87.622609] [032/162] (916s left) gem_flink_basic (flink-lifetime)
[87.690621] Starting subtest: flink-lifetime
[87.692986] Subtest flink-lifetime: SUCCESS (0.002s)
[87.881284] [033/162] (916s left) gem_huc_copy (huc-copy)
[87.958925] Starting subtest: huc-copy
[87.961098] Subtest huc-copy: SKIP (0.000s)
[88.175728] [034/162] (916s left) gem_linear_blits (basic)
[88.388443] Starting subtest: basic
[88.403089] Subtest basic: SUCCESS (0.015s)
[88.618030] [035/162] (915s left) gem_mmap (basic)
[88.760380] Starting subtest: basic
[88.765393] Subtest basic: SUCCESS (0.001s)
[88.946705] [036/162] (915s left) gem_mmap_gtt (basic)
[89.019350] Starting subtest: basic
[89.020439] Subtest basic: SUCCESS (0.001s)
[89.182269] [037/162] (915s left) gem_render_linear_blits (basic)
[89.262407] Starting subtest: basic
[89.303574] Subtest basic: SUCCESS (0.042s)
[89.407816] [038/162] (915s left) gem_render_tiled_blits (basic)
[89.483876] Starting subtest: basic
[89.531694] Subtest basic: SUCCESS (0.048s)
[89.641419] [039/162] (915s left) gem_ringfill (basic-all)
[89.928371] Starting subtest: basic-all
[91.090072] Subtest basic-all: SUCCESS (1.152s)
[91.324078] [040/162] (913s left) gem_softpin (allocator-basic)
[91.402366] Starting subtest: allocator-basic
[91.582213] Subtest allocator-basic: SUCCESS (0.171s)
[91.790927] [041/162] (913s left) gem_softpin (allocator-basic-reserve)
[91.874152] Starting subtest: allocator-basic-reserve
[92.073091] Subtest allocator-basic-reserve: SUCCESS (0.190s)
[92.285959] [042/162] (912s left) gem_softpin (safe-alignment)
[92.365692] Starting subtest: safe-alignment
[92.386629] Subtest safe-alignment: SUCCESS (0.021s)
[92.598563] [043/162] (912s left) gem_sync (basic-all)
[92.692370] Starting subtest: basic-all
[95.010490] Subtest basic-all: SUCCESS (2.318s)
[95.357908] [044/162] (909s left) gem_sync (basic-each)
[95.447740] Starting subtest: basic-each
[97.708534] Subtest basic-each: SUCCESS (2.260s)
[98.063559] [045/162] (907s left) gem_tiled_blits (basic)
[98.238770] Starting subtest: basic
[98.259588] Subtest basic: SUCCESS (0.022s)
[98.489726] [046/162] (906s left) gem_tiled_fence_blits (basic)
[98.740461] Starting subtest: basic
[98.755079] Subtest basic: SUCCESS (0.014s)
[98.973499] [047/162] (906s left) gem_tiled_pread_basic
[99.430621] [048/162] (906s left) gem_wait (busy@all-engines)
[99.527718] Starting subtest: busy
[99.529052] Starting dynamic subtest: all-engines
[100.056392] Dynamic subtest all-engines: SUCCESS (0.528s)
[100.057428] Subtest busy: SUCCESS (0.529s)
[100.370161] [049/162] (905s left) gem_wait (wait@all-engines)
[100.468162] Starting subtest: wait
[100.469286] Starting dynamic subtest: all-engines
[101.542313] Dynamic subtest all-engines: SUCCESS (1.071s)
[101.546283] Subtest wait: SUCCESS (1.074s)
[101.879924] [050/162] (903s left) i915_getparams_basic (basic-eu-total)
[101.958149] Starting subtest: basic-eu-total
[101.960154] Subtest basic-eu-total: SUCCESS (0.000s)
[102.152280] [051/162] (903s left) i915_getparams_basic (basic-subslice-total)
[102.221137] Starting subtest: basic-subslice-total
[102.223114] Subtest basic-subslice-total: SUCCESS (0.000s)
[102.375119] [052/162] (903s left) i915_hangman (error-state-basic)
[102.472453] Starting subtest: error-state-basic
[102.484883] Subtest error-state-basic: SUCCESS (0.010s)
[102.670928] [053/162] (903s left) i915_pciid
[102.914626] [054/162] (903s left) intel_hwmon (hwmon-read)
[102.985294] Subtest hwmon-read: SKIP (0.000s)
[103.083937] [055/162] (903s left) intel_hwmon (hwmon-write)
[103.153348] Subtest hwmon-write: SKIP (0.000s)
[103.248379] [056/162] (902s left) kms_addfb_basic (addfb25-4-tiled)
[103.387495] Starting subtest: addfb25-4-tiled
[103.388464] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[103.539989] [057/162] (902s left) kms_addfb_basic (addfb25-bad-modifier)
[103.667367] Starting subtest: addfb25-bad-modifier
[103.668338] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[103.867645] [058/162] (902s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[103.997506] Starting subtest: addfb25-framebuffer-vs-set-tiling
[103.998651] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[104.221839] [059/162] (902s left) kms_addfb_basic (addfb25-modifier-no-flag)
[104.348446] Starting subtest: addfb25-modifier-no-flag
[104.349518] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[104.552227] [060/162] (902s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[104.680251] Starting subtest: addfb25-x-tiled-legacy
[104.681211] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[104.848967] [061/162] (901s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[104.976180] Starting subtest: addfb25-x-tiled-mismatch-legacy
[104.977218] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[105.205773] [062/162] (901s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[105.332343] Starting subtest: addfb25-yf-tiled-legacy
[105.333432] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[105.558954] [063/162] (901s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[105.686544] Starting subtest: addfb25-y-tiled-legacy
[105.687568] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[105.873280] [064/162] (901s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[105.997255] Starting subtest: addfb25-y-tiled-small-legacy
[105.998353] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[106.183830] [065/162] (900s left) kms_addfb_basic (bad-pitch-0)
[106.253768] Starting subtest: bad-pitch-0
[106.254859] Subtest bad-pitch-0: SUCCESS (0.000s)
[106.523217] [066/162] (900s left) kms_addfb_basic (bad-pitch-1024)
[106.600851] Starting subtest: bad-pitch-1024
[106.602103] Subtest bad-pitch-1024: SUCCESS (0.000s)
[106.808281] [067/162] (900s left) kms_addfb_basic (bad-pitch-128)
[106.879444] Starting subtest: bad-pitch-128
[106.880462] Subtest bad-pitch-128: SUCCESS (0.000s)
[107.131833] [068/162] (900s left) kms_addfb_basic (bad-pitch-256)
[107.201281] Starting subtest: bad-pitch-256
[107.202263] Subtest bad-pitch-256: SUCCESS (0.000s)
[107.440336] [069/162] (899s left) kms_addfb_basic (bad-pitch-32)
[107.510207] Starting subtest: bad-pitch-32
[107.511225] Subtest bad-pitch-32: SUCCESS (0.000s)
[107.756589] [070/162] (899s left) kms_addfb_basic (bad-pitch-63)
[107.826466] Starting subtest: bad-pitch-63
[107.827483] Subtest bad-pitch-63: SUCCESS (0.000s)
[108.061431] [071/162] (899s left) kms_addfb_basic (bad-pitch-65536)
[108.131969] Starting subtest: bad-pitch-65536
[108.133140] Subtest bad-pitch-65536: SUCCESS (0.000s)
[108.360587] [072/162] (899s left) kms_addfb_basic (bad-pitch-999)
[108.432245] Starting subtest: bad-pitch-999
[108.433258] Subtest bad-pitch-999: SUCCESS (0.000s)
[108.692205] [073/162] (899s left) kms_addfb_basic (basic)
[108.762640] Starting subtest: basic
[108.763696] Subtest basic: SUCCESS (0.000s)
[108.963980] [074/162] (898s left) kms_addfb_basic (basic-x-tiled-legacy)
[109.034830] Starting subtest: basic-x-tiled-legacy
[109.035966] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[109.297818] [075/162] (898s left) kms_addfb_basic (basic-y-tiled-legacy)
[109.430518] Starting subtest: basic-y-tiled-legacy
[109.431611] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[109.721547] [076/162] (898s left) kms_addfb_basic (bo-too-small)
[109.848355] Starting subtest: bo-too-small
[109.849326] Subtest bo-too-small: SUCCESS (0.001s)
[110.012872] [077/162] (898s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[110.140516] Starting subtest: bo-too-small-due-to-tiling
[110.141570] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[110.350584] [078/162] (897s left) kms_addfb_basic (clobberred-modifier)
[110.419622] Starting subtest: clobberred-modifier
[110.420846] Subtest clobberred-modifier: SUCCESS (0.000s)
[110.649365] [079/162] (897s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[110.719446] Starting subtest: framebuffer-vs-set-tiling
[110.720469] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[110.980928] [080/162] (897s left) kms_addfb_basic (invalid-get-prop)
[111.053122] Starting subtest: invalid-get-prop
[111.054149] Subtest invalid-get-prop: SUCCESS (0.000s)
[111.303349] [081/162] (897s left) kms_addfb_basic (invalid-get-prop-any)
[111.373565] Starting subtest: invalid-get-prop-any
[111.374679] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[111.626695] [082/162] (896s left) kms_addfb_basic (invalid-set-prop)
[111.695352] Starting subtest: invalid-set-prop
[111.696441] Subtest invalid-set-prop: SUCCESS (0.000s)
[111.913400] [083/162] (896s left) kms_addfb_basic (invalid-set-prop-any)
[111.983263] Starting subtest: invalid-set-prop-any
[111.984373] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[112.286644] [084/162] (896s left) kms_addfb_basic (no-handle)
[112.358404] Starting subtest: no-handle
[112.359445] Subtest no-handle: SUCCESS (0.000s)
[112.630840] [085/162] (896s left) kms_addfb_basic (size-max)
[112.759218] Starting subtest: size-max
[112.760198] Subtest size-max: SUCCESS (0.000s)
[112.954605] [086/162] (896s left) kms_addfb_basic (small-bo)
[113.082284] Starting subtest: small-bo
[113.083298] Subtest small-bo: SUCCESS (0.000s)
[113.248324] [087/162] (895s left) kms_addfb_basic (tile-pitch-mismatch)
[113.320043] Starting subtest: tile-pitch-mismatch
[113.321116] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[113.575917] [088/162] (895s left) kms_addfb_basic (too-high)
[113.700403] Starting subtest: too-high
[113.701500] Subtest too-high: SUCCESS (0.001s)
[113.922358] [089/162] (895s left) kms_addfb_basic (too-wide)
[114.046400] Starting subtest: too-wide
[114.047442] Subtest too-wide: SUCCESS (0.000s)
[114.204230] [090/162] (895s left) kms_addfb_basic (unused-handle)
[114.272366] Starting subtest: unused-handle
[114.273431] Subtest unused-handle: SUCCESS (0.000s)
[114.536928] [091/162] (894s left) kms_addfb_basic (unused-modifier)
[114.606254] Starting subtest: unused-modifier
[114.607256] Subtest unused-modifier: SUCCESS (0.000s)
[114.865877] [092/162] (894s left) kms_addfb_basic (unused-offsets)
[114.935681] Starting subtest: unused-offsets
[114.936785] Subtest unused-offsets: SUCCESS (0.000s)
[115.184405] [093/162] (894s left) kms_addfb_basic (unused-pitches)
[115.254626] Starting subtest: unused-pitches
[115.255676] Subtest unused-pitches: SUCCESS (0.000s)
[115.468873] [094/162] (894s left) kms_busy (basic)
[115.601919] Starting subtest: basic
[115.604147] Starting dynamic subtest: flip
[116.455889] Dynamic subtest flip: SUCCESS (0.851s)
[116.480275] Starting dynamic subtest: modeset
[120.038633] Dynamic subtest modeset: SUCCESS (3.579s)
[120.041213] Subtest basic: SUCCESS (4.434s)
[120.969861] [095/162] (888s left) kms_prop_blob (basic)
[121.042327] Starting subtest: basic
[121.043386] Subtest basic: SUCCESS (0.000s)
[121.186173] [096/162] (888s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[121.310223] Starting subtest: basic-busy-flip-before-cursor-atomic
[121.312242] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[121.519024] [097/162] (888s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[121.645428] Starting subtest: basic-busy-flip-before-cursor-legacy
[121.647404] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[121.804988] [098/162] (888s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[121.930395] Starting subtest: basic-flip-after-cursor-atomic
[123.207995] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.274s)
[124.184572] [099/162] (885s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[124.370362] Starting subtest: basic-flip-after-cursor-legacy
[125.705793] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.334s)
[126.601643] [100/162] (883s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[126.730580] Starting subtest: basic-flip-after-cursor-varying-size
[128.003496] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.269s)
[128.892079] [101/162] (881s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[129.026388] Starting subtest: basic-flip-before-cursor-atomic
[130.321784] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.294s)
[131.234956] [102/162] (879s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[131.358560] Starting subtest: basic-flip-before-cursor-legacy
[132.673033] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.311s)
[133.692556] [103/162] (876s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[133.820457] Starting subtest: basic-flip-before-cursor-varying-size
[135.127746] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.305s)
[136.109598] [104/162] (874s left) kms_dsc (dsc-basic)
[136.233573] Subtest dsc-basic: SKIP (0.000s)
[136.338471] [105/162] (874s left) kms_flip (basic-flip-vs-dpms)
[136.467964] Starting subtest: basic-flip-vs-dpms
[136.471586] Starting dynamic subtest: A-eDP1
[139.195577] Dynamic subtest A-eDP1: SUCCESS (2.722s)
[139.268081] Starting dynamic subtest: B-eDP1
[141.564902] Dynamic subtest B-eDP1: SUCCESS (2.361s)
[141.625096] Starting dynamic subtest: C-eDP1
[143.753466] Dynamic subtest C-eDP1: SUCCESS (2.182s)
[143.762235] Subtest basic-flip-vs-dpms: SUCCESS (7.293s)
[144.727585] [106/162] (866s left) kms_flip (basic-flip-vs-modeset)
[144.856246] Starting subtest: basic-flip-vs-modeset
[144.860888] Starting dynamic subtest: A-eDP1
[147.524683] Dynamic subtest A-eDP1: SUCCESS (2.662s)
[147.582594] Starting dynamic subtest: B-eDP1
[149.909228] Dynamic subtest B-eDP1: SUCCESS (2.378s)
[149.977879] Starting dynamic subtest: C-eDP1
[152.112154] Dynamic subtest C-eDP1: SUCCESS (2.197s)
[152.122837] Subtest basic-flip-vs-modeset: SUCCESS (7.266s)
[153.104188] [107/162] (857s left) kms_flip (basic-flip-vs-wf_vblank)
[153.230531] Starting subtest: basic-flip-vs-wf_vblank
[153.233105] Starting dynamic subtest: A-eDP1
[155.378434] Dynamic subtest A-eDP1: SUCCESS (2.146s)
[155.400465] Starting dynamic subtest: B-eDP1
[157.594536] Dynamic subtest B-eDP1: SUCCESS (2.214s)
[157.616531] Starting dynamic subtest: C-eDP1
[159.780995] Dynamic subtest C-eDP1: SUCCESS (2.185s)
[159.784406] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.556s)
[161.078797] [108/162] (849s left) kms_flip (basic-plain-flip)
[161.206315] Starting subtest: basic-plain-flip
[161.208721] Starting dynamic subtest: A-eDP1
[163.118254] Dynamic subtest A-eDP1: SUCCESS (1.904s)
[163.175300] Starting dynamic subtest: B-eDP1
[165.025974] Dynamic subtest B-eDP1: SUCCESS (1.901s)
[165.094821] Starting dynamic subtest: C-eDP1
[166.964055] Dynamic subtest C-eDP1: SUCCESS (1.930s)
[166.971876] Subtest basic-plain-flip: SUCCESS (5.766s)
[168.286744] [109/162] (842s left) kms_force_connector_basic (force-connector-state)
[168.392811] Starting subtest: force-connector-state
[168.956456] Subtest force-connector-state: SUCCESS (0.563s)
[170.210153] [110/162] (840s left) kms_force_connector_basic (force-edid)
[170.310401] Starting subtest: force-edid
[170.347152] Subtest force-edid: SUCCESS (0.036s)
[170.586558] [111/162] (840s left) kms_force_connector_basic (force-load-detect)
[170.690018] Starting subtest: force-load-detect
[170.691919] Subtest force-load-detect: SKIP (0.000s)
[170.903937] [112/162] (840s left) kms_force_connector_basic (prune-stale-modes)
[171.005196] Starting subtest: prune-stale-modes
[171.042326] Subtest prune-stale-modes: SUCCESS (0.038s)
[171.284230] [113/162] (840s left) kms_frontbuffer_tracking (basic)
[171.415078] Starting subtest: basic
[175.108483] Subtest basic: SUCCESS (3.694s)
[175.306498] [114/162] (836s left) kms_hdmi_inject (inject-audio)
[175.705508] Starting subtest: inject-audio
[175.835165] Subtest inject-audio: SUCCESS (0.133s)
[176.738907] [115/162] (834s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[176.866162] Starting subtest: compare-crc-sanitycheck-xr24
[176.867337] Starting dynamic subtest: pipe-A-eDP-1
[177.617640] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.744s)
[177.680558] Starting dynamic subtest: pipe-B-eDP-1
[179.076167] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.445s)
[179.149275] Starting dynamic subtest: pipe-C-eDP-1
[180.506179] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.404s)
[180.509314] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.636s)
[181.538834] [116/162] (830s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[181.667128] Starting subtest: compare-crc-sanitycheck-nv12
[181.668334] Starting dynamic subtest: pipe-A-eDP-1
[181.670356] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[181.688094] Starting dynamic subtest: pipe-B-eDP-1
[181.690337] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[181.706871] Starting dynamic subtest: pipe-C-eDP-1
[181.709531] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[181.712053] Subtest compare-crc-sanitycheck-nv12: SKIP (0.011s)
[181.816140] [117/162] (829s left) kms_pipe_crc_basic (hang-read-crc)
[181.939081] Starting subtest: hang-read-crc
[181.940175] Starting dynamic subtest: pipe-A-eDP-1
[184.490837] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.549s)
[184.529883] Starting dynamic subtest: pipe-B-eDP-1
[187.716794] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.216s)
[187.790985] Starting dynamic subtest: pipe-C-eDP-1
[190.952202] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.218s)
[190.954358] Subtest hang-read-crc: SUCCESS (9.013s)
[191.919183] [118/162] (819s left) kms_pipe_crc_basic (nonblocking-crc)
[192.044973] Starting subtest: nonblocking-crc
[192.047971] Starting dynamic subtest: pipe-A-eDP-1
[193.086656] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.032s)
[193.170664] Starting dynamic subtest: pipe-B-eDP-1
[194.759582] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.661s)
[194.846283] Starting dynamic subtest: pipe-C-eDP-1
[196.508197] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.735s)
[196.519508] Subtest nonblocking-crc: SUCCESS (4.466s)
[197.408832] [119/162] (814s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[197.534556] Starting subtest: nonblocking-crc-frame-sequence
[197.535575] Starting dynamic subtest: pipe-A-eDP-1
[198.564545] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.026s)
[198.644842] Starting dynamic subtest: pipe-B-eDP-1
[200.178528] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.599s)
[200.253917] Starting dynamic subtest: pipe-C-eDP-1
[201.884324] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.688s)
[201.886283] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.351s)
[202.882391] [120/162] (809s left) kms_pipe_crc_basic (read-crc)
[203.008986] Starting subtest: read-crc
[203.012302] Starting dynamic subtest: pipe-A-eDP-1
[203.879626] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.863s)
[203.956150] Starting dynamic subtest: pipe-B-eDP-1
[205.561472] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.664s)
[205.642158] Starting dynamic subtest: pipe-C-eDP-1
[207.143246] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.565s)
[207.145733] Subtest read-crc: SUCCESS (4.135s)
[208.095709] [121/162] (804s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[208.223187] Starting subtest: read-crc-frame-sequence
[208.224191] Starting dynamic subtest: pipe-A-eDP-1
[209.148398] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.922s)
[209.231039] Starting dynamic subtest: pipe-B-eDP-1
[210.726724] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.563s)
[210.807585] Starting dynamic subtest: pipe-C-eDP-1
[212.301488] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.549s)
[212.304311] Subtest read-crc-frame-sequence: SUCCESS (4.079s)
[213.325840] [122/162] (798s left) kms_pm_backlight (basic-brightness)
[213.455610] Starting subtest: basic-brightness
[213.523850] Starting dynamic subtest: eDP-1
[214.166120] Dynamic subtest eDP-1: SUCCESS (0.633s)
[214.168921] Subtest basic-brightness: SUCCESS (0.706s)
[215.149967] [123/162] (797s left) kms_pm_rpm (basic-pci-d3-state)
[216.899377] Starting subtest: basic-pci-d3-state
[217.481248] Subtest basic-pci-d3-state: SUCCESS (0.588s)
[217.715617] [124/162] (794s left) kms_pm_rpm (basic-rte)
[217.787512] Starting subtest: basic-rte
[220.020771] Subtest basic-rte: SUCCESS (2.231s)
[221.436476] [125/162] (791s left) kms_psr (psr-primary-page-flip)
[221.570948] Starting subtest: psr-primary-page-flip
[221.572052] Starting dynamic subtest: eDP-1
[223.664841] Dynamic subtest eDP-1: SUCCESS (2.082s)
[223.667741] Subtest psr-primary-page-flip: SUCCESS (2.089s)
[224.635267] [126/162] (788s left) kms_psr (psr-cursor-plane-move)
[224.760596] Starting subtest: psr-cursor-plane-move
[224.761644] Starting dynamic subtest: eDP-1
[226.795657] Dynamic subtest eDP-1: SUCCESS (2.023s)
[226.798343] Subtest psr-cursor-plane-move: SUCCESS (2.029s)
[227.715080] [127/162] (785s left) kms_psr (psr-sprite-plane-onoff)
[227.846163] Starting subtest: psr-sprite-plane-onoff
[227.849151] Starting dynamic subtest: eDP-1
[229.896205] Dynamic subtest eDP-1: SUCCESS (2.039s)
[229.899342] Subtest psr-sprite-plane-onoff: SUCCESS (2.046s)
[230.840990] [128/162] (782s left) kms_psr (psr-primary-mmap-gtt)
[230.967578] Starting subtest: psr-primary-mmap-gtt
[230.971145] Starting dynamic subtest: eDP-1
[232.917094] Dynamic subtest eDP-1: SUCCESS (1.943s)
[232.925800] Subtest psr-primary-mmap-gtt: SUCCESS (1.952s)
[233.860869] [129/162] (779s left) kms_setmode (basic-clone-single-crtc)
[233.934566] Starting subtest: basic-clone-single-crtc
[233.937330] Subtest basic-clone-single-crtc: SKIP (0.000s)
[234.124582] [130/162] (779s left) i915_pm_rps (basic-api)
[234.206886] Starting subtest: basic-api
[234.212985] Subtest basic-api: SUCCESS (0.006s)
[234.375755] [131/162] (778s left) prime_self_import (basic-llseek-bad)
[234.444343] Starting subtest: basic-llseek-bad
[234.449415] Subtest basic-llseek-bad: SUCCESS (0.004s)
[234.646663] [132/162] (778s left) prime_self_import (basic-llseek-size)
[234.714137] Starting subtest: basic-llseek-size
[234.719272] Subtest basic-llseek-size: SUCCESS (0.005s)
[234.911868] [133/162] (778s left) prime_self_import (basic-with_fd_dup)
[234.980507] Starting subtest: basic-with_fd_dup
[234.984694] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[235.189333] [134/162] (778s left) prime_self_import (basic-with_one_bo)
[235.257383] Starting subtest: basic-with_one_bo
[235.262429] Subtest basic-with_one_bo: SUCCESS (0.005s)
[235.425651] [135/162] (778s left) prime_self_import (basic-with_one_bo_two_files)
[235.493188] Starting subtest: basic-with_one_bo_two_files
[235.499484] Subtest basic-with_one_bo_two_files: SUCCESS (0.005s)
[235.670945] [136/162] (778s left) prime_self_import (basic-with_two_bos)
[235.739192] Starting subtest: basic-with_two_bos
[235.744173] Subtest basic-with_two_bos: SUCCESS (0.005s)
[235.949522] [137/162] (777s left) prime_vgem (basic-fence-flip)
[236.041354] Starting subtest: basic-fence-flip
[236.557241] Subtest basic-fence-flip: SUCCESS (0.515s)
[236.775910] [138/162] (777s left) prime_vgem (basic-fence-mmap)
[236.875473] Starting subtest: basic-fence-mmap
[236.911946] Subtest basic-fence-mmap: SUCCESS (0.036s)
[237.103119] [139/162] (776s left) prime_vgem (basic-fence-read)
[237.201395] Starting subtest: basic-fence-read
[237.285318] Subtest basic-fence-read: SUCCESS (0.084s)
[237.457763] [140/162] (776s left) prime_vgem (basic-gtt)
[237.561497] Starting subtest: basic-gtt
[237.578967] Subtest basic-gtt: SUCCESS (0.017s)
[237.758836] [141/162] (776s left) prime_vgem (basic-read)
[237.858674] Starting subtest: basic-read
[237.898102] Subtest basic-read: SUCCESS (0.039s)
[238.105718] [142/162] (776s left) prime_vgem (basic-write)
[238.215280] Starting subtest: basic-write
[238.257142] Subtest basic-write: SUCCESS (0.042s)
[238.421917] [143/162] (775s left) vgem_basic (setversion)
[238.515409] Starting subtest: setversion
[238.517673] Subtest setversion: SUCCESS (0.000s)
[238.624618] [144/162] (775s left) vgem_basic (create)
[238.698358] Starting subtest: create
[238.699351] Subtest create: SUCCESS (0.001s)
[238.828126] [145/162] (775s left) vgem_basic (debugfs)
[238.937429] Starting subtest: debugfs
[238.938446] Subtest debugfs: SUCCESS (0.001s)
[239.091473] [146/162] (775s left) vgem_basic (dmabuf-export)
[239.176454] Starting subtest: dmabuf-export
[239.189278] Subtest dmabuf-export: SUCCESS (0.013s)
[239.386570] [147/162] (775s left) vgem_basic (dmabuf-fence)
[239.464280] Starting subtest: dmabuf-fence
[239.465349] Subtest dmabuf-fence: SUCCESS (0.001s)
[239.598579] [148/162] (775s left) vgem_basic (dmabuf-fence-before)
[239.705576] Starting subtest: dmabuf-fence-before
[239.706686] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[239.849618] [149/162] (775s left) vgem_basic (dmabuf-mmap)
[239.945404] Starting subtest: dmabuf-mmap
[239.959656] Subtest dmabuf-mmap: SUCCESS (0.016s)
[240.095242] [150/162] (774s left) vgem_basic (mmap)
[240.200170] Starting subtest: mmap
[240.210492] Subtest mmap: SUCCESS (0.012s)
[240.348190] [151/162] (774s left) vgem_basic (second-client)
[240.456212] Starting subtest: second-client
[240.486910] Subtest second-client: SUCCESS (0.032s)
[240.646937] [152/162] (774s left) vgem_basic (sysfs)
[240.739452] Starting subtest: sysfs
[240.740516] Subtest sysfs: SUCCESS (0.001s)
[240.888081] [153/162] (774s left) core_hotunplug (unbind-rebind)
[241.044137] Starting subtest: unbind-rebind
[246.294106] Subtest unbind-rebind: SUCCESS (5.251s)
[246.379340] [154/162] (768s left) vgem_basic (unload)
[246.459824] Starting subtest: unload
[275.590195] Subtest unload: SUCCESS (29.094s)
[275.768863] [155/162] (739s left) i915_module_load (reload)
[275.838315] Starting subtest: reload
[280.015596] Subtest reload: SUCCESS (4.177s)
[280.120873] [156/162] (735s left) gem_lmem_swapping (basic)
[280.209928] Subtest basic: SKIP (0.000s)
[280.300867] [157/162] (735s left) gem_lmem_swapping (parallel-random-engines)
[280.380140] Subtest parallel-random-engines: SKIP (0.000s)
[280.467921] [158/162] (735s left) gem_lmem_swapping (random-engines)
[280.546276] Subtest random-engines: SKIP (0.000s)
[280.634556] [159/162] (735s left) gem_lmem_swapping (verify-random)
[280.712469] Subtest verify-random: SKIP (0.000s)
[280.801927] [160/162] (734s left) i915_pm_rpm (module-reload)
[283.193291] Starting subtest: module-reload
[296.447848] Subtest module-reload: SUCCESS (13.254s)
[296.494875] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[296.497378] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[296.501343] [161/162] (719s left) i915_selftest (live)
[296.578047] Starting subtest: live
[296.579131] Starting dynamic subtest: sanitycheck
[299.364963] Dynamic subtest sanitycheck: SUCCESS (2.777s)
[299.384219] Starting dynamic subtest: uncore
[302.113883] Dynamic subtest uncore: SUCCESS (2.746s)
[302.132529] Starting dynamic subtest: workarounds
[319.389521] Dynamic subtest workarounds: SUCCESS (3.006s)
[319.410169] Starting dynamic subtest: gt_engines
[319.411424] Dynamic subtest gt_engines: SUCCESS (3.121s)
[319.466090] Starting dynamic subtest: gt_timelines