[52.981154] Initializing watchdogs
[52.981297]   /dev/watchdog0
[53.014777] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:1916 Intel Skylake (Gen9) Skylake GT2 [HD Graphics 520]
[53.023666] [001/162] (960s left) i915_module_load (load)
[53.315486] Starting subtest: load
[55.445378] Subtest load: SUCCESS (2.061s)
[55.505687] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.510220] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[55.514458] [002/162] (958s left) core_auth (basic-auth)
[55.621950] Starting subtest: basic-auth
[55.623148] Subtest basic-auth: SUCCESS (0.001s)
[55.754237] [003/162] (957s left) core_debugfs (read-all-entries)
[55.834767] Starting subtest: read-all-entries
[55.979564] Subtest read-all-entries: SUCCESS (0.141s)
[56.117950] [004/162] (957s left) core_sysfs (read-all-entries)
[56.195604] Starting subtest: read-all-entries
[56.202199] Subtest read-all-entries: SUCCESS (0.007s)
[56.322051] [005/162] (957s left) fbdev (eof)
[56.406498] Starting subtest: eof
[56.407613] Subtest eof: SUCCESS (0.001s)
[56.544080] [006/162] (957s left) fbdev (info)
[56.612478] Starting subtest: info
[56.613703] Subtest info: SUCCESS (0.000s)
[56.727994] [007/162] (957s left) fbdev (nullptr)
[56.813164] Starting subtest: nullptr
[56.814415] Subtest nullptr: SUCCESS (0.000s)
[56.918746] [008/162] (957s left) fbdev (read)
[57.000807] Starting subtest: read
[57.935034] Subtest read: SUCCESS (0.905s)
[58.062762] [009/162] (956s left) fbdev (write)
[58.142544] Starting subtest: write
[58.647201] Subtest write: SUCCESS (0.486s)
[58.774747] [010/162] (955s left) gem_basic (bad-close)
[58.848822] Starting subtest: bad-close
[58.851115] Subtest bad-close: SUCCESS (0.000s)
[58.991844] [011/162] (955s left) gem_basic (create-close)
[59.066475] Starting subtest: create-close
[59.069346] Subtest create-close: SUCCESS (0.000s)
[59.263864] [012/162] (955s left) gem_basic (create-fd-close)
[59.340461] Starting subtest: create-fd-close
[59.342576] Subtest create-fd-close: SUCCESS (0.000s)
[59.474358] [013/162] (954s left) gem_busy (busy@all-engines)
[59.582756] Starting subtest: busy
[59.583907] Starting dynamic subtest: all-engines
[59.643461] Dynamic subtest all-engines: SUCCESS (0.060s)
[59.644516] Subtest busy: SUCCESS (0.060s)
[60.137588] [014/162] (954s left) gem_close_race (basic-process)
[60.243736] Starting subtest: basic-process
[60.318645] Subtest basic-process: SUCCESS (0.074s)
[60.610975] [015/162] (953s left) gem_close_race (basic-threads)
[60.778669] Starting subtest: basic-threads
[62.046235] Subtest basic-threads: SUCCESS (1.254s)
[62.342268] [016/162] (952s left) gem_ctx_create (basic)
[62.434377] Starting subtest: basic
[62.435433] Subtest basic: SUCCESS (0.000s)
[62.711281] [017/162] (952s left) gem_ctx_create (basic-files)
[62.810941] Starting subtest: basic-files
[64.830445] Subtest basic-files: SUCCESS (2.001s)
[65.177960] [018/162] (949s left) gem_ctx_exec (basic)
[65.257384] Starting subtest: basic
[65.262076] Subtest basic: SUCCESS (0.006s)
[65.437496] [019/162] (949s left) gem_exec_basic (basic)
[65.523796] Starting subtest: basic
[65.524945] Starting dynamic subtest: rcs0-smem
[65.527393] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[65.544863] Starting dynamic subtest: bcs0-smem
[65.546173] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[65.560210] Starting dynamic subtest: vcs0-smem
[65.561428] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[65.578717] Starting dynamic subtest: vecs0-smem
[65.580064] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[65.581461] Subtest basic: SUCCESS (0.011s)
[65.850703] [020/162] (949s left) gem_exec_create (basic)
[65.941832] Starting subtest: basic
[65.942940] Starting dynamic subtest: smem
[68.207368] Dynamic subtest smem: SUCCESS (2.243s)
[68.208597] Subtest basic: SUCCESS (2.243s)
[68.696350] [021/162] (946s left) gem_exec_fence (basic-busy)
[68.993359] Starting subtest: basic-busy
[68.994997] Starting dynamic subtest: rcs0
[69.117347] Dynamic subtest rcs0: SUCCESS (0.123s)
[69.132260] Starting dynamic subtest: bcs0
[69.176567] Dynamic subtest bcs0: SUCCESS (0.058s)
[69.192121] Starting dynamic subtest: vcs0
[69.306441] Dynamic subtest vcs0: SUCCESS (0.129s)
[69.325989] Starting dynamic subtest: vecs0
[69.338269] Dynamic subtest vecs0: SUCCESS (0.031s)
[69.339536] Subtest basic-busy: SUCCESS (0.344s)
[70.147967] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[70.153197] [022/162] (945s left) gem_exec_fence (basic-wait)
[70.407097] Starting subtest: basic-wait
[70.408534] Starting dynamic subtest: rcs0
[70.434396] Dynamic subtest rcs0: SUCCESS (0.029s)
[70.451225] Starting dynamic subtest: bcs0
[70.461365] Dynamic subtest bcs0: SUCCESS (0.026s)
[70.476846] Starting dynamic subtest: vcs0
[70.491250] Dynamic subtest vcs0: SUCCESS (0.029s)
[70.509322] Starting dynamic subtest: vecs0
[70.516401] Dynamic subtest vecs0: SUCCESS (0.025s)
[70.517597] Subtest basic-wait: SUCCESS (0.111s)
[71.335701] [023/162] (943s left) gem_exec_fence (basic-await)
[71.584789] Starting subtest: basic-await
[71.586464] Starting dynamic subtest: rcs0
[71.690636] Dynamic subtest rcs0: SUCCESS (0.105s)
[71.708228] Starting dynamic subtest: bcs0
[71.792367] Dynamic subtest bcs0: SUCCESS (0.101s)
[71.809886] Starting dynamic subtest: vcs0
[71.900391] Dynamic subtest vcs0: SUCCESS (0.107s)
[71.922981] Starting dynamic subtest: vecs0
[72.026101] Dynamic subtest vecs0: SUCCESS (0.125s)
[72.027186] Subtest basic-await: SUCCESS (0.440s)
[72.833910] [024/162] (942s left) gem_exec_fence (nb-await)
[73.073998] Starting subtest: nb-await
[73.075720] Starting dynamic subtest: rcs0
[73.146429] Dynamic subtest rcs0: SUCCESS (0.072s)
[73.181685] Starting dynamic subtest: bcs0
[73.230658] Dynamic subtest bcs0: SUCCESS (0.081s)
[73.281514] Starting dynamic subtest: vcs0
[73.325658] Dynamic subtest vcs0: SUCCESS (0.092s)
[73.364625] Starting dynamic subtest: vecs0
[73.410339] Dynamic subtest vecs0: SUCCESS (0.082s)
[73.417620] Subtest nb-await: SUCCESS (0.339s)
[74.227929] [025/162] (941s left) gem_exec_gttfill (basic)
[74.328234] Starting subtest: basic
[80.201811] Subtest basic: SUCCESS (5.864s)
[80.607594] [026/162] (934s left) gem_exec_parallel (engines)
[80.783534] Starting subtest: engines
[80.784620] Starting dynamic subtest: basic
[83.994642] Dynamic subtest basic: SUCCESS (3.206s)
[84.013682] Starting dynamic subtest: contexts
[87.949196] Dynamic subtest contexts: SUCCESS (3.934s)
[87.970756] Starting dynamic subtest: fds
[93.377847] Dynamic subtest fds: SUCCESS (5.425s)
[93.397773] Starting dynamic subtest: userptr
[96.040142] Dynamic subtest userptr: SUCCESS (2.629s)
[96.041202] Subtest engines: SUCCESS (15.197s)
[96.268623] [027/162] (919s left) gem_exec_store (basic)
[96.359463] Starting subtest: basic
[96.412575] Subtest basic: SUCCESS (0.053s)
[96.713088] [028/162] (919s left) gem_flink_basic (bad-flink)
[96.788299] Starting subtest: bad-flink
[96.789330] Subtest bad-flink: SUCCESS (0.000s)
[96.918258] [029/162] (918s left) gem_flink_basic (bad-open)
[96.992767] Starting subtest: bad-open
[96.993882] Subtest bad-open: SUCCESS (0.000s)
[97.118895] [030/162] (918s left) gem_flink_basic (basic)
[97.195146] Starting subtest: basic
[97.196239] Subtest basic: SUCCESS (0.000s)
[97.378981] [031/162] (918s left) gem_flink_basic (double-flink)
[97.449521] Starting subtest: double-flink
[97.450602] Subtest double-flink: SUCCESS (0.000s)
[97.615208] [032/162] (918s left) gem_flink_basic (flink-lifetime)
[97.701152] Starting subtest: flink-lifetime
[97.704813] Subtest flink-lifetime: SUCCESS (0.003s)
[97.921018] [033/162] (918s left) gem_huc_copy (huc-copy)
[98.001574] Starting subtest: huc-copy
[98.003816] Subtest huc-copy: SKIP (0.000s)
[98.168943] [034/162] (918s left) gem_linear_blits (basic)
[98.426201] Starting subtest: basic
[98.437531] Subtest basic: SUCCESS (0.013s)
[98.633127] [035/162] (917s left) gem_mmap (basic)
[98.790669] Starting subtest: basic
[98.796277] Subtest basic: SUCCESS (0.001s)
[98.937572] [036/162] (917s left) gem_mmap_gtt (basic)
[99.012549] Starting subtest: basic
[99.013867] Subtest basic: SUCCESS (0.001s)
[99.157869] [037/162] (917s left) gem_render_linear_blits (basic)
[99.248328] Starting subtest: basic
[99.292345] Subtest basic: SUCCESS (0.045s)
[99.407626] [038/162] (917s left) gem_render_tiled_blits (basic)
[99.491698] Starting subtest: basic
[99.547318] Subtest basic: SUCCESS (0.055s)
[99.658585] [039/162] (917s left) gem_ringfill (basic-all)
[99.958492] Starting subtest: basic-all
[101.127456] Subtest basic-all: SUCCESS (1.158s)
[101.322775] [040/162] (915s left) gem_softpin (allocator-basic)
[101.403627] Starting subtest: allocator-basic
[101.605562] Subtest allocator-basic: SUCCESS (0.193s)
[101.786694] [041/162] (915s left) gem_softpin (allocator-basic-reserve)
[101.869860] Starting subtest: allocator-basic-reserve
[102.077447] Subtest allocator-basic-reserve: SUCCESS (0.198s)
[102.280667] [042/162] (914s left) gem_softpin (safe-alignment)
[102.365490] Starting subtest: safe-alignment
[102.385374] Subtest safe-alignment: SUCCESS (0.020s)
[102.553111] [043/162] (914s left) gem_sync (basic-all)
[102.658256] Starting subtest: basic-all
[105.229913] Subtest basic-all: SUCCESS (2.554s)
[105.654685] [044/162] (911s left) gem_sync (basic-each)
[105.751352] Starting subtest: basic-each
[108.034629] Subtest basic-each: SUCCESS (2.282s)
[108.524021] [045/162] (908s left) gem_tiled_blits (basic)
[108.691666] Starting subtest: basic
[108.717835] Subtest basic: SUCCESS (0.026s)
[108.966430] [046/162] (908s left) gem_tiled_fence_blits (basic)
[109.228804] Starting subtest: basic
[109.242111] Subtest basic: SUCCESS (0.013s)
[109.453277] [047/162] (908s left) gem_tiled_pread_basic (basic)
[109.590687] Starting subtest: basic
[109.762241] Subtest basic: SUCCESS (0.163s)
[109.956304] [048/162] (907s left) gem_wait (busy@all-engines)
[110.054831] Starting subtest: busy
[110.056169] Starting dynamic subtest: all-engines
[110.597351] Dynamic subtest all-engines: SUCCESS (0.542s)
[110.598370] Subtest busy: SUCCESS (0.543s)
[110.921369] [049/162] (906s left) gem_wait (wait@all-engines)
[111.017982] Starting subtest: wait
[111.019506] Starting dynamic subtest: all-engines
[112.083971] Dynamic subtest all-engines: SUCCESS (1.063s)
[112.087242] Subtest wait: SUCCESS (1.065s)
[112.456943] [050/162] (905s left) i915_getparams_basic (basic-eu-total)
[112.530414] Starting subtest: basic-eu-total
[112.532543] Subtest basic-eu-total: SUCCESS (0.000s)
[112.659969] [051/162] (905s left) i915_getparams_basic (basic-subslice-total)
[112.735493] Starting subtest: basic-subslice-total
[112.737448] Subtest basic-subslice-total: SUCCESS (0.000s)
[112.860175] [052/162] (905s left) i915_hangman (error-state-basic)
[112.950316] Starting subtest: error-state-basic
[112.960137] Subtest error-state-basic: SUCCESS (0.010s)
[113.104239] [053/162] (904s left) i915_pciid
[113.302366] [054/162] (904s left) intel_hwmon (hwmon-read)
[113.374507] Subtest hwmon-read: SKIP (0.000s)
[113.464371] [055/162] (904s left) intel_hwmon (hwmon-write)
[113.541405] Subtest hwmon-write: SKIP (0.000s)
[113.633403] [056/162] (904s left) kms_addfb_basic (addfb25-4-tiled)
[113.779343] Starting subtest: addfb25-4-tiled
[113.780418] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[113.937800] [057/162] (904s left) kms_addfb_basic (addfb25-bad-modifier)
[114.064414] Starting subtest: addfb25-bad-modifier
[114.065435] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[114.253185] [058/162] (904s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[114.387222] Starting subtest: addfb25-framebuffer-vs-set-tiling
[114.388232] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[114.533259] [059/162] (904s left) kms_addfb_basic (addfb25-modifier-no-flag)
[114.661519] Starting subtest: addfb25-modifier-no-flag
[114.662504] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[114.851706] [060/162] (903s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[114.980315] Starting subtest: addfb25-x-tiled-legacy
[114.981440] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[115.119206] [061/162] (903s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[115.247403] Starting subtest: addfb25-x-tiled-mismatch-legacy
[115.248525] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[115.398749] [062/162] (903s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[115.535251] Starting subtest: addfb25-yf-tiled-legacy
[115.536218] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[115.711674] [063/162] (903s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[115.838562] Starting subtest: addfb25-y-tiled-legacy
[115.839634] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[116.066917] [064/162] (902s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[116.201756] Starting subtest: addfb25-y-tiled-small-legacy
[116.202859] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[116.365886] [065/162] (902s left) kms_addfb_basic (bad-pitch-0)
[116.440208] Starting subtest: bad-pitch-0
[116.441279] Subtest bad-pitch-0: SUCCESS (0.000s)
[116.634472] [066/162] (902s left) kms_addfb_basic (bad-pitch-1024)
[116.713574] Starting subtest: bad-pitch-1024
[116.714938] Subtest bad-pitch-1024: SUCCESS (0.000s)
[116.916826] [067/162] (902s left) kms_addfb_basic (bad-pitch-128)
[116.990821] Starting subtest: bad-pitch-128
[116.991951] Subtest bad-pitch-128: SUCCESS (0.000s)
[117.213653] [068/162] (902s left) kms_addfb_basic (bad-pitch-256)
[117.285619] Starting subtest: bad-pitch-256
[117.286762] Subtest bad-pitch-256: SUCCESS (0.000s)
[117.501485] [069/162] (901s left) kms_addfb_basic (bad-pitch-32)
[117.576649] Starting subtest: bad-pitch-32
[117.577689] Subtest bad-pitch-32: SUCCESS (0.000s)
[117.810921] [070/162] (901s left) kms_addfb_basic (bad-pitch-63)
[117.884473] Starting subtest: bad-pitch-63
[117.885470] Subtest bad-pitch-63: SUCCESS (0.000s)
[118.106334] [071/162] (901s left) kms_addfb_basic (bad-pitch-65536)
[118.178535] Starting subtest: bad-pitch-65536
[118.179789] Subtest bad-pitch-65536: SUCCESS (0.000s)
[118.390023] [072/162] (901s left) kms_addfb_basic (bad-pitch-999)
[118.462681] Starting subtest: bad-pitch-999
[118.463977] Subtest bad-pitch-999: SUCCESS (0.000s)
[118.702256] [073/162] (901s left) kms_addfb_basic (basic)
[118.774920] Starting subtest: basic
[118.776196] Subtest basic: SUCCESS (0.000s)
[119.009606] [074/162] (900s left) kms_addfb_basic (basic-x-tiled-legacy)
[119.084399] Starting subtest: basic-x-tiled-legacy
[119.085442] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[119.322852] [075/162] (900s left) kms_addfb_basic (basic-y-tiled-legacy)
[119.396953] Starting subtest: basic-y-tiled-legacy
[119.398222] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[119.644728] [076/162] (900s left) kms_addfb_basic (bo-too-small)
[119.777573] Starting subtest: bo-too-small
[119.778582] Subtest bo-too-small: SUCCESS (0.001s)
[119.912871] [077/162] (900s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[120.035949] Starting subtest: bo-too-small-due-to-tiling
[120.037179] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[120.238605] [078/162] (899s left) kms_addfb_basic (clobberred-modifier)
[120.314142] Starting subtest: clobberred-modifier
[120.315236] Subtest clobberred-modifier: SUCCESS (0.000s)
[120.561466] [079/162] (899s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[120.634922] Starting subtest: framebuffer-vs-set-tiling
[120.635952] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[120.850307] [080/162] (899s left) kms_addfb_basic (invalid-get-prop)
[120.983983] Starting subtest: invalid-get-prop
[120.985083] Subtest invalid-get-prop: SUCCESS (0.000s)
[121.302610] [081/162] (899s left) kms_addfb_basic (invalid-get-prop-any)
[121.374279] Starting subtest: invalid-get-prop-any
[121.375408] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[121.608912] [082/162] (898s left) kms_addfb_basic (invalid-set-prop)
[121.681870] Starting subtest: invalid-set-prop
[121.682845] Subtest invalid-set-prop: SUCCESS (0.000s)
[121.894029] [083/162] (898s left) kms_addfb_basic (invalid-set-prop-any)
[121.967442] Starting subtest: invalid-set-prop-any
[121.968466] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[122.210922] [084/162] (898s left) kms_addfb_basic (no-handle)
[122.282356] Starting subtest: no-handle
[122.283527] Subtest no-handle: SUCCESS (0.000s)
[122.494114] [085/162] (898s left) kms_addfb_basic (size-max)
[122.620349] Starting subtest: size-max
[122.621376] Subtest size-max: SUCCESS (0.000s)
[122.800871] [086/162] (898s left) kms_addfb_basic (small-bo)
[122.924414] Starting subtest: small-bo
[122.925512] Subtest small-bo: SUCCESS (0.000s)
[123.109672] [087/162] (897s left) kms_addfb_basic (tile-pitch-mismatch)
[123.183024] Starting subtest: tile-pitch-mismatch
[123.184161] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[123.425704] [088/162] (897s left) kms_addfb_basic (too-high)
[123.558400] Starting subtest: too-high
[123.559527] Subtest too-high: SUCCESS (0.001s)
[123.698115] [089/162] (897s left) kms_addfb_basic (too-wide)
[123.828458] Starting subtest: too-wide
[123.829469] Subtest too-wide: SUCCESS (0.000s)
[124.037270] [090/162] (897s left) kms_addfb_basic (unused-handle)
[124.108722] Starting subtest: unused-handle
[124.109892] Subtest unused-handle: SUCCESS (0.000s)
[124.325059] [091/162] (897s left) kms_addfb_basic (unused-modifier)
[124.396995] Starting subtest: unused-modifier
[124.398246] Subtest unused-modifier: SUCCESS (0.000s)
[124.645502] [092/162] (896s left) kms_addfb_basic (unused-offsets)
[124.717256] Starting subtest: unused-offsets
[124.718346] Subtest unused-offsets: SUCCESS (0.000s)
[124.906500] [093/162] (896s left) kms_addfb_basic (unused-pitches)
[124.977231] Starting subtest: unused-pitches
[124.978368] Subtest unused-pitches: SUCCESS (0.000s)
[125.219219] [094/162] (896s left) kms_busy (basic)
[125.350470] Starting subtest: basic
[125.351481] Starting dynamic subtest: flip
[126.255395] Dynamic subtest flip: SUCCESS (0.896s)
[126.283453] Starting dynamic subtest: modeset
[130.118169] Dynamic subtest modeset: SUCCESS (3.865s)
[130.121886] Subtest basic: SUCCESS (4.766s)
[131.041489] [095/162] (890s left) kms_prop_blob (basic)
[131.114042] Starting subtest: basic
[131.115373] Subtest basic: SUCCESS (0.000s)
[131.292488] [096/162] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[131.420106] Starting subtest: basic-busy-flip-before-cursor-atomic
[131.422138] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[131.557335] [097/162] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[131.680323] Starting subtest: basic-busy-flip-before-cursor-legacy
[131.682422] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[131.816985] [098/162] (890s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[131.945258] Starting subtest: basic-flip-after-cursor-atomic
[133.267106] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.320s)
[134.259797] [099/162] (887s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[134.391511] Starting subtest: basic-flip-after-cursor-legacy
[135.670992] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.276s)
[136.603100] [100/162] (885s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[136.726262] Starting subtest: basic-flip-after-cursor-varying-size
[138.039586] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.308s)
[138.928708] [101/162] (883s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[139.053395] Starting subtest: basic-flip-before-cursor-atomic
[140.360667] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.306s)
[141.280086] [102/162] (881s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[141.411028] Starting subtest: basic-flip-before-cursor-legacy
[142.691503] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.277s)
[143.590555] [103/162] (878s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[143.722238] Starting subtest: basic-flip-before-cursor-varying-size
[144.999696] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.273s)
[145.921341] [104/162] (876s left) kms_dsc (dsc-basic)
[146.050341] Subtest dsc-basic: SKIP (0.000s)
[146.142165] [105/162] (876s left) kms_flip (basic-flip-vs-dpms)
[146.277167] Starting subtest: basic-flip-vs-dpms
[146.281147] Starting dynamic subtest: A-eDP1
[148.945265] Dynamic subtest A-eDP1: SUCCESS (2.662s)
[148.999907] Starting dynamic subtest: B-eDP1
[151.196280] Dynamic subtest B-eDP1: SUCCESS (2.245s)
[151.229894] Starting dynamic subtest: C-eDP1
[153.458802] Dynamic subtest C-eDP1: SUCCESS (2.260s)
[153.462243] Subtest basic-flip-vs-dpms: SUCCESS (7.187s)
[154.353504] [106/162] (868s left) kms_flip (basic-flip-vs-modeset)
[154.484233] Starting subtest: basic-flip-vs-modeset
[154.488486] Starting dynamic subtest: A-eDP1
[157.260390] Dynamic subtest A-eDP1: SUCCESS (2.765s)
[157.313164] Starting dynamic subtest: B-eDP1
[159.595349] Dynamic subtest B-eDP1: SUCCESS (2.326s)
[159.672410] Starting dynamic subtest: C-eDP1
[161.926656] Dynamic subtest C-eDP1: SUCCESS (2.322s)
[161.937742] Subtest basic-flip-vs-modeset: SUCCESS (7.453s)
[162.913380] [107/162] (859s left) kms_flip (basic-flip-vs-wf_vblank)
[163.042533] Starting subtest: basic-flip-vs-wf_vblank
[163.045171] Starting dynamic subtest: A-eDP1
[165.242984] Dynamic subtest A-eDP1: SUCCESS (2.198s)
[165.264119] Starting dynamic subtest: B-eDP1
[167.454190] Dynamic subtest B-eDP1: SUCCESS (2.210s)
[167.476056] Starting dynamic subtest: C-eDP1
[169.653243] Dynamic subtest C-eDP1: SUCCESS (2.197s)
[169.657510] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.617s)
[171.006148] [108/162] (851s left) kms_flip (basic-plain-flip)
[171.133533] Starting subtest: basic-plain-flip
[171.135744] Starting dynamic subtest: A-eDP1
[173.094049] Dynamic subtest A-eDP1: SUCCESS (1.949s)
[173.125688] Starting dynamic subtest: B-eDP1
[175.012592] Dynamic subtest B-eDP1: SUCCESS (1.919s)
[175.036116] Starting dynamic subtest: C-eDP1
[176.903605] Dynamic subtest C-eDP1: SUCCESS (1.881s)
[176.908945] Subtest basic-plain-flip: SUCCESS (5.776s)
[178.225298] [109/162] (844s left) kms_force_connector_basic (force-connector-state)
[178.336969] Starting subtest: force-connector-state
[179.015147] Subtest force-connector-state: SUCCESS (0.677s)
[180.348352] [110/162] (842s left) kms_force_connector_basic (force-edid)
[180.451828] Starting subtest: force-edid
[180.495212] Subtest force-edid: SUCCESS (0.044s)
[180.718627] [111/162] (842s left) kms_force_connector_basic (force-load-detect)
[180.835819] Starting subtest: force-load-detect
[180.840863] Subtest force-load-detect: SKIP (0.000s)
[181.123626] [112/162] (842s left) kms_force_connector_basic (prune-stale-modes)
[181.232774] Starting subtest: prune-stale-modes
[181.276176] Subtest prune-stale-modes: SUCCESS (0.042s)
[181.514547] [113/162] (841s left) kms_frontbuffer_tracking (basic)
[181.651776] Starting subtest: basic
[185.394889] Subtest basic: SUCCESS (3.744s)
[185.656895] [114/162] (837s left) kms_hdmi_inject (inject-audio)
[186.044405] Starting subtest: inject-audio
[186.193938] Subtest inject-audio: SUCCESS (0.152s)
[187.056653] [115/162] (836s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[187.185229] Starting subtest: compare-crc-sanitycheck-xr24
[187.186368] Starting dynamic subtest: pipe-A-eDP-1
[187.982130] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.790s)
[188.048649] Starting dynamic subtest: pipe-B-eDP-1
[189.516857] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.511s)
[189.575772] Starting dynamic subtest: pipe-C-eDP-1
[190.926637] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.392s)
[190.931172] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.740s)
[191.802193] [116/162] (831s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[191.927987] Starting subtest: compare-crc-sanitycheck-nv12
[191.929188] Starting dynamic subtest: pipe-A-eDP-1
[191.931189] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[191.952847] Starting dynamic subtest: pipe-B-eDP-1
[191.955544] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[191.973659] Starting dynamic subtest: pipe-C-eDP-1
[191.976252] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[191.978535] Subtest compare-crc-sanitycheck-nv12: SKIP (0.010s)
[192.075932] [117/162] (831s left) kms_pipe_crc_basic (hang-read-crc)
[192.210434] Starting subtest: hang-read-crc
[192.211462] Starting dynamic subtest: pipe-A-eDP-1
[194.919564] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.700s)
[195.002952] Starting dynamic subtest: pipe-B-eDP-1
[198.249611] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.319s)
[198.340452] Starting dynamic subtest: pipe-C-eDP-1
[201.551485] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.278s)
[201.562339] Subtest hang-read-crc: SUCCESS (9.344s)
[202.466361] [118/162] (821s left) kms_pipe_crc_basic (nonblocking-crc)
[202.596637] Starting subtest: nonblocking-crc
[202.597695] Starting dynamic subtest: pipe-A-eDP-1
[203.672209] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.071s)
[203.769774] Starting dynamic subtest: pipe-B-eDP-1
[205.325793] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.633s)
[205.409141] Starting dynamic subtest: pipe-C-eDP-1
[207.121650] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.775s)
[207.124760] Subtest nonblocking-crc: SUCCESS (4.523s)
[208.059190] [119/162] (815s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[208.192044] Starting subtest: nonblocking-crc-frame-sequence
[208.193398] Starting dynamic subtest: pipe-A-eDP-1
[209.259565] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.064s)
[209.324989] Starting dynamic subtest: pipe-B-eDP-1
[211.013310] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.734s)
[211.036976] Starting dynamic subtest: pipe-C-eDP-1
[212.634969] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.622s)
[212.645367] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.447s)
[213.580484] [120/162] (810s left) kms_pipe_crc_basic (read-crc)
[213.711178] Starting subtest: read-crc
[213.716554] Starting dynamic subtest: pipe-A-eDP-1
[214.747692] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.027s)
[214.835194] Starting dynamic subtest: pipe-B-eDP-1
[216.297856] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.530s)
[216.379045] Starting dynamic subtest: pipe-C-eDP-1
[217.922900] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.607s)
[217.938487] Subtest read-crc: SUCCESS (4.215s)
[218.868394] [121/162] (805s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[218.995717] Starting subtest: read-crc-frame-sequence
[218.997274] Starting dynamic subtest: pipe-A-eDP-1
[219.994628] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.994s)
[220.080764] Starting dynamic subtest: pipe-B-eDP-1
[221.695401] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.673s)
[221.763925] Starting dynamic subtest: pipe-C-eDP-1
[223.303238] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.599s)
[223.306220] Subtest read-crc-frame-sequence: SUCCESS (4.310s)
[224.252847] [122/162] (799s left) kms_pm_backlight (basic-brightness)
[224.386990] Starting subtest: basic-brightness
[224.438288] Starting dynamic subtest: eDP-1
[225.064412] Dynamic subtest eDP-1: SUCCESS (0.626s)
[225.078179] Subtest basic-brightness: SUCCESS (0.681s)
[226.099938] [123/162] (798s left) kms_pm_rpm (basic-pci-d3-state)
[227.855074] Starting subtest: basic-pci-d3-state
[228.504084] Subtest basic-pci-d3-state: SUCCESS (0.653s)
[228.767210] [124/162] (795s left) kms_pm_rpm (basic-rte)
[228.837297] Starting subtest: basic-rte
[231.095228] Subtest basic-rte: SUCCESS (2.256s)
[232.438996] [125/162] (792s left) kms_psr (psr-primary-page-flip)
[232.572995] Starting subtest: psr-primary-page-flip
[232.576023] Starting dynamic subtest: eDP-1
[234.648897] Dynamic subtest eDP-1: SUCCESS (2.071s)
[234.651032] Subtest psr-primary-page-flip: SUCCESS (2.077s)
[235.641555] [126/162] (789s left) kms_psr (psr-cursor-plane-move)
[235.770884] Starting subtest: psr-cursor-plane-move
[235.771986] Starting dynamic subtest: eDP-1
[237.750221] Dynamic subtest eDP-1: SUCCESS (1.977s)
[237.756613] Subtest psr-cursor-plane-move: SUCCESS (1.982s)
[238.699138] [127/162] (786s left) kms_psr (psr-sprite-plane-onoff)
[238.831989] Starting subtest: psr-sprite-plane-onoff
[238.833150] Starting dynamic subtest: eDP-1
[240.911705] Dynamic subtest eDP-1: SUCCESS (2.076s)
[240.913840] Subtest psr-sprite-plane-onoff: SUCCESS (2.080s)
[241.783503] [128/162] (783s left) kms_psr (psr-primary-mmap-gtt)
[241.918581] Starting subtest: psr-primary-mmap-gtt
[241.919623] Starting dynamic subtest: eDP-1
[243.966467] Dynamic subtest eDP-1: SUCCESS (2.045s)
[243.967455] Subtest psr-primary-mmap-gtt: SUCCESS (2.048s)
[244.891472] [129/162] (780s left) kms_setmode (basic-clone-single-crtc)
[244.967166] Starting subtest: basic-clone-single-crtc
[244.970247] Subtest basic-clone-single-crtc: SKIP (0.000s)
[245.143302] [130/162] (779s left) i915_pm_rps (basic-api)
[245.236757] Starting subtest: basic-api
[245.243064] Subtest basic-api: SUCCESS (0.007s)
[245.373389] [131/162] (779s left) prime_self_import (basic-llseek-bad)
[245.444079] Starting subtest: basic-llseek-bad
[245.449109] Subtest basic-llseek-bad: SUCCESS (0.003s)
[245.638263] [132/162] (779s left) prime_self_import (basic-llseek-size)
[245.708361] Starting subtest: basic-llseek-size
[245.714540] Subtest basic-llseek-size: SUCCESS (0.005s)
[245.870891] [133/162] (779s left) prime_self_import (basic-with_fd_dup)
[245.942327] Starting subtest: basic-with_fd_dup
[245.946373] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[246.065903] [134/162] (779s left) prime_self_import (basic-with_one_bo)
[246.135543] Starting subtest: basic-with_one_bo
[246.141115] Subtest basic-with_one_bo: SUCCESS (0.006s)
[246.364333] [135/162] (779s left) prime_self_import (basic-with_one_bo_two_files)
[246.438181] Starting subtest: basic-with_one_bo_two_files
[246.440202] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[246.572968] [136/162] (778s left) prime_self_import (basic-with_two_bos)
[246.644520] Starting subtest: basic-with_two_bos
[246.651208] Subtest basic-with_two_bos: SUCCESS (0.007s)
[246.841993] [137/162] (778s left) prime_vgem (basic-fence-flip)
[246.959240] Starting subtest: basic-fence-flip
[247.474965] Subtest basic-fence-flip: SUCCESS (0.511s)
[247.618972] [138/162] (778s left) prime_vgem (basic-fence-mmap)
[247.718998] Starting subtest: basic-fence-mmap
[247.757574] Subtest basic-fence-mmap: SUCCESS (0.041s)
[247.913349] [139/162] (777s left) prime_vgem (basic-fence-read)
[248.030496] Starting subtest: basic-fence-read
[248.117416] Subtest basic-fence-read: SUCCESS (0.087s)
[248.296911] [140/162] (777s left) prime_vgem (basic-gtt)
[248.381655] Starting subtest: basic-gtt
[248.404595] Subtest basic-gtt: SUCCESS (0.023s)
[248.537456] [141/162] (777s left) prime_vgem (basic-read)
[248.642467] Starting subtest: basic-read
[248.687484] Subtest basic-read: SUCCESS (0.045s)
[248.845069] [142/162] (777s left) prime_vgem (basic-write)
[248.941487] Starting subtest: basic-write
[248.983897] Subtest basic-write: SUCCESS (0.042s)
[249.118174] [143/162] (777s left) vgem_basic (setversion)
[249.208308] Starting subtest: setversion
[249.210677] Subtest setversion: SUCCESS (0.000s)
[249.317915] [144/162] (776s left) vgem_basic (create)
[249.408093] Starting subtest: create
[249.409177] Subtest create: SUCCESS (0.001s)
[249.517552] [145/162] (776s left) vgem_basic (debugfs)
[249.608303] Starting subtest: debugfs
[249.609402] Subtest debugfs: SUCCESS (0.001s)
[249.721414] [146/162] (776s left) vgem_basic (dmabuf-export)
[249.808865] Starting subtest: dmabuf-export
[249.820985] Subtest dmabuf-export: SUCCESS (0.012s)
[249.970250] [147/162] (776s left) vgem_basic (dmabuf-fence)
[250.058378] Starting subtest: dmabuf-fence
[250.059417] Subtest dmabuf-fence: SUCCESS (0.001s)
[250.170670] [148/162] (776s left) vgem_basic (dmabuf-fence-before)
[250.259259] Starting subtest: dmabuf-fence-before
[250.260311] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[250.369516] [149/162] (776s left) vgem_basic (dmabuf-mmap)
[250.459057] Starting subtest: dmabuf-mmap
[250.477371] Subtest dmabuf-mmap: SUCCESS (0.017s)
[250.585875] [150/162] (776s left) vgem_basic (mmap)
[250.675323] Starting subtest: mmap
[250.686361] Subtest mmap: SUCCESS (0.012s)
[250.803189] [151/162] (776s left) vgem_basic (second-client)
[250.893045] Starting subtest: second-client
[250.926235] Subtest second-client: SUCCESS (0.032s)
[251.048473] [152/162] (775s left) vgem_basic (sysfs)
[251.127817] Starting subtest: sysfs
[251.129123] Subtest sysfs: SUCCESS (0.001s)
[251.238662] [153/162] (775s left) core_hotunplug (unbind-rebind)
[251.401791] Starting subtest: unbind-rebind
[256.613179] Subtest unbind-rebind: SUCCESS (5.210s)
[256.701332] [154/162] (770s left) vgem_basic (unload)
[256.780195] Starting subtest: unload
[285.866334] Subtest unload: SUCCESS (29.049s)
[286.096683] [155/162] (741s left) i915_module_load (reload)
[286.169993] Starting subtest: reload
[290.484345] Subtest reload: SUCCESS (4.314s)
[290.570282] [156/162] (736s left) gem_lmem_swapping (basic)
[290.664076] Subtest basic: SKIP (0.000s)
[290.751561] [157/162] (736s left) gem_lmem_swapping (parallel-random-engines)
[290.833338] Subtest parallel-random-engines: SKIP (0.000s)
[290.928795] [158/162] (736s left) gem_lmem_swapping (random-engines)
[291.012953] Subtest random-engines: SKIP (0.000s)
[291.100291] [159/162] (736s left) gem_lmem_swapping (verify-random)
[291.183966] Subtest verify-random: SKIP (0.000s)
[291.268603] [160/162] (736s left) i915_pm_rpm (module-reload)
[293.663251] Starting subtest: module-reload
[306.944799] Subtest module-reload: SUCCESS (13.285s)
[306.991546] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[306.993966] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[306.999767] [161/162] (720s left) i915_selftest (live)
[307.083092] Starting subtest: live
[307.084475] Starting dynamic subtest: sanitycheck
[309.950749] Dynamic subtest sanitycheck: SUCCESS (2.861s)
[309.965698] Starting dynamic subtest: uncore
[312.908587] Dynamic subtest uncore: SUCCESS (2.956s)
[312.927226] Starting dynamic subtest: workarounds
[315.991937] Dynamic subtest workarounds: SUCCESS (3.081s)
[316.012331] Starting dynamic subtest: gt_engines
[319.387977] Dynamic subtest gt_engines: SUCCESS (3.394s)
[319.406065] Starting dynamic subtest: gt_timelines
[333.431914] Dynamic subtest gt_timelines: SUCCESS (14.041s)
[333.447913] Starting dynamic subtest: gt_contexts
[336.374855] Dynamic subtest gt_contexts: SUCCESS (2.941s)
[336.396422] Starting dynamic subtest: gt_lrc
[341.476712] Dynamic subtest gt_lrc: SUCCESS (5.099s)
[341.491736] Starting dynamic subtest: gt_mocs
[344.525800] Dynamic subtest gt_mocs: SUCCESS (3.047s)
[344.541597] Starting dynamic subtest: gt_pm
[354.405745] Dynamic subtest gt_pm: SUCCESS (9.877s)
[354.422053] Starting dynamic subtest: gt_heartbeat
[357.218609] Dynamic subtest gt_heartbeat: SUCCESS (2.810s)
[357.234446] Starting dynamic subtest: gt_tlb
[361.849040] Dynamic subtest gt_tlb: SUCCESS (4.628s)
[361.863345] Starting dynamic subtest: requests
[370.533015] Dynamic subtest requests: SUCCESS (8.681s)
[370.553055] Starting dynamic subtest: migrate
[376.102327] Dynamic subtest migrate: SUCCESS (5.566s)
[376.129987] Starting dynamic subtest: active
[379.141705] Dynamic subtest active: SUCCESS (3.037s)
[379.156590] Starting dynamic subtest: objects
[383.036834] Dynamic subtest objects: SUCCESS (3.892s)
[383.051764] Starting dynamic subtest: mman
[389.073875] Dynamic subtest mman: SUCCESS (6.034s)
[389.089031] Starting dynamic subtest: dmabuf
[392.100830] Dynamic subtest dmabuf: SUCCESS (3.024s)
[392.120473] Starting dynamic subtest: vma