[40.452447] Initializing watchdogs
[40.452591]   /dev/watchdog0
[40.487263] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:1916 Intel Skylake (Gen9) Skylake GT2 [HD Graphics 520]
[40.494992] [001/162] (960s left) i915_module_load (load)
[40.578769] Starting subtest: load
[42.399503] Subtest load: SUCCESS (1.823s)
[42.457253] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[42.460371] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[42.464459] [002/162] (958s left) core_auth (basic-auth)
[42.548240] Starting subtest: basic-auth
[42.549212] Subtest basic-auth: SUCCESS (0.001s)
[42.734907] [003/162] (958s left) core_debugfs (read-all-entries)
[42.801699] Starting subtest: read-all-entries
[42.901077] Subtest read-all-entries: SUCCESS (0.100s)
[43.070841] [004/162] (958s left) core_sysfs (read-all-entries)
[43.138894] Starting subtest: read-all-entries
[43.144038] Subtest read-all-entries: SUCCESS (0.006s)
[43.325983] [005/162] (957s left) fbdev (eof)
[43.402156] Starting subtest: eof
[43.403186] Subtest eof: SUCCESS (0.001s)
[43.578976] [006/162] (957s left) fbdev (info)
[43.656545] Starting subtest: info
[43.657756] Subtest info: SUCCESS (0.000s)
[43.797530] [007/162] (957s left) fbdev (nullptr)
[43.866850] Starting subtest: nullptr
[43.867943] Subtest nullptr: SUCCESS (0.000s)
[44.037799] [008/162] (957s left) fbdev (read)
[44.118408] Starting subtest: read
[45.015029] Subtest read: SUCCESS (0.889s)
[45.147018] [009/162] (956s left) fbdev (write)
[45.217486] Starting subtest: write
[45.696090] Subtest write: SUCCESS (0.470s)
[45.824022] [010/162] (955s left) gem_basic (bad-close)
[45.891415] Starting subtest: bad-close
[45.893394] Subtest bad-close: SUCCESS (0.000s)
[46.061261] [011/162] (955s left) gem_basic (create-close)
[46.128728] Starting subtest: create-close
[46.130604] Subtest create-close: SUCCESS (0.000s)
[46.325644] [012/162] (955s left) gem_basic (create-fd-close)
[46.391924] Starting subtest: create-fd-close
[46.394453] Subtest create-fd-close: SUCCESS (0.000s)
[46.592417] [013/162] (955s left) gem_busy (busy@all-engines)
[46.684814] Starting subtest: busy
[46.685889] Starting dynamic subtest: all-engines
[46.749595] Dynamic subtest all-engines: SUCCESS (0.064s)
[46.750554] Subtest busy: SUCCESS (0.065s)
[47.299125] [014/162] (954s left) gem_close_race (basic-process)
[47.388721] Starting subtest: basic-process
[47.461643] Subtest basic-process: SUCCESS (0.073s)
[47.770347] [015/162] (954s left) gem_close_race (basic-threads)
[47.860411] Starting subtest: basic-threads
[49.037921] Subtest basic-threads: SUCCESS (1.178s)
[49.383972] [016/162] (952s left) gem_ctx_create (basic)
[49.470463] Starting subtest: basic
[49.471608] Subtest basic: SUCCESS (0.000s)
[49.781326] [017/162] (952s left) gem_ctx_create (basic-files)
[49.864109] Starting subtest: basic-files
[51.884399] Subtest basic-files: SUCCESS (2.020s)
[52.227644] [018/162] (950s left) gem_ctx_exec (basic)
[52.296848] Starting subtest: basic
[52.302231] Subtest basic: SUCCESS (0.006s)
[52.495824] [019/162] (949s left) gem_exec_basic (basic)
[52.569560] Starting subtest: basic
[52.570425] Starting dynamic subtest: rcs0-smem
[52.572826] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[52.585161] Starting dynamic subtest: bcs0-smem
[52.586054] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[52.597585] Starting dynamic subtest: vcs0-smem
[52.598695] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[52.610280] Starting dynamic subtest: vecs0-smem
[52.611455] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[52.612567] Subtest basic: SUCCESS (0.011s)
[52.898675] [020/162] (949s left) gem_exec_create (basic)
[52.980112] Starting subtest: basic
[52.981065] Starting dynamic subtest: smem
[55.019487] Dynamic subtest smem: SUCCESS (2.123s)
[55.020405] Subtest basic: SUCCESS (2.124s)
[55.379889] [021/162] (947s left) gem_exec_fence (basic-busy)
[55.606383] Starting subtest: basic-busy
[55.607312] Starting dynamic subtest: rcs0
[55.639168] Dynamic subtest rcs0: SUCCESS (0.034s)
[55.651554] Starting dynamic subtest: bcs0
[55.670425] Dynamic subtest bcs0: SUCCESS (0.032s)
[55.682549] Starting dynamic subtest: vcs0
[55.695124] Dynamic subtest vcs0: SUCCESS (0.025s)
[55.707235] Starting dynamic subtest: vecs0
[55.719227] Dynamic subtest vecs0: SUCCESS (0.024s)
[55.720362] Subtest basic-busy: SUCCESS (0.117s)
[56.539080] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[56.541658] [022/162] (946s left) gem_exec_fence (basic-wait)
[56.774944] Starting subtest: basic-wait
[56.776068] Starting dynamic subtest: rcs0
[56.812160] Dynamic subtest rcs0: SUCCESS (0.038s)
[56.825249] Starting dynamic subtest: bcs0
[56.838189] Dynamic subtest bcs0: SUCCESS (0.026s)
[56.852589] Starting dynamic subtest: vcs0
[56.872234] Dynamic subtest vcs0: SUCCESS (0.035s)
[56.885426] Starting dynamic subtest: vecs0
[56.897290] Dynamic subtest vecs0: SUCCESS (0.025s)
[56.898218] Subtest basic-wait: SUCCESS (0.126s)
[57.690833] [023/162] (944s left) gem_exec_fence (basic-await)
[57.921034] Starting subtest: basic-await
[57.922442] Starting dynamic subtest: rcs0
[58.026904] Dynamic subtest rcs0: SUCCESS (0.108s)
[58.044178] Starting dynamic subtest: bcs0
[58.129724] Dynamic subtest bcs0: SUCCESS (0.103s)
[58.146837] Starting dynamic subtest: vcs0
[58.228600] Dynamic subtest vcs0: SUCCESS (0.102s)
[58.243514] Starting dynamic subtest: vecs0
[58.332532] Dynamic subtest vecs0: SUCCESS (0.106s)
[58.333541] Subtest basic-await: SUCCESS (0.421s)
[59.165770] [024/162] (943s left) gem_exec_fence (nb-await)
[59.401325] Starting subtest: nb-await
[59.402498] Starting dynamic subtest: rcs0
[59.472815] Dynamic subtest rcs0: SUCCESS (0.071s)
[59.512041] Starting dynamic subtest: bcs0
[59.563165] Dynamic subtest bcs0: SUCCESS (0.091s)
[59.592453] Starting dynamic subtest: vcs0
[59.647227] Dynamic subtest vcs0: SUCCESS (0.081s)
[59.705178] Starting dynamic subtest: vecs0
[59.752520] Dynamic subtest vecs0: SUCCESS (0.106s)
[59.756502] Subtest nb-await: SUCCESS (0.358s)
[60.574920] [025/162] (942s left) gem_exec_gttfill (basic)
[60.659320] Starting subtest: basic
[66.373926] Subtest basic: SUCCESS (5.779s)
[66.783268] [026/162] (936s left) gem_exec_parallel (engines)
[66.936022] Starting subtest: engines
[66.937484] Starting dynamic subtest: basic
[70.050933] Dynamic subtest basic: SUCCESS (3.135s)
[70.066509] Starting dynamic subtest: contexts
[75.580678] Dynamic subtest contexts: SUCCESS (5.545s)
[75.599654] Starting dynamic subtest: fds
[78.759579] Dynamic subtest fds: SUCCESS (3.186s)
[78.774417] Starting dynamic subtest: userptr
[81.187530] Dynamic subtest userptr: SUCCESS (2.431s)
[81.188714] Subtest engines: SUCCESS (14.298s)
[81.561982] [027/162] (921s left) gem_exec_store (basic)
[81.646379] Starting subtest: basic
[81.710931] Subtest basic: SUCCESS (0.064s)
[82.041929] [028/162] (921s left) gem_flink_basic (bad-flink)
[82.109871] Starting subtest: bad-flink
[82.110957] Subtest bad-flink: SUCCESS (0.000s)
[82.287289] [029/162] (920s left) gem_flink_basic (bad-open)
[82.353997] Starting subtest: bad-open
[82.355128] Subtest bad-open: SUCCESS (0.000s)
[82.543306] [030/162] (920s left) gem_flink_basic (basic)
[82.608406] Starting subtest: basic
[82.609321] Subtest basic: SUCCESS (0.000s)
[82.834323] [031/162] (920s left) gem_flink_basic (double-flink)
[82.965760] Starting subtest: double-flink
[82.966877] Subtest double-flink: SUCCESS (0.000s)
[83.134145] [032/162] (920s left) gem_flink_basic (flink-lifetime)
[83.202501] Starting subtest: flink-lifetime
[83.204998] Subtest flink-lifetime: SUCCESS (0.002s)
[83.406630] [033/162] (920s left) gem_huc_copy (huc-copy)
[83.484231] Starting subtest: huc-copy
[83.486350] Subtest huc-copy: SKIP (0.000s)
[83.689088] [034/162] (919s left) gem_linear_blits (basic)
[83.888869] Starting subtest: basic
[83.896794] Subtest basic: SUCCESS (0.009s)
[84.139578] [035/162] (919s left) gem_mmap (basic)
[84.274055] Starting subtest: basic
[84.279040] Subtest basic: SUCCESS (0.001s)
[84.478873] [036/162] (919s left) gem_mmap_gtt (basic)
[84.546147] Starting subtest: basic
[84.547195] Subtest basic: SUCCESS (0.001s)
[84.716150] [037/162] (919s left) gem_render_linear_blits (basic)
[84.790978] Starting subtest: basic
[84.841112] Subtest basic: SUCCESS (0.050s)
[84.939292] [038/162] (918s left) gem_render_tiled_blits (basic)
[85.020497] Starting subtest: basic
[85.073341] Subtest basic: SUCCESS (0.053s)
[85.173353] [039/162] (918s left) gem_ringfill (basic-all)
[85.452287] Starting subtest: basic-all
[86.624844] Subtest basic-all: SUCCESS (1.163s)
[86.871879] [040/162] (917s left) gem_softpin (allocator-basic)
[86.949151] Starting subtest: allocator-basic
[87.124227] Subtest allocator-basic: SUCCESS (0.167s)
[87.342596] [041/162] (916s left) gem_softpin (allocator-basic-reserve)
[87.416602] Starting subtest: allocator-basic-reserve
[87.614024] Subtest allocator-basic-reserve: SUCCESS (0.189s)
[87.835502] [042/162] (916s left) gem_softpin (safe-alignment)
[87.913510] Starting subtest: safe-alignment
[87.933288] Subtest safe-alignment: SUCCESS (0.020s)
[88.137548] [043/162] (916s left) gem_sync (basic-all)
[88.223507] Starting subtest: basic-all
[90.322428] Subtest basic-all: SUCCESS (2.098s)
[90.661889] [044/162] (913s left) gem_sync (basic-each)
[90.749252] Starting subtest: basic-each
[92.913430] Subtest basic-each: SUCCESS (2.165s)
[93.287558] [045/162] (911s left) gem_tiled_blits (basic)
[93.451681] Starting subtest: basic
[93.470939] Subtest basic: SUCCESS (0.020s)
[93.716620] [046/162] (910s left) gem_tiled_fence_blits (basic)
[93.955234] Starting subtest: basic
[93.968724] Subtest basic: SUCCESS (0.014s)
[94.212540] [047/162] (910s left) gem_tiled_pread_basic
[94.677159] [048/162] (910s left) gem_wait (busy@all-engines)
[94.772017] Starting subtest: busy
[94.773083] Starting dynamic subtest: all-engines
[95.313264] Dynamic subtest all-engines: SUCCESS (0.541s)
[95.314237] Subtest busy: SUCCESS (0.542s)
[95.646644] [049/162] (909s left) gem_wait (wait@all-engines)
[95.737255] Starting subtest: wait
[95.738408] Starting dynamic subtest: all-engines
[96.799044] Dynamic subtest all-engines: SUCCESS (1.060s)
[96.803666] Subtest wait: SUCCESS (1.062s)
[97.198865] [050/162] (907s left) i915_getparams_basic (basic-eu-total)
[97.266976] Starting subtest: basic-eu-total
[97.269195] Subtest basic-eu-total: SUCCESS (0.000s)
[97.449392] [051/162] (907s left) i915_getparams_basic (basic-subslice-total)
[97.515081] Starting subtest: basic-subslice-total
[97.517280] Subtest basic-subslice-total: SUCCESS (0.000s)
[97.688964] [052/162] (907s left) i915_hangman (error-state-basic)
[97.785842] Starting subtest: error-state-basic
[97.798853] Subtest error-state-basic: SUCCESS (0.016s)
[98.019605] [053/162] (907s left) i915_pciid
[98.249871] [054/162] (907s left) intel_hwmon (hwmon-read)
[98.317747] Subtest hwmon-read: SKIP (0.000s)
[98.408729] [055/162] (906s left) intel_hwmon (hwmon-write)
[98.476767] Subtest hwmon-write: SKIP (0.000s)
[98.561116] [056/162] (906s left) kms_addfb_basic (addfb25-4-tiled)
[98.690254] Starting subtest: addfb25-4-tiled
[98.691231] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[98.907295] [057/162] (906s left) kms_addfb_basic (addfb25-bad-modifier)
[99.032342] Starting subtest: addfb25-bad-modifier
[99.033332] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[99.238020] [058/162] (906s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[99.358193] Starting subtest: addfb25-framebuffer-vs-set-tiling
[99.359165] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[99.558875] [059/162] (906s left) kms_addfb_basic (addfb25-modifier-no-flag)
[99.688325] Starting subtest: addfb25-modifier-no-flag
[99.689261] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[99.902774] [060/162] (905s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[100.030392] Starting subtest: addfb25-x-tiled-legacy
[100.031337] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[100.237483] [061/162] (905s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[100.356311] Starting subtest: addfb25-x-tiled-mismatch-legacy
[100.357297] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[100.544602] [062/162] (905s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[100.670171] Starting subtest: addfb25-yf-tiled-legacy
[100.671166] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[100.870591] [063/162] (905s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[100.991143] Starting subtest: addfb25-y-tiled-legacy
[100.992135] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[101.188944] [064/162] (904s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[101.307261] Starting subtest: addfb25-y-tiled-small-legacy
[101.308258] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[101.529379] [065/162] (904s left) kms_addfb_basic (bad-pitch-0)
[101.598184] Starting subtest: bad-pitch-0
[101.599136] Subtest bad-pitch-0: SUCCESS (0.000s)
[101.850689] [066/162] (904s left) kms_addfb_basic (bad-pitch-1024)
[101.917617] Starting subtest: bad-pitch-1024
[101.918599] Subtest bad-pitch-1024: SUCCESS (0.000s)
[102.188253] [067/162] (904s left) kms_addfb_basic (bad-pitch-128)
[102.255272] Starting subtest: bad-pitch-128
[102.256246] Subtest bad-pitch-128: SUCCESS (0.000s)
[102.523027] [068/162] (903s left) kms_addfb_basic (bad-pitch-256)
[102.590550] Starting subtest: bad-pitch-256
[102.591488] Subtest bad-pitch-256: SUCCESS (0.000s)
[102.856503] [069/162] (903s left) kms_addfb_basic (bad-pitch-32)
[102.924360] Starting subtest: bad-pitch-32
[102.925352] Subtest bad-pitch-32: SUCCESS (0.000s)
[103.189043] [070/162] (903s left) kms_addfb_basic (bad-pitch-63)
[103.255700] Starting subtest: bad-pitch-63
[103.256946] Subtest bad-pitch-63: SUCCESS (0.000s)
[103.521947] [071/162] (903s left) kms_addfb_basic (bad-pitch-65536)
[103.589368] Starting subtest: bad-pitch-65536
[103.590381] Subtest bad-pitch-65536: SUCCESS (0.000s)
[103.847541] [072/162] (902s left) kms_addfb_basic (bad-pitch-999)
[103.915159] Starting subtest: bad-pitch-999
[103.916095] Subtest bad-pitch-999: SUCCESS (0.000s)
[104.191507] [073/162] (902s left) kms_addfb_basic (basic)
[104.258901] Starting subtest: basic
[104.260366] Subtest basic: SUCCESS (0.000s)
[104.521770] [074/162] (902s left) kms_addfb_basic (basic-x-tiled-legacy)
[104.591240] Starting subtest: basic-x-tiled-legacy
[104.592161] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[104.855998] [075/162] (902s left) kms_addfb_basic (basic-y-tiled-legacy)
[104.933454] Starting subtest: basic-y-tiled-legacy
[104.934497] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[105.182932] [076/162] (901s left) kms_addfb_basic (bo-too-small)
[105.306232] Starting subtest: bo-too-small
[105.307236] Subtest bo-too-small: SUCCESS (0.000s)
[105.546572] [077/162] (901s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[105.670287] Starting subtest: bo-too-small-due-to-tiling
[105.671313] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[105.873286] [078/162] (901s left) kms_addfb_basic (clobberred-modifier)
[105.940231] Starting subtest: clobberred-modifier
[105.941284] Subtest clobberred-modifier: SUCCESS (0.000s)
[106.198690] [079/162] (901s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[106.268138] Starting subtest: framebuffer-vs-set-tiling
[106.269130] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[106.509685] [080/162] (900s left) kms_addfb_basic (invalid-get-prop)
[106.578456] Starting subtest: invalid-get-prop
[106.579429] Subtest invalid-get-prop: SUCCESS (0.000s)
[106.834672] [081/162] (900s left) kms_addfb_basic (invalid-get-prop-any)
[106.903936] Starting subtest: invalid-get-prop-any
[106.904928] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[107.174300] [082/162] (900s left) kms_addfb_basic (invalid-set-prop)
[107.242158] Starting subtest: invalid-set-prop
[107.243036] Subtest invalid-set-prop: SUCCESS (0.000s)
[107.505848] [083/162] (900s left) kms_addfb_basic (invalid-set-prop-any)
[107.573360] Starting subtest: invalid-set-prop-any
[107.574319] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[107.821817] [084/162] (899s left) kms_addfb_basic (no-handle)
[107.890331] Starting subtest: no-handle
[107.891242] Subtest no-handle: SUCCESS (0.000s)
[108.150297] [085/162] (899s left) kms_addfb_basic (size-max)
[108.268261] Starting subtest: size-max
[108.269290] Subtest size-max: SUCCESS (0.000s)
[108.467954] [086/162] (899s left) kms_addfb_basic (small-bo)
[108.590221] Starting subtest: small-bo
[108.591188] Subtest small-bo: SUCCESS (0.000s)
[108.791376] [087/162] (899s left) kms_addfb_basic (tile-pitch-mismatch)
[108.858665] Starting subtest: tile-pitch-mismatch
[108.859914] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[109.114900] [088/162] (899s left) kms_addfb_basic (too-high)
[109.234197] Starting subtest: too-high
[109.235188] Subtest too-high: SUCCESS (0.001s)
[109.437889] [089/162] (898s left) kms_addfb_basic (too-wide)
[109.563224] Starting subtest: too-wide
[109.564283] Subtest too-wide: SUCCESS (0.000s)
[109.774222] [090/162] (898s left) kms_addfb_basic (unused-handle)
[109.841123] Starting subtest: unused-handle
[109.842835] Subtest unused-handle: SUCCESS (0.000s)
[110.099092] [091/162] (898s left) kms_addfb_basic (unused-modifier)
[110.168084] Starting subtest: unused-modifier
[110.169023] Subtest unused-modifier: SUCCESS (0.000s)
[110.402888] [092/162] (898s left) kms_addfb_basic (unused-offsets)
[110.469948] Starting subtest: unused-offsets
[110.471156] Subtest unused-offsets: SUCCESS (0.000s)
[110.726554] [093/162] (897s left) kms_addfb_basic (unused-pitches)
[110.792949] Starting subtest: unused-pitches
[110.794174] Subtest unused-pitches: SUCCESS (0.000s)
[111.059154] [094/162] (897s left) kms_busy (basic)
[111.185198] Starting subtest: basic
[111.186183] Starting dynamic subtest: flip
[112.051748] Dynamic subtest flip: SUCCESS (0.865s)
[112.071033] Starting dynamic subtest: modeset
[115.768089] Dynamic subtest modeset: SUCCESS (3.713s)
[115.770650] Subtest basic: SUCCESS (4.583s)
[116.741290] [095/162] (892s left) kms_prop_blob (basic)
[116.810449] Starting subtest: basic
[116.811475] Subtest basic: SUCCESS (0.000s)
[116.985876] [096/162] (891s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[117.107476] Starting subtest: basic-busy-flip-before-cursor-atomic
[117.109391] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[117.262906] [097/162] (891s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[117.381170] Starting subtest: basic-busy-flip-before-cursor-legacy
[117.382985] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[117.559836] [098/162] (891s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[117.680321] Starting subtest: basic-flip-after-cursor-atomic
[118.997444] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.315s)
[120.028901] [099/162] (889s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[120.150147] Starting subtest: basic-flip-after-cursor-legacy
[121.461396] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.308s)
[122.418824] [100/162] (886s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[122.540173] Starting subtest: basic-flip-after-cursor-varying-size
[123.828204] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.285s)
[124.840978] [101/162] (884s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[124.958171] Starting subtest: basic-flip-before-cursor-atomic
[126.240499] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.278s)
[127.185040] [102/162] (882s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[127.305143] Starting subtest: basic-flip-before-cursor-legacy
[128.610232] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.301s)
[129.613577] [103/162] (879s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[129.735648] Starting subtest: basic-flip-before-cursor-varying-size
[131.086551] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.346s)
[132.010481] [104/162] (877s left) kms_dsc (dsc-basic)
[132.134961] Subtest dsc-basic: SKIP (0.000s)
[132.227041] [105/162] (877s left) kms_flip (basic-flip-vs-dpms)
[132.353067] Starting subtest: basic-flip-vs-dpms
[132.356784] Starting dynamic subtest: A-eDP1
[135.032897] Dynamic subtest A-eDP1: SUCCESS (2.674s)
[135.101840] Starting dynamic subtest: B-eDP1
[137.343559] Dynamic subtest B-eDP1: SUCCESS (2.301s)
[137.411266] Starting dynamic subtest: C-eDP1
[139.626306] Dynamic subtest C-eDP1: SUCCESS (2.275s)
[139.636048] Subtest basic-flip-vs-dpms: SUCCESS (7.282s)
[140.625156] [106/162] (869s left) kms_flip (basic-flip-vs-modeset)
[140.744927] Starting subtest: basic-flip-vs-modeset
[140.747435] Starting dynamic subtest: A-eDP1
[143.451508] Dynamic subtest A-eDP1: SUCCESS (2.703s)
[143.510364] Starting dynamic subtest: B-eDP1
[145.824782] Dynamic subtest B-eDP1: SUCCESS (2.361s)
[145.896350] Starting dynamic subtest: C-eDP1
[148.132925] Dynamic subtest C-eDP1: SUCCESS (2.301s)
[148.147500] Subtest basic-flip-vs-modeset: SUCCESS (7.397s)
[149.133375] [107/162] (860s left) kms_flip (basic-flip-vs-wf_vblank)
[149.257111] Starting subtest: basic-flip-vs-wf_vblank
[149.259367] Starting dynamic subtest: A-eDP1
[151.427533] Dynamic subtest A-eDP1: SUCCESS (2.168s)
[151.450676] Starting dynamic subtest: B-eDP1
[153.550313] Dynamic subtest B-eDP1: SUCCESS (2.121s)
[153.571743] Starting dynamic subtest: C-eDP1
[155.747122] Dynamic subtest C-eDP1: SUCCESS (2.195s)
[155.750941] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.495s)
[157.061451] [108/162] (852s left) kms_flip (basic-plain-flip)
[157.183418] Starting subtest: basic-plain-flip
[157.185681] Starting dynamic subtest: A-eDP1
[159.118417] Dynamic subtest A-eDP1: SUCCESS (1.928s)
[159.181835] Starting dynamic subtest: B-eDP1
[161.127382] Dynamic subtest B-eDP1: SUCCESS (1.999s)
[161.193173] Starting dynamic subtest: C-eDP1
[163.090449] Dynamic subtest C-eDP1: SUCCESS (1.956s)
[163.099133] Subtest basic-plain-flip: SUCCESS (5.916s)
[164.435916] [109/162] (845s left) kms_force_connector_basic (force-connector-state)
[164.534840] Starting subtest: force-connector-state
[165.071196] Subtest force-connector-state: SUCCESS (0.538s)
[166.402486] [110/162] (843s left) kms_force_connector_basic (force-edid)
[166.502643] Starting subtest: force-edid
[166.538092] Subtest force-edid: SUCCESS (0.035s)
[166.823126] [111/162] (843s left) kms_force_connector_basic (force-load-detect)
[166.919056] Starting subtest: force-load-detect
[166.921068] Subtest force-load-detect: SKIP (0.000s)
[167.126706] [112/162] (843s left) kms_force_connector_basic (prune-stale-modes)
[167.226451] Starting subtest: prune-stale-modes
[167.264731] Subtest prune-stale-modes: SUCCESS (0.037s)
[167.520605] [113/162] (842s left) kms_frontbuffer_tracking (basic)
[167.645636] Starting subtest: basic
[171.236702] Subtest basic: SUCCESS (3.592s)
[171.459378] [114/162] (839s left) kms_hdmi_inject (inject-audio)
[171.864753] Starting subtest: inject-audio
[171.988439] Subtest inject-audio: SUCCESS (0.124s)
[172.882606] [115/162] (837s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[173.076070] Starting subtest: compare-crc-sanitycheck-xr24
[173.077109] Starting dynamic subtest: pipe-A-eDP-1
[173.807698] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.720s)
[173.885488] Starting dynamic subtest: pipe-B-eDP-1
[175.226251] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.401s)
[175.306988] Starting dynamic subtest: pipe-C-eDP-1
[176.543186] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.300s)
[176.545563] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.469s)
[177.589692] [116/162] (833s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[177.709104] Starting subtest: compare-crc-sanitycheck-nv12
[177.710065] Starting dynamic subtest: pipe-A-eDP-1
[177.712030] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[177.729706] Starting dynamic subtest: pipe-B-eDP-1
[177.732086] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[177.747562] Starting dynamic subtest: pipe-C-eDP-1
[177.749997] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[177.752527] Subtest compare-crc-sanitycheck-nv12: SKIP (0.010s)
[177.906106] [117/162] (832s left) kms_pipe_crc_basic (hang-read-crc)
[178.028167] Starting subtest: hang-read-crc
[178.029134] Starting dynamic subtest: pipe-A-eDP-1
[180.601080] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.569s)
[180.686975] Starting dynamic subtest: pipe-B-eDP-1
[183.835224] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.220s)
[183.932197] Starting dynamic subtest: pipe-C-eDP-1
[186.980157] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.122s)
[186.982752] Subtest hang-read-crc: SUCCESS (8.948s)
[188.004194] [118/162] (823s left) kms_pipe_crc_basic (nonblocking-crc)
[188.144635] Starting subtest: nonblocking-crc
[188.148144] Starting dynamic subtest: pipe-A-eDP-1
[189.187593] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.033s)
[189.271880] Starting dynamic subtest: pipe-B-eDP-1
[190.878287] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.676s)
[190.962890] Starting dynamic subtest: pipe-C-eDP-1
[192.626628] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.737s)
[192.628558] Subtest nonblocking-crc: SUCCESS (4.483s)
[193.661148] [119/162] (817s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[193.785980] Starting subtest: nonblocking-crc-frame-sequence
[193.788980] Starting dynamic subtest: pipe-A-eDP-1
[194.775440] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.983s)
[194.865257] Starting dynamic subtest: pipe-B-eDP-1
[196.535210] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.727s)
[196.617088] Starting dynamic subtest: pipe-C-eDP-1
[198.214870] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.680s)
[198.218572] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.431s)
[199.190816] [120/162] (812s left) kms_pipe_crc_basic (read-crc)
[199.319039] Starting subtest: read-crc
[199.322252] Starting dynamic subtest: pipe-A-eDP-1
[200.187920] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.862s)
[200.275794] Starting dynamic subtest: pipe-B-eDP-1
[201.798796] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.595s)
[201.888519] Starting dynamic subtest: pipe-C-eDP-1
[203.376704] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.559s)
[203.379041] Subtest read-crc: SUCCESS (4.056s)
[204.366367] [121/162] (806s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[204.491044] Starting subtest: read-crc-frame-sequence
[204.494012] Starting dynamic subtest: pipe-A-eDP-1
[205.395209] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.892s)
[205.463381] Starting dynamic subtest: pipe-B-eDP-1
[206.984340] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.572s)
[207.066132] Starting dynamic subtest: pipe-C-eDP-1
[208.571962] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.571s)
[208.584178] Subtest read-crc-frame-sequence: SUCCESS (4.087s)
[209.579700] [122/162] (801s left) kms_pm_backlight (basic-brightness)
[209.703683] Starting subtest: basic-brightness
[209.775659] Starting dynamic subtest: eDP-1
[210.400993] Dynamic subtest eDP-1: SUCCESS (0.622s)
[210.410209] Subtest basic-brightness: SUCCESS (0.701s)
[211.377946] [123/162] (800s left) kms_pm_rpm (basic-pci-d3-state)
[213.126854] Starting subtest: basic-pci-d3-state
[213.739312] Subtest basic-pci-d3-state: SUCCESS (0.607s)
[213.971307] [124/162] (797s left) kms_pm_rpm (basic-rte)
[214.039733] Starting subtest: basic-rte
[216.343275] Subtest basic-rte: SUCCESS (2.297s)
[217.763436] [125/162] (793s left) kms_psr (psr-primary-page-flip)
[217.957441] Starting subtest: psr-primary-page-flip
[217.960886] Starting dynamic subtest: eDP-1
[219.987824] Dynamic subtest eDP-1: SUCCESS (2.026s)
[220.000226] Subtest psr-primary-page-flip: SUCCESS (2.034s)
[220.933788] [126/162] (790s left) kms_psr (psr-cursor-plane-move)
[221.060745] Starting subtest: psr-cursor-plane-move
[221.061781] Starting dynamic subtest: eDP-1
[223.091329] Dynamic subtest eDP-1: SUCCESS (2.027s)
[223.103610] Subtest psr-cursor-plane-move: SUCCESS (2.034s)
[224.059751] [127/162] (787s left) kms_psr (psr-sprite-plane-onoff)
[224.186021] Starting subtest: psr-sprite-plane-onoff
[224.188920] Starting dynamic subtest: eDP-1
[226.259380] Dynamic subtest eDP-1: SUCCESS (2.070s)
[226.270348] Subtest psr-sprite-plane-onoff: SUCCESS (2.078s)
[227.278533] [128/162] (784s left) kms_psr (psr-primary-mmap-gtt)
[227.399855] Starting subtest: psr-primary-mmap-gtt
[227.403324] Starting dynamic subtest: eDP-1
[229.223231] Dynamic subtest eDP-1: SUCCESS (1.820s)
[229.235193] Subtest psr-primary-mmap-gtt: SUCCESS (1.827s)
[230.205899] [129/162] (781s left) kms_setmode (basic-clone-single-crtc)
[230.277934] Starting subtest: basic-clone-single-crtc
[230.280835] Subtest basic-clone-single-crtc: SKIP (0.000s)
[230.477642] [130/162] (781s left) i915_pm_rps (basic-api)
[230.558380] Starting subtest: basic-api
[230.564691] Subtest basic-api: SUCCESS (0.006s)
[230.769146] [131/162] (781s left) prime_self_import (basic-llseek-bad)
[230.835885] Starting subtest: basic-llseek-bad
[230.840572] Subtest basic-llseek-bad: SUCCESS (0.003s)
[231.033404] [132/162] (781s left) prime_self_import (basic-llseek-size)
[231.100381] Starting subtest: basic-llseek-size
[231.105238] Subtest basic-llseek-size: SUCCESS (0.005s)
[231.316424] [133/162] (781s left) prime_self_import (basic-with_fd_dup)
[231.383344] Starting subtest: basic-with_fd_dup
[231.388216] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[231.597023] [134/162] (781s left) prime_self_import (basic-with_one_bo)
[231.662055] Starting subtest: basic-with_one_bo
[231.668244] Subtest basic-with_one_bo: SUCCESS (0.006s)
[231.855197] [135/162] (780s left) prime_self_import (basic-with_one_bo_two_files)
[231.922196] Starting subtest: basic-with_one_bo_two_files
[231.927089] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[232.132481] [136/162] (780s left) prime_self_import (basic-with_two_bos)
[232.198234] Starting subtest: basic-with_two_bos
[232.203957] Subtest basic-with_two_bos: SUCCESS (0.004s)
[232.415330] [137/162] (780s left) prime_vgem (basic-fence-flip)
[232.506542] Starting subtest: basic-fence-flip
[232.988706] Subtest basic-fence-flip: SUCCESS (0.479s)
[233.235610] [138/162] (779s left) prime_vgem (basic-fence-mmap)
[233.340545] Starting subtest: basic-fence-mmap
[233.375111] Subtest basic-fence-mmap: SUCCESS (0.034s)
[233.599208] [139/162] (779s left) prime_vgem (basic-fence-read)
[233.694947] Starting subtest: basic-fence-read
[233.775551] Subtest basic-fence-read: SUCCESS (0.080s)
[233.963463] [140/162] (779s left) prime_vgem (basic-gtt)
[234.056492] Starting subtest: basic-gtt
[234.072453] Subtest basic-gtt: SUCCESS (0.016s)
[234.284925] [141/162] (778s left) prime_vgem (basic-read)
[234.364668] Starting subtest: basic-read
[234.403211] Subtest basic-read: SUCCESS (0.038s)
[234.600902] [142/162] (778s left) prime_vgem (basic-write)
[234.680777] Starting subtest: basic-write
[234.721292] Subtest basic-write: SUCCESS (0.041s)
[234.918415] [143/162] (778s left) vgem_basic (setversion)
[234.988948] Starting subtest: setversion
[234.991137] Subtest setversion: SUCCESS (0.000s)
[235.169126] [144/162] (778s left) vgem_basic (create)
[235.256890] Starting subtest: create
[235.257914] Subtest create: SUCCESS (0.000s)
[235.448058] [145/162] (778s left) vgem_basic (debugfs)
[235.523121] Starting subtest: debugfs
[235.524153] Subtest debugfs: SUCCESS (0.001s)
[235.685853] [146/162] (778s left) vgem_basic (dmabuf-export)
[235.773552] Starting subtest: dmabuf-export
[235.790510] Subtest dmabuf-export: SUCCESS (0.018s)
[235.995212] [147/162] (777s left) vgem_basic (dmabuf-fence)
[236.073241] Starting subtest: dmabuf-fence
[236.074225] Subtest dmabuf-fence: SUCCESS (0.001s)
[236.240304] [148/162] (777s left) vgem_basic (dmabuf-fence-before)
[236.324192] Starting subtest: dmabuf-fence-before
[236.325174] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[236.496132] [149/162] (777s left) vgem_basic (dmabuf-mmap)
[236.574062] Starting subtest: dmabuf-mmap
[236.586202] Subtest dmabuf-mmap: SUCCESS (0.014s)
[236.757759] [150/162] (777s left) vgem_basic (mmap)
[236.840600] Starting subtest: mmap
[236.850480] Subtest mmap: SUCCESS (0.011s)
[237.027071] [151/162] (777s left) vgem_basic (second-client)
[237.107876] Starting subtest: second-client
[237.138203] Subtest second-client: SUCCESS (0.032s)
[237.311486] [152/162] (776s left) vgem_basic (sysfs)
[237.390953] Starting subtest: sysfs
[237.392099] Subtest sysfs: SUCCESS (0.001s)
[237.563403] [153/162] (776s left) core_hotunplug (unbind-rebind)
[237.706781] Starting subtest: unbind-rebind
[242.847207] Subtest unbind-rebind: SUCCESS (5.141s)
[242.929677] [154/162] (771s left) vgem_basic (unload)
[243.003912] Starting subtest: unload
[272.122336] Subtest unload: SUCCESS (29.083s)
[272.343408] [155/162] (742s left) i915_module_load (reload)
[272.414297] Starting subtest: reload
[276.467316] Subtest reload: SUCCESS (4.058s)
[276.560860] [156/162] (738s left) gem_lmem_swapping (basic)
[276.647316] Subtest basic: SKIP (0.000s)
[276.731467] [157/162] (737s left) gem_lmem_swapping (parallel-random-engines)
[276.807386] Subtest parallel-random-engines: SKIP (0.000s)
[276.897084] [158/162] (737s left) gem_lmem_swapping (random-engines)
[276.972970] Subtest random-engines: SKIP (0.000s)
[277.063866] [159/162] (737s left) gem_lmem_swapping (verify-random)
[277.138800] Subtest verify-random: SKIP (0.000s)
[277.231753] [160/162] (737s left) i915_pm_rpm (module-reload)
[279.608696] Starting subtest: module-reload
[292.373029] Subtest module-reload: SUCCESS (12.766s)
[292.424606] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[292.426723] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[292.430176] [161/162] (722s left) i915_selftest (live)
[292.502842] Starting subtest: live
[292.506107] Starting dynamic subtest: sanitycheck
[295.164789] Dynamic subtest sanitycheck: SUCCESS (2.652s)
[295.184398] Starting dynamic subtest: uncore
[297.757618] Dynamic subtest uncore: SUCCESS (2.590s)
[297.775995] Starting dynamic subtest: workarounds
[300.590629] Dynamic subtest workarounds: SUCCESS (2.831s)
[300.603189] Starting dynamic subtest: gt_engines
[303.587714] Dynamic subtest gt_engines: SUCCESS (2.995s)
[303.600653] Starting dynamic subtest: gt_timelines
[315.984030] Dynamic subtest gt_timelines: SUCCESS (12.394s)
[315.997836] Starting dynamic subtest: gt_contexts
[318.727995] Dynamic subtest gt_contexts: SUCCESS (2.750s)