[41.439538] Initializing watchdogs
[41.439667]   /dev/watchdog0
[41.470531] [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.478450] [001/162] (960s left) i915_module_load (load)
[41.554220] Starting subtest: load
[43.409669] Subtest load: SUCCESS (1.854s)
[43.465750] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[43.469113] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[43.472788] [002/162] (958s left) core_auth (basic-auth)
[43.552221] Starting subtest: basic-auth
[43.553242] Subtest basic-auth: SUCCESS (0.001s)
[43.707959] [003/162] (958s left) core_debugfs (read-all-entries)
[43.777071] Starting subtest: read-all-entries
[43.893170] Subtest read-all-entries: SUCCESS (0.116s)
[44.079186] [004/162] (958s left) core_sysfs (read-all-entries)
[44.147264] Starting subtest: read-all-entries
[44.153102] Subtest read-all-entries: SUCCESS (0.006s)
[44.328209] [005/162] (957s left) fbdev (eof)
[44.410246] Starting subtest: eof
[44.411337] Subtest eof: SUCCESS (0.001s)
[44.594903] [006/162] (957s left) fbdev (info)
[44.658117] Starting subtest: info
[44.659072] Subtest info: SUCCESS (0.000s)
[44.847784] [007/162] (957s left) fbdev (nullptr)
[44.925025] Starting subtest: nullptr
[44.926055] Subtest nullptr: SUCCESS (0.000s)
[45.089789] [008/162] (957s left) fbdev (read)
[45.158397] Starting subtest: read
[46.053779] Subtest read: SUCCESS (0.891s)
[46.193600] [009/162] (956s left) fbdev (write)
[46.275431] Starting subtest: write
[46.755987] Subtest write: SUCCESS (0.472s)
[46.880723] [010/162] (955s left) gem_basic (bad-close)
[46.948471] Starting subtest: bad-close
[46.950264] Subtest bad-close: SUCCESS (0.000s)
[47.121128] [011/162] (955s left) gem_basic (create-close)
[47.186823] Starting subtest: create-close
[47.188912] Subtest create-close: SUCCESS (0.000s)
[47.373669] [012/162] (955s left) gem_basic (create-fd-close)
[47.446475] Starting subtest: create-fd-close
[47.448262] Subtest create-fd-close: SUCCESS (0.000s)
[47.634153] [013/162] (955s left) gem_busy (busy@all-engines)
[47.727113] Starting subtest: busy
[47.728071] Starting dynamic subtest: all-engines
[47.784649] Dynamic subtest all-engines: SUCCESS (0.058s)
[47.785634] Subtest busy: SUCCESS (0.058s)
[48.297465] [014/162] (954s left) gem_close_race (basic-process)
[48.387379] Starting subtest: basic-process
[48.457452] Subtest basic-process: SUCCESS (0.070s)
[48.777919] [015/162] (954s left) gem_close_race (basic-threads)
[48.867699] Starting subtest: basic-threads
[50.125261] Subtest basic-threads: SUCCESS (1.257s)
[50.456385] [016/162] (952s left) gem_ctx_create (basic)
[50.539885] Starting subtest: basic
[50.541168] Subtest basic: SUCCESS (0.000s)
[50.852991] [017/162] (952s left) gem_ctx_create (basic-files)
[50.936962] Starting subtest: basic-files
[52.956337] Subtest basic-files: SUCCESS (2.051s)
[53.304329] [018/162] (949s left) gem_ctx_exec (basic)
[53.375862] Starting subtest: basic
[53.383067] Subtest basic: SUCCESS (0.007s)
[53.585217] [019/162] (949s left) gem_exec_basic (basic)
[53.661323] Starting subtest: basic
[53.662585] Starting dynamic subtest: rcs0-smem
[53.664816] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[53.678139] Starting dynamic subtest: bcs0-smem
[53.679478] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[53.691622] Starting dynamic subtest: vcs0-smem
[53.692875] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[53.705251] Starting dynamic subtest: vecs0-smem
[53.706569] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[53.707817] Subtest basic: SUCCESS (0.008s)
[53.993807] [020/162] (949s left) gem_exec_create (basic)
[54.077716] Starting subtest: basic
[54.078875] Starting dynamic subtest: smem
[56.128732] Dynamic subtest smem: SUCCESS (2.073s)
[56.129663] Subtest basic: SUCCESS (2.074s)
[56.464841] [021/162] (946s left) gem_exec_fence (basic-busy)
[56.704547] Starting subtest: basic-busy
[56.705717] Starting dynamic subtest: rcs0
[56.734229] Dynamic subtest rcs0: SUCCESS (0.030s)
[56.747888] Starting dynamic subtest: bcs0
[56.763170] Dynamic subtest bcs0: SUCCESS (0.029s)
[56.776473] Starting dynamic subtest: vcs0
[56.797209] Dynamic subtest vcs0: SUCCESS (0.034s)
[56.810487] Starting dynamic subtest: vecs0
[56.820274] Dynamic subtest vecs0: SUCCESS (0.023s)
[56.822879] Subtest basic-busy: SUCCESS (0.117s)
[57.617121] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[57.622777] [022/162] (945s left) gem_exec_fence (basic-wait)
[57.860780] Starting subtest: basic-wait
[57.861963] Starting dynamic subtest: rcs0
[57.888310] Dynamic subtest rcs0: SUCCESS (0.029s)
[57.901990] Starting dynamic subtest: bcs0
[57.916168] Dynamic subtest bcs0: SUCCESS (0.028s)
[57.929158] Starting dynamic subtest: vcs0
[57.941263] Dynamic subtest vcs0: SUCCESS (0.025s)
[57.954553] Starting dynamic subtest: vecs0
[57.975078] Dynamic subtest vecs0: SUCCESS (0.033s)
[57.976478] Subtest basic-wait: SUCCESS (0.116s)
[58.801990] [023/162] (944s left) gem_exec_fence (basic-await)
[59.032462] Starting subtest: basic-await
[59.033490] Starting dynamic subtest: rcs0
[59.130466] Dynamic subtest rcs0: SUCCESS (0.100s)
[59.147889] Starting dynamic subtest: bcs0
[59.232152] Dynamic subtest bcs0: SUCCESS (0.102s)
[59.249716] Starting dynamic subtest: vcs0
[59.332772] Dynamic subtest vcs0: SUCCESS (0.101s)
[59.347801] Starting dynamic subtest: vecs0
[59.434226] Dynamic subtest vecs0: SUCCESS (0.101s)
[59.435314] Subtest basic-await: SUCCESS (0.406s)
[60.287592] [024/162] (943s left) gem_exec_fence (nb-await)
[60.515244] Starting subtest: nb-await
[60.517109] Starting dynamic subtest: rcs0
[60.585358] Dynamic subtest rcs0: SUCCESS (0.070s)
[60.616320] Starting dynamic subtest: bcs0
[60.671084] Dynamic subtest bcs0: SUCCESS (0.083s)
[60.710726] Starting dynamic subtest: vcs0
[60.758094] Dynamic subtest vcs0: SUCCESS (0.087s)
[60.776812] Starting dynamic subtest: vecs0
[60.831191] Dynamic subtest vecs0: SUCCESS (0.069s)
[60.833835] Subtest nb-await: SUCCESS (0.317s)
[61.635340] [025/162] (942s left) gem_exec_gttfill (basic)
[61.719503] Starting subtest: basic
[67.383600] Subtest basic: SUCCESS (5.673s)
[67.730955] [026/162] (936s left) gem_exec_parallel (engines)
[67.938115] Starting subtest: engines
[67.939248] Starting dynamic subtest: basic
[70.731240] Dynamic subtest basic: SUCCESS (2.799s)
[70.745879] Starting dynamic subtest: contexts
[76.195841] Dynamic subtest contexts: SUCCESS (5.469s)
[76.210214] Starting dynamic subtest: fds
[84.008403] Dynamic subtest fds: SUCCESS (7.815s)
[84.023524] Starting dynamic subtest: userptr
[86.917303] Dynamic subtest userptr: SUCCESS (2.888s)
[86.918577] Subtest engines: SUCCESS (18.973s)
[87.249159] [027/162] (916s left) gem_exec_store (basic)
[87.351563] Starting subtest: basic
[87.436413] Subtest basic: SUCCESS (0.084s)
[87.795357] [028/162] (916s left) gem_flink_basic (bad-flink)
[87.862817] Starting subtest: bad-flink
[87.864156] Subtest bad-flink: SUCCESS (0.000s)
[88.054596] [029/162] (916s left) gem_flink_basic (bad-open)
[88.120202] Starting subtest: bad-open
[88.121165] Subtest bad-open: SUCCESS (0.000s)
[88.282211] [030/162] (915s left) gem_flink_basic (basic)
[88.351432] Starting subtest: basic
[88.352414] Subtest basic: SUCCESS (0.000s)
[88.553310] [031/162] (915s left) gem_flink_basic (double-flink)
[88.626411] Starting subtest: double-flink
[88.627332] Subtest double-flink: SUCCESS (0.000s)
[88.840289] [032/162] (915s left) gem_flink_basic (flink-lifetime)
[88.907182] Starting subtest: flink-lifetime
[88.910189] Subtest flink-lifetime: SUCCESS (0.003s)
[89.102668] [033/162] (915s left) gem_huc_copy (huc-copy)
[89.177928] Starting subtest: huc-copy
[89.180156] Subtest huc-copy: SKIP (0.000s)
[89.391614] [034/162] (915s left) gem_linear_blits (basic)
[89.592854] Starting subtest: basic
[89.606918] Subtest basic: SUCCESS (0.013s)
[89.863017] [035/162] (914s left) gem_mmap (basic)
[89.996288] Starting subtest: basic
[90.000865] Subtest basic: SUCCESS (0.001s)
[90.195291] [036/162] (914s left) gem_mmap_gtt (basic)
[90.261292] Starting subtest: basic
[90.263224] Subtest basic: SUCCESS (0.001s)
[90.433953] [037/162] (914s left) gem_render_linear_blits (basic)
[90.512065] Starting subtest: basic
[90.558193] Subtest basic: SUCCESS (0.046s)
[90.651559] [038/162] (914s left) gem_render_tiled_blits (basic)
[90.728618] Starting subtest: basic
[90.778589] Subtest basic: SUCCESS (0.050s)
[90.886042] [039/162] (914s left) gem_ringfill (basic-all)
[91.173334] Starting subtest: basic-all
[92.339960] Subtest basic-all: SUCCESS (1.155s)
[92.611415] [040/162] (912s left) gem_softpin (allocator-basic)
[92.686490] Starting subtest: allocator-basic
[92.875637] Subtest allocator-basic: SUCCESS (0.180s)
[93.075840] [041/162] (912s left) gem_softpin (allocator-basic-reserve)
[93.150334] Starting subtest: allocator-basic-reserve
[93.330939] Subtest allocator-basic-reserve: SUCCESS (0.172s)
[93.546480] [042/162] (911s left) gem_softpin (safe-alignment)
[93.621668] Starting subtest: safe-alignment
[93.640686] Subtest safe-alignment: SUCCESS (0.019s)
[93.865474] [043/162] (911s left) gem_sync (basic-all)
[93.952477] Starting subtest: basic-all
[96.011377] Subtest basic-all: SUCCESS (2.059s)
[96.358800] [044/162] (909s left) gem_sync (basic-each)
[96.445643] Starting subtest: basic-each
[98.762517] Subtest basic-each: SUCCESS (2.317s)
[99.179720] [045/162] (906s left) gem_tiled_blits (basic)
[99.343494] Starting subtest: basic
[99.365941] Subtest basic: SUCCESS (0.023s)
[99.613914] [046/162] (906s left) gem_tiled_fence_blits (basic)
[99.844083] Starting subtest: basic
[99.857575] Subtest basic: SUCCESS (0.014s)
[100.089272] [047/162] (905s left) gem_tiled_pread_basic
[100.556631] [048/162] (905s left) gem_wait (busy@all-engines)
[100.651033] Starting subtest: busy
[100.652182] Starting dynamic subtest: all-engines
[101.171409] Dynamic subtest all-engines: SUCCESS (0.520s)
[101.172417] Subtest busy: SUCCESS (0.521s)
[101.543915] [049/162] (904s left) gem_wait (wait@all-engines)
[101.639439] Starting subtest: wait
[101.640423] Starting dynamic subtest: all-engines
[102.704450] Dynamic subtest all-engines: SUCCESS (1.063s)
[102.707654] Subtest wait: SUCCESS (1.065s)
[103.097921] [050/162] (902s left) i915_getparams_basic (basic-eu-total)
[103.165335] Starting subtest: basic-eu-total
[103.167213] Subtest basic-eu-total: SUCCESS (0.000s)
[103.346902] [051/162] (902s left) i915_getparams_basic (basic-subslice-total)
[103.414954] Starting subtest: basic-subslice-total
[103.417492] Subtest basic-subslice-total: SUCCESS (0.000s)
[103.596294] [052/162] (902s left) i915_hangman (error-state-basic)
[103.684222] Starting subtest: error-state-basic
[103.693637] Subtest error-state-basic: SUCCESS (0.009s)
[103.850985] [053/162] (902s left) i915_pciid
[104.101399] [054/162] (902s left) intel_hwmon (hwmon-read)
[104.168229] Subtest hwmon-read: SKIP (0.000s)
[104.258406] [055/162] (902s left) intel_hwmon (hwmon-write)
[104.326109] Subtest hwmon-write: SKIP (0.000s)
[104.409834] [056/162] (902s left) kms_addfb_basic (addfb25-4-tiled)
[104.539344] Starting subtest: addfb25-4-tiled
[104.540362] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[104.738097] [057/162] (901s left) kms_addfb_basic (addfb25-bad-modifier)
[104.861226] Starting subtest: addfb25-bad-modifier
[104.862135] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[105.069093] [058/162] (901s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[105.187283] Starting subtest: addfb25-framebuffer-vs-set-tiling
[105.188329] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[105.388803] [059/162] (901s left) kms_addfb_basic (addfb25-modifier-no-flag)
[105.508338] Starting subtest: addfb25-modifier-no-flag
[105.509349] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[105.720542] [060/162] (901s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[105.840258] Starting subtest: addfb25-x-tiled-legacy
[105.841223] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[106.037907] [061/162] (900s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[106.158194] Starting subtest: addfb25-x-tiled-mismatch-legacy
[106.159175] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[106.370326] [062/162] (900s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[106.491224] Starting subtest: addfb25-yf-tiled-legacy
[106.492258] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[106.706621] [063/162] (900s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[106.824091] Starting subtest: addfb25-y-tiled-legacy
[106.825120] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[107.038784] [064/162] (900s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[107.157254] Starting subtest: addfb25-y-tiled-small-legacy
[107.158229] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[107.349856] [065/162] (899s left) kms_addfb_basic (bad-pitch-0)
[107.418144] Starting subtest: bad-pitch-0
[107.419120] Subtest bad-pitch-0: SUCCESS (0.000s)
[107.676572] [066/162] (899s left) kms_addfb_basic (bad-pitch-1024)
[107.744222] Starting subtest: bad-pitch-1024
[107.745417] Subtest bad-pitch-1024: SUCCESS (0.000s)
[107.994831] [067/162] (899s left) kms_addfb_basic (bad-pitch-128)
[108.061491] Starting subtest: bad-pitch-128
[108.062514] Subtest bad-pitch-128: SUCCESS (0.000s)
[108.317369] [068/162] (899s left) kms_addfb_basic (bad-pitch-256)
[108.384248] Starting subtest: bad-pitch-256
[108.385261] Subtest bad-pitch-256: SUCCESS (0.000s)
[108.648863] [069/162] (898s left) kms_addfb_basic (bad-pitch-32)
[108.715356] Starting subtest: bad-pitch-32
[108.716272] Subtest bad-pitch-32: SUCCESS (0.000s)
[108.991707] [070/162] (898s left) kms_addfb_basic (bad-pitch-63)
[109.057404] Starting subtest: bad-pitch-63
[109.058389] Subtest bad-pitch-63: SUCCESS (0.000s)
[109.315258] [071/162] (898s left) kms_addfb_basic (bad-pitch-65536)
[109.382216] Starting subtest: bad-pitch-65536
[109.383136] Subtest bad-pitch-65536: SUCCESS (0.000s)
[109.635568] [072/162] (898s left) kms_addfb_basic (bad-pitch-999)
[109.701532] Starting subtest: bad-pitch-999
[109.702530] Subtest bad-pitch-999: SUCCESS (0.000s)
[109.965044] [073/162] (897s left) kms_addfb_basic (basic)
[110.032745] Starting subtest: basic
[110.033971] Subtest basic: SUCCESS (0.000s)
[110.290682] [074/162] (897s left) kms_addfb_basic (basic-x-tiled-legacy)
[110.359413] Starting subtest: basic-x-tiled-legacy
[110.360332] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[110.613923] [075/162] (897s left) kms_addfb_basic (basic-y-tiled-legacy)
[110.682017] Starting subtest: basic-y-tiled-legacy
[110.683094] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[110.957537] [076/162] (897s left) kms_addfb_basic (bo-too-small)
[111.081178] Starting subtest: bo-too-small
[111.082192] Subtest bo-too-small: SUCCESS (0.000s)
[111.288243] [077/162] (896s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[111.408430] Starting subtest: bo-too-small-due-to-tiling
[111.409420] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[111.620370] [078/162] (896s left) kms_addfb_basic (clobberred-modifier)
[111.685756] Starting subtest: clobberred-modifier
[111.686796] Subtest clobberred-modifier: SUCCESS (0.000s)
[111.948521] [079/162] (896s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[112.016293] Starting subtest: framebuffer-vs-set-tiling
[112.017313] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[112.277216] [080/162] (896s left) kms_addfb_basic (invalid-get-prop)
[112.343377] Starting subtest: invalid-get-prop
[112.344336] Subtest invalid-get-prop: SUCCESS (0.000s)
[112.584019] [081/162] (895s left) kms_addfb_basic (invalid-get-prop-any)
[112.650566] Starting subtest: invalid-get-prop-any
[112.651521] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[112.909462] [082/162] (895s left) kms_addfb_basic (invalid-set-prop)
[112.977471] Starting subtest: invalid-set-prop
[112.978458] Subtest invalid-set-prop: SUCCESS (0.000s)
[113.238284] [083/162] (895s left) kms_addfb_basic (invalid-set-prop-any)
[113.304370] Starting subtest: invalid-set-prop-any
[113.305351] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[113.572337] [084/162] (895s left) kms_addfb_basic (no-handle)
[113.640960] Starting subtest: no-handle
[113.642143] Subtest no-handle: SUCCESS (0.000s)
[113.909640] [085/162] (894s left) kms_addfb_basic (size-max)
[114.033150] Starting subtest: size-max
[114.034241] Subtest size-max: SUCCESS (0.000s)
[114.244620] [086/162] (894s left) kms_addfb_basic (small-bo)
[114.362251] Starting subtest: small-bo
[114.363210] Subtest small-bo: SUCCESS (0.000s)
[114.582302] [087/162] (894s left) kms_addfb_basic (tile-pitch-mismatch)
[114.651246] Starting subtest: tile-pitch-mismatch
[114.652235] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[114.950132] [088/162] (894s left) kms_addfb_basic (too-high)
[115.068410] Starting subtest: too-high
[115.069408] Subtest too-high: SUCCESS (0.001s)
[115.263421] [089/162] (893s left) kms_addfb_basic (too-wide)
[115.384346] Starting subtest: too-wide
[115.385327] Subtest too-wide: SUCCESS (0.000s)
[115.585136] [090/162] (893s left) kms_addfb_basic (unused-handle)
[115.649942] Starting subtest: unused-handle
[115.651134] Subtest unused-handle: SUCCESS (0.000s)
[115.900564] [091/162] (893s left) kms_addfb_basic (unused-modifier)
[115.966812] Starting subtest: unused-modifier
[115.967992] Subtest unused-modifier: SUCCESS (0.000s)
[116.225621] [092/162] (893s left) kms_addfb_basic (unused-offsets)
[116.294508] Starting subtest: unused-offsets
[116.295454] Subtest unused-offsets: SUCCESS (0.000s)
[116.556296] [093/162] (892s left) kms_addfb_basic (unused-pitches)
[116.623221] Starting subtest: unused-pitches
[116.624183] Subtest unused-pitches: SUCCESS (0.000s)
[116.881822] [094/162] (892s left) kms_busy (basic)
[117.008617] Starting subtest: basic
[117.011084] Starting dynamic subtest: flip
[117.893194] Dynamic subtest flip: SUCCESS (0.881s)
[117.928887] Starting dynamic subtest: modeset
[121.533957] Dynamic subtest modeset: SUCCESS (3.637s)
[121.536515] Subtest basic: SUCCESS (4.522s)
[122.516386] [095/162] (887s left) kms_prop_blob (basic)
[122.586358] Starting subtest: basic
[122.587392] Subtest basic: SUCCESS (0.000s)
[122.785410] [096/162] (887s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[122.905129] Starting subtest: basic-busy-flip-before-cursor-atomic
[122.906932] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[123.098763] [097/162] (886s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[123.220207] Starting subtest: basic-busy-flip-before-cursor-legacy
[123.222063] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[123.405022] [098/162] (886s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[123.524133] Starting subtest: basic-flip-after-cursor-atomic
[124.855180] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.327s)
[125.788301] [099/162] (884s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[125.910217] Starting subtest: basic-flip-after-cursor-legacy
[127.222233] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.310s)
[128.235221] [100/162] (881s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[128.360127] Starting subtest: basic-flip-after-cursor-varying-size
[129.657141] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.294s)
[130.587428] [101/162] (879s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[130.706202] Starting subtest: basic-flip-before-cursor-atomic
[131.936336] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.225s)
[132.912476] [102/162] (877s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[133.033159] Starting subtest: basic-flip-before-cursor-legacy
[134.299875] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.266s)
[135.217617] [103/162] (875s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[135.341180] Starting subtest: basic-flip-before-cursor-varying-size
[136.670722] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.325s)
[137.626081] [104/162] (872s left) kms_dsc (dsc-basic)
[137.746246] Subtest dsc-basic: SKIP (0.000s)
[137.830711] [105/162] (872s left) kms_flip (basic-flip-vs-dpms)
[137.951196] Starting subtest: basic-flip-vs-dpms
[137.955251] Starting dynamic subtest: A-eDP1
[140.655598] Dynamic subtest A-eDP1: SUCCESS (2.697s)
[140.724340] Starting dynamic subtest: B-eDP1
[143.063311] Dynamic subtest B-eDP1: SUCCESS (2.399s)
[143.135175] Starting dynamic subtest: C-eDP1
[145.384309] Dynamic subtest C-eDP1: SUCCESS (2.319s)
[145.399594] Subtest basic-flip-vs-dpms: SUCCESS (7.445s)
[146.417565] [106/162] (864s left) kms_flip (basic-flip-vs-modeset)
[146.538365] Starting subtest: basic-flip-vs-modeset
[146.540224] Starting dynamic subtest: A-eDP1
[149.237860] Dynamic subtest A-eDP1: SUCCESS (2.696s)
[149.311291] Starting dynamic subtest: B-eDP1
[151.566059] Dynamic subtest B-eDP1: SUCCESS (2.322s)
[151.635082] Starting dynamic subtest: C-eDP1
[153.835358] Dynamic subtest C-eDP1: SUCCESS (2.262s)
[153.845974] Subtest basic-flip-vs-modeset: SUCCESS (7.308s)
[154.819134] [107/162] (855s left) kms_flip (basic-flip-vs-wf_vblank)
[154.940297] Starting subtest: basic-flip-vs-wf_vblank
[154.943131] Starting dynamic subtest: A-eDP1
[157.142329] Dynamic subtest A-eDP1: SUCCESS (2.199s)
[157.169407] Starting dynamic subtest: B-eDP1
[159.319397] Dynamic subtest B-eDP1: SUCCESS (2.175s)
[159.346521] Starting dynamic subtest: C-eDP1
[161.434326] Dynamic subtest C-eDP1: SUCCESS (2.113s)
[161.438139] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.499s)
[162.776461] [108/162] (848s left) kms_flip (basic-plain-flip)
[162.905243] Starting subtest: basic-plain-flip
[162.909657] Starting dynamic subtest: A-eDP1
[164.763537] Dynamic subtest A-eDP1: SUCCESS (1.852s)
[164.830038] Starting dynamic subtest: B-eDP1
[166.674148] Dynamic subtest B-eDP1: SUCCESS (1.906s)
[166.695714] Starting dynamic subtest: C-eDP1
[168.614648] Dynamic subtest C-eDP1: SUCCESS (1.932s)
[168.622999] Subtest basic-plain-flip: SUCCESS (5.717s)
[170.122022] [109/162] (840s left) kms_force_connector_basic (force-connector-state)
[170.220340] Starting subtest: force-connector-state
[170.791674] Subtest force-connector-state: SUCCESS (0.571s)
[172.195679] [110/162] (838s left) kms_force_connector_basic (force-edid)
[172.294336] Starting subtest: force-edid
[172.332264] Subtest force-edid: SUCCESS (0.037s)
[172.580749] [111/162] (838s left) kms_force_connector_basic (force-load-detect)
[172.679827] Starting subtest: force-load-detect
[172.681903] Subtest force-load-detect: SKIP (0.000s)
[172.939710] [112/162] (838s left) kms_force_connector_basic (prune-stale-modes)
[173.036105] Starting subtest: prune-stale-modes
[173.074372] Subtest prune-stale-modes: SUCCESS (0.039s)
[173.326062] [113/162] (838s left) kms_frontbuffer_tracking (basic)
[173.450425] Starting subtest: basic
[177.059948] Subtest basic: SUCCESS (3.609s)
[177.302999] [114/162] (834s left) kms_hdmi_inject (inject-audio)
[177.723216] Starting subtest: inject-audio
[177.893958] Subtest inject-audio: SUCCESS (0.180s)
[178.784058] [115/162] (832s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[178.904026] Starting subtest: compare-crc-sanitycheck-xr24
[178.905109] Starting dynamic subtest: pipe-A-eDP-1
[179.664881] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.749s)
[179.732905] Starting dynamic subtest: pipe-B-eDP-1
[181.109331] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.430s)
[181.185670] Starting dynamic subtest: pipe-C-eDP-1
[182.618566] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.483s)
[182.621210] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.711s)
[183.662750] [116/162] (828s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[183.786972] Starting subtest: compare-crc-sanitycheck-nv12
[183.788082] Starting dynamic subtest: pipe-A-eDP-1
[183.789906] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[183.808345] Starting dynamic subtest: pipe-B-eDP-1
[183.810580] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[183.826299] Starting dynamic subtest: pipe-C-eDP-1
[183.828717] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[183.831061] Subtest compare-crc-sanitycheck-nv12: SKIP (0.010s)
[183.967652] [117/162] (827s left) kms_pipe_crc_basic (hang-read-crc)
[184.089203] Starting subtest: hang-read-crc
[184.090472] Starting dynamic subtest: pipe-A-eDP-1
[186.634157] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.535s)
[186.712863] Starting dynamic subtest: pipe-B-eDP-1
[189.956736] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.317s)
[190.033329] Starting dynamic subtest: pipe-C-eDP-1
[193.042142] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.071s)
[193.051100] Subtest hang-read-crc: SUCCESS (8.956s)
[194.048637] [118/162] (817s left) kms_pipe_crc_basic (nonblocking-crc)
[194.173144] Starting subtest: nonblocking-crc
[194.176188] Starting dynamic subtest: pipe-A-eDP-1
[195.164344] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.981s)
[195.235114] Starting dynamic subtest: pipe-B-eDP-1
[196.870218] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.696s)
[196.952460] Starting dynamic subtest: pipe-C-eDP-1
[198.630281] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.743s)
[198.640644] Subtest nonblocking-crc: SUCCESS (4.462s)
[199.607361] [119/162] (812s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[199.726248] Starting subtest: nonblocking-crc-frame-sequence
[199.727402] Starting dynamic subtest: pipe-A-eDP-1
[200.677052] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.937s)
[200.755038] Starting dynamic subtest: pipe-B-eDP-1
[202.444221] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.760s)
[202.526205] Starting dynamic subtest: pipe-C-eDP-1
[204.191262] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.738s)
[204.203275] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.470s)
[205.228854] [120/162] (806s left) kms_pipe_crc_basic (read-crc)
[205.351065] Starting subtest: read-crc
[205.353962] Starting dynamic subtest: pipe-A-eDP-1
[206.254229] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.896s)
[206.336198] Starting dynamic subtest: pipe-B-eDP-1
[207.813805] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.545s)
[207.838093] Starting dynamic subtest: pipe-C-eDP-1
[209.392090] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.570s)
[209.394220] Subtest read-crc: SUCCESS (4.041s)
[210.404648] [121/162] (801s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[210.527492] Starting subtest: read-crc-frame-sequence
[210.529816] Starting dynamic subtest: pipe-A-eDP-1
[211.446289] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.909s)
[211.529422] Starting dynamic subtest: pipe-B-eDP-1
[212.966116] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.504s)
[213.047178] Starting dynamic subtest: pipe-C-eDP-1
[214.604826] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.621s)
[214.607355] Subtest read-crc-frame-sequence: SUCCESS (4.074s)
[215.583404] [122/162] (796s left) kms_pm_backlight (basic-brightness)
[215.709322] Starting subtest: basic-brightness
[215.779184] Starting dynamic subtest: eDP-1
[216.366520] Dynamic subtest eDP-1: SUCCESS (0.585s)
[216.375261] Subtest basic-brightness: SUCCESS (0.660s)
[217.386788] [123/162] (795s left) kms_pm_rpm (basic-pci-d3-state)
[219.123477] Starting subtest: basic-pci-d3-state
[219.724664] Subtest basic-pci-d3-state: SUCCESS (0.593s)
[220.007883] [124/162] (792s left) kms_pm_rpm (basic-rte)
[220.074790] Starting subtest: basic-rte
[222.343054] Subtest basic-rte: SUCCESS (2.270s)
[223.698791] [125/162] (788s left) kms_psr (psr-primary-page-flip)
[223.820801] Starting subtest: psr-primary-page-flip
[223.824371] Starting dynamic subtest: eDP-1
[225.820371] Dynamic subtest eDP-1: SUCCESS (1.994s)
[225.829743] Subtest psr-primary-page-flip: SUCCESS (2.003s)
[226.812822] [126/162] (785s left) kms_psr (psr-cursor-plane-move)
[226.933994] Starting subtest: psr-cursor-plane-move
[226.935037] Starting dynamic subtest: eDP-1
[228.915175] Dynamic subtest eDP-1: SUCCESS (1.976s)
[228.917325] Subtest psr-cursor-plane-move: SUCCESS (1.983s)
[229.878050] [127/162] (782s left) kms_psr (psr-sprite-plane-onoff)
[229.998849] Starting subtest: psr-sprite-plane-onoff
[230.002275] Starting dynamic subtest: eDP-1
[232.004790] Dynamic subtest eDP-1: SUCCESS (1.998s)
[232.006962] Subtest psr-sprite-plane-onoff: SUCCESS (2.004s)
[232.996361] [128/162] (779s left) kms_psr (psr-primary-mmap-gtt)
[233.119860] Starting subtest: psr-primary-mmap-gtt
[233.120873] Starting dynamic subtest: eDP-1
[235.067687] Dynamic subtest eDP-1: SUCCESS (1.945s)
[235.079127] Subtest psr-primary-mmap-gtt: SUCCESS (1.952s)
[236.051663] [129/162] (776s left) kms_setmode (basic-clone-single-crtc)
[236.121473] Starting subtest: basic-clone-single-crtc
[236.124209] Subtest basic-clone-single-crtc: SKIP (0.000s)
[236.298336] [130/162] (776s left) i915_pm_rps (basic-api)
[236.377849] Starting subtest: basic-api
[236.383863] Subtest basic-api: SUCCESS (0.006s)
[236.574780] [131/162] (776s left) prime_self_import (basic-llseek-bad)
[236.642200] Starting subtest: basic-llseek-bad
[236.647130] Subtest basic-llseek-bad: SUCCESS (0.003s)
[236.858529] [132/162] (776s left) prime_self_import (basic-llseek-size)
[236.923520] Starting subtest: basic-llseek-size
[236.929532] Subtest basic-llseek-size: SUCCESS (0.006s)
[237.157428] [133/162] (776s left) prime_self_import (basic-with_fd_dup)
[237.275648] Starting subtest: basic-with_fd_dup
[237.281125] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[237.545870] [134/162] (775s left) prime_self_import (basic-with_one_bo)
[237.613028] Starting subtest: basic-with_one_bo
[237.615527] Subtest basic-with_one_bo: SUCCESS (0.004s)
[237.806313] [135/162] (775s left) prime_self_import (basic-with_one_bo_two_files)
[237.870356] Starting subtest: basic-with_one_bo_two_files
[237.876430] Subtest basic-with_one_bo_two_files: SUCCESS (0.005s)
[238.077153] [136/162] (775s left) prime_self_import (basic-with_two_bos)
[238.141325] Starting subtest: basic-with_two_bos
[238.146482] Subtest basic-with_two_bos: SUCCESS (0.005s)
[238.361233] [137/162] (775s left) prime_vgem (basic-fence-flip)
[238.445374] Starting subtest: basic-fence-flip
[238.965345] Subtest basic-fence-flip: SUCCESS (0.519s)
[239.189122] [138/162] (774s left) prime_vgem (basic-fence-mmap)
[239.287599] Starting subtest: basic-fence-mmap
[239.320763] Subtest basic-fence-mmap: SUCCESS (0.033s)
[239.536168] [139/162] (774s left) prime_vgem (basic-fence-read)
[239.637722] Starting subtest: basic-fence-read
[239.717638] Subtest basic-fence-read: SUCCESS (0.080s)
[239.905659] [140/162] (774s left) prime_vgem (basic-gtt)
[240.004367] Starting subtest: basic-gtt
[240.020486] Subtest basic-gtt: SUCCESS (0.016s)
[240.202738] [141/162] (773s left) prime_vgem (basic-read)
[240.300851] Starting subtest: basic-read
[240.338608] Subtest basic-read: SUCCESS (0.038s)
[240.558505] [142/162] (773s left) prime_vgem (basic-write)
[240.652454] Starting subtest: basic-write
[240.692131] Subtest basic-write: SUCCESS (0.040s)
[240.892508] [143/162] (773s left) vgem_basic (setversion)
[240.968385] Starting subtest: setversion
[240.970190] Subtest setversion: SUCCESS (0.000s)
[241.145604] [144/162] (773s left) vgem_basic (create)
[241.219142] Starting subtest: create
[241.220154] Subtest create: SUCCESS (0.000s)
[241.398710] [145/162] (773s left) vgem_basic (debugfs)
[241.467091] Starting subtest: debugfs
[241.468116] Subtest debugfs: SUCCESS (0.001s)
[241.650052] [146/162] (772s left) vgem_basic (dmabuf-export)
[241.735285] Starting subtest: dmabuf-export
[241.737106] Subtest dmabuf-export: SUCCESS (0.003s)
[241.944233] [147/162] (772s left) vgem_basic (dmabuf-fence)
[242.019412] Starting subtest: dmabuf-fence
[242.020437] Subtest dmabuf-fence: SUCCESS (0.001s)
[242.175841] [148/162] (772s left) vgem_basic (dmabuf-fence-before)
[242.252084] Starting subtest: dmabuf-fence-before
[242.253013] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[242.421545] [149/162] (772s left) vgem_basic (dmabuf-mmap)
[242.502316] Starting subtest: dmabuf-mmap
[242.515624] Subtest dmabuf-mmap: SUCCESS (0.015s)
[242.676911] [150/162] (772s left) vgem_basic (mmap)
[242.753113] Starting subtest: mmap
[242.761295] Subtest mmap: SUCCESS (0.010s)
[242.934767] [151/162] (772s left) vgem_basic (second-client)
[243.020077] Starting subtest: second-client
[243.050215] Subtest second-client: SUCCESS (0.032s)
[243.226819] [152/162] (771s left) vgem_basic (sysfs)
[243.302415] Starting subtest: sysfs
[243.303334] Subtest sysfs: SUCCESS (0.001s)
[243.443405] [153/162] (771s left) core_hotunplug (unbind-rebind)
[243.586323] Starting subtest: unbind-rebind
[248.629442] Subtest unbind-rebind: SUCCESS (5.045s)
[248.709358] [154/162] (766s left) vgem_basic (unload)
[248.779018] Starting subtest: unload
[277.950765] Subtest unload: SUCCESS (29.138s)
[278.184200] [155/162] (737s left) i915_module_load (reload)
[278.250114] Starting subtest: reload
[282.434596] Subtest reload: SUCCESS (4.185s)
[282.552311] [156/162] (732s left) gem_lmem_swapping (basic)
[282.637072] Subtest basic: SKIP (0.000s)
[282.729748] [157/162] (732s left) gem_lmem_swapping (parallel-random-engines)
[282.805325] Subtest parallel-random-engines: SKIP (0.000s)
[282.896796] [158/162] (732s left) gem_lmem_swapping (random-engines)
[282.971967] Subtest random-engines: SKIP (0.000s)
[283.062216] [159/162] (732s left) gem_lmem_swapping (verify-random)
[283.138081] Subtest verify-random: SKIP (0.000s)
[283.245233] [160/162] (732s left) i915_pm_rpm (module-reload)
[285.600248] Starting subtest: module-reload
[298.568869] Subtest module-reload: SUCCESS (12.961s)
[298.619131] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[298.621526] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[298.625329] [161/162] (717s left) i915_selftest (live)
[298.696505] Starting subtest: live
[298.699514] Starting dynamic subtest: sanitycheck
[301.331542] Dynamic subtest sanitycheck: SUCCESS (2.625s)
[301.343460] Starting dynamic subtest: uncore
[303.945056] Dynamic subtest uncore: SUCCESS (2.611s)
[303.965469] Starting dynamic subtest: workarounds
[306.766558] Dynamic subtest workarounds: SUCCESS (2.817s)
[306.796405] Starting dynamic subtest: gt_engines
[309.794705] Dynamic subtest gt_engines: SUCCESS (3.024s)
[309.814195] Starting dynamic subtest: gt_timelines
[322.517862] Dynamic subtest gt_timelines: SUCCESS (12.720s)
[322.535412] Starting dynamic subtest: gt_contexts
[325.320925] Dynamic subtest gt_contexts: SUCCESS (2.800s)
[325.342060] Starting dynamic subtest: gt_lrc