[45.856125] Initializing watchdogs
[45.856255]   /dev/watchdog0
[45.887833] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:1916 Intel Skylake (Gen9) Skylake GT2 [HD Graphics 520]
[45.896991] [001/162] (960s left) i915_module_load (load)
[45.975103] Starting subtest: load
[47.784281] Subtest load: SUCCESS (1.809s)
[47.842063] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[47.845262] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[47.849155] [002/162] (958s left) core_auth (basic-auth)
[47.930322] Starting subtest: basic-auth
[47.931316] Subtest basic-auth: SUCCESS (0.001s)
[48.106073] [003/162] (958s left) core_debugfs (read-all-entries)
[48.171927] Starting subtest: read-all-entries
[48.269125] Subtest read-all-entries: SUCCESS (0.098s)
[48.460841] [004/162] (958s left) core_sysfs (read-all-entries)
[48.530129] Starting subtest: read-all-entries
[48.537363] Subtest read-all-entries: SUCCESS (0.008s)
[48.716665] [005/162] (958s left) fbdev (eof)
[48.787972] Starting subtest: eof
[48.789064] Subtest eof: SUCCESS (0.001s)
[48.985848] [006/162] (957s left) fbdev (info)
[49.049995] Starting subtest: info
[49.051400] Subtest info: SUCCESS (0.000s)
[49.233495] [007/162] (957s left) fbdev (nullptr)
[49.301796] Starting subtest: nullptr
[49.302912] Subtest nullptr: SUCCESS (0.000s)
[49.464612] [008/162] (957s left) fbdev (read)
[49.534973] Starting subtest: read
[50.444217] Subtest read: SUCCESS (0.901s)
[50.583979] [009/162] (956s left) fbdev (write)
[50.653064] Starting subtest: write
[51.132588] Subtest write: SUCCESS (0.480s)
[51.326455] [010/162] (955s left) gem_basic (bad-close)
[51.422292] Starting subtest: bad-close
[51.424408] Subtest bad-close: SUCCESS (0.000s)
[51.600248] [011/162] (955s left) gem_basic (create-close)
[51.666467] Starting subtest: create-close
[51.668249] Subtest create-close: SUCCESS (0.000s)
[51.881823] [012/162] (955s left) gem_basic (create-fd-close)
[51.950081] Starting subtest: create-fd-close
[51.951973] Subtest create-fd-close: SUCCESS (0.000s)
[52.156187] [013/162] (955s left) gem_busy (busy@all-engines)
[52.249819] Starting subtest: busy
[52.250967] Starting dynamic subtest: all-engines
[52.316186] Dynamic subtest all-engines: SUCCESS (0.064s)
[52.317256] Subtest busy: SUCCESS (0.065s)
[52.859593] [014/162] (954s left) gem_close_race (basic-process)
[52.957347] Starting subtest: basic-process
[53.031840] Subtest basic-process: SUCCESS (0.072s)
[53.361754] [015/162] (954s left) gem_close_race (basic-threads)
[53.451661] Starting subtest: basic-threads
[54.660088] Subtest basic-threads: SUCCESS (1.174s)
[55.015364] [016/162] (952s left) gem_ctx_create (basic)
[55.102859] Starting subtest: basic
[55.104591] Subtest basic: SUCCESS (0.000s)
[55.422065] [017/162] (952s left) gem_ctx_create (basic-files)
[55.507010] Starting subtest: basic-files
[57.526501] Subtest basic-files: SUCCESS (1.978s)
[57.891569] [018/162] (949s left) gem_ctx_exec (basic)
[57.965313] Starting subtest: basic
[57.971800] Subtest basic: SUCCESS (0.006s)
[58.162486] [019/162] (949s left) gem_exec_basic (basic)
[58.241512] Starting subtest: basic
[58.242491] Starting dynamic subtest: rcs0-smem
[58.244527] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[58.257305] Starting dynamic subtest: bcs0-smem
[58.258311] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[58.270664] Starting dynamic subtest: vcs0-smem
[58.271804] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[58.284180] Starting dynamic subtest: vecs0-smem
[58.285067] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[58.286482] Subtest basic: SUCCESS (0.011s)
[58.584707] [020/162] (949s left) gem_exec_create (basic)
[58.669458] Starting subtest: basic
[58.670535] Starting dynamic subtest: smem
[60.721591] Dynamic subtest smem: SUCCESS (2.024s)
[60.722696] Subtest basic: SUCCESS (2.025s)
[61.042928] [021/162] (946s left) gem_exec_fence (basic-busy)
[61.285673] Starting subtest: basic-busy
[61.286754] Starting dynamic subtest: rcs0
[61.315256] Dynamic subtest rcs0: SUCCESS (0.031s)
[61.328808] Starting dynamic subtest: bcs0
[61.339340] Dynamic subtest bcs0: SUCCESS (0.023s)
[61.351757] Starting dynamic subtest: vcs0
[61.362412] Dynamic subtest vcs0: SUCCESS (0.022s)
[61.375102] Starting dynamic subtest: vecs0
[61.395169] Dynamic subtest vecs0: SUCCESS (0.032s)
[61.396243] Subtest basic-busy: SUCCESS (0.110s)
[62.265202] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[62.269176] [022/162] (945s left) gem_exec_fence (basic-wait)
[62.516546] Starting subtest: basic-wait
[62.518136] Starting dynamic subtest: rcs0
[62.545542] Dynamic subtest rcs0: SUCCESS (0.030s)
[62.562749] Starting dynamic subtest: bcs0
[62.575319] Dynamic subtest bcs0: SUCCESS (0.029s)
[62.588496] Starting dynamic subtest: vcs0
[62.599319] Dynamic subtest vcs0: SUCCESS (0.023s)
[62.612740] Starting dynamic subtest: vecs0
[62.623202] Dynamic subtest vecs0: SUCCESS (0.023s)
[62.624562] Subtest basic-wait: SUCCESS (0.108s)
[63.403483] [023/162] (944s left) gem_exec_fence (basic-await)
[63.643919] Starting subtest: basic-await
[63.645243] Starting dynamic subtest: rcs0
[63.748128] Dynamic subtest rcs0: SUCCESS (0.103s)
[63.763023] Starting dynamic subtest: bcs0
[63.851518] Dynamic subtest bcs0: SUCCESS (0.102s)
[63.874135] Starting dynamic subtest: vcs0
[63.972233] Dynamic subtest vcs0: SUCCESS (0.119s)
[63.988714] Starting dynamic subtest: vecs0
[64.079539] Dynamic subtest vecs0: SUCCESS (0.106s)
[64.080623] Subtest basic-await: SUCCESS (0.433s)
[64.977817] [024/162] (943s left) gem_exec_fence (nb-await)
[65.221582] Starting subtest: nb-await
[65.222712] Starting dynamic subtest: rcs0
[65.293476] Dynamic subtest rcs0: SUCCESS (0.070s)
[65.319389] Starting dynamic subtest: bcs0
[65.376000] Dynamic subtest bcs0: SUCCESS (0.076s)
[65.420976] Starting dynamic subtest: vcs0
[65.473095] Dynamic subtest vcs0: SUCCESS (0.096s)
[65.533383] Starting dynamic subtest: vecs0
[65.578339] Dynamic subtest vecs0: SUCCESS (0.103s)
[65.582362] Subtest nb-await: SUCCESS (0.355s)
[66.446447] [025/162] (941s left) gem_exec_gttfill (basic)
[66.588987] Starting subtest: basic
[72.291074] Subtest basic: SUCCESS (5.682s)
[72.646271] [026/162] (935s left) gem_exec_parallel (engines)
[72.804028] Starting subtest: engines
[72.805026] Starting dynamic subtest: basic
[75.520307] Dynamic subtest basic: SUCCESS (2.690s)
[75.534047] Starting dynamic subtest: contexts
[81.302841] Dynamic subtest contexts: SUCCESS (5.796s)
[81.318182] Starting dynamic subtest: fds
[85.957583] Dynamic subtest fds: SUCCESS (4.629s)
[85.977684] Starting dynamic subtest: userptr
[88.453582] Dynamic subtest userptr: SUCCESS (2.516s)
[88.454880] Subtest engines: SUCCESS (15.633s)
[88.790616] [027/162] (919s left) gem_exec_store (basic)
[88.878182] Starting subtest: basic
[88.934532] Subtest basic: SUCCESS (0.057s)
[89.279313] [028/162] (919s left) gem_flink_basic (bad-flink)
[89.348902] Starting subtest: bad-flink
[89.350052] Subtest bad-flink: SUCCESS (0.000s)
[89.526993] [029/162] (919s left) gem_flink_basic (bad-open)
[89.593198] Starting subtest: bad-open
[89.594094] Subtest bad-open: SUCCESS (0.000s)
[89.780233] [030/162] (919s left) gem_flink_basic (basic)
[89.846584] Starting subtest: basic
[89.847618] Subtest basic: SUCCESS (0.000s)
[90.046338] [031/162] (918s left) gem_flink_basic (double-flink)
[90.111227] Starting subtest: double-flink
[90.112675] Subtest double-flink: SUCCESS (0.000s)
[90.312250] [032/162] (918s left) gem_flink_basic (flink-lifetime)
[90.379102] Starting subtest: flink-lifetime
[90.381251] Subtest flink-lifetime: SUCCESS (0.002s)
[90.585859] [033/162] (918s left) gem_huc_copy (huc-copy)
[90.662299] Starting subtest: huc-copy
[90.664217] Subtest huc-copy: SKIP (0.000s)
[90.890658] [034/162] (918s left) gem_linear_blits (basic)
[91.092156] Starting subtest: basic
[91.107941] Subtest basic: SUCCESS (0.017s)
[91.351925] [035/162] (917s left) gem_mmap (basic)
[91.486015] Starting subtest: basic
[91.490896] Subtest basic: SUCCESS (0.001s)
[91.674102] [036/162] (917s left) gem_mmap_gtt (basic)
[91.743297] Starting subtest: basic
[91.744248] Subtest basic: SUCCESS (0.001s)
[91.908691] [037/162] (917s left) gem_render_linear_blits (basic)
[91.984207] Starting subtest: basic
[92.025630] Subtest basic: SUCCESS (0.041s)
[92.133536] [038/162] (917s left) gem_render_tiled_blits (basic)
[92.208424] Starting subtest: basic
[92.266316] Subtest basic: SUCCESS (0.058s)
[92.366230] [039/162] (917s left) gem_ringfill (basic-all)
[92.642309] Starting subtest: basic-all
[93.814995] Subtest basic-all: SUCCESS (1.163s)
[94.027567] [040/162] (915s left) gem_softpin (allocator-basic)
[94.104171] Starting subtest: allocator-basic
[94.279928] Subtest allocator-basic: SUCCESS (0.168s)
[94.504847] [041/162] (915s left) gem_softpin (allocator-basic-reserve)
[94.581561] Starting subtest: allocator-basic-reserve
[94.756462] Subtest allocator-basic-reserve: SUCCESS (0.166s)
[94.977237] [042/162] (914s left) gem_softpin (safe-alignment)
[95.057370] Starting subtest: safe-alignment
[95.077269] Subtest safe-alignment: SUCCESS (0.019s)
[95.307535] [043/162] (914s left) gem_sync (basic-all)
[95.394539] Starting subtest: basic-all
[97.568032] Subtest basic-all: SUCCESS (2.172s)
[97.932891] [044/162] (911s left) gem_sync (basic-each)
[98.022063] Starting subtest: basic-each
[100.235360] Subtest basic-each: SUCCESS (2.213s)
[100.628266] [045/162] (909s left) gem_tiled_blits (basic)
[100.797131] Starting subtest: basic
[100.818543] Subtest basic: SUCCESS (0.022s)
[101.067074] [046/162] (909s left) gem_tiled_fence_blits (basic)
[101.297461] Starting subtest: basic
[101.310614] Subtest basic: SUCCESS (0.014s)
[101.548991] [047/162] (908s left) gem_tiled_pread_basic
[102.013631] [048/162] (908s left) gem_wait (busy@all-engines)
[102.105935] Starting subtest: busy
[102.107076] Starting dynamic subtest: all-engines
[102.651368] Dynamic subtest all-engines: SUCCESS (0.545s)
[102.652342] Subtest busy: SUCCESS (0.546s)
[103.012930] [049/162] (907s left) gem_wait (wait@all-engines)
[103.102963] Starting subtest: wait
[103.103999] Starting dynamic subtest: all-engines
[104.148645] Dynamic subtest all-engines: SUCCESS (1.043s)
[104.151758] Subtest wait: SUCCESS (1.045s)
[104.526884] [050/162] (905s left) i915_getparams_basic (basic-eu-total)
[104.592990] Starting subtest: basic-eu-total
[104.595028] Subtest basic-eu-total: SUCCESS (0.000s)
[104.758698] [051/162] (905s left) i915_getparams_basic (basic-subslice-total)
[104.828268] Starting subtest: basic-subslice-total
[104.830194] Subtest basic-subslice-total: SUCCESS (0.000s)
[105.018591] [052/162] (905s left) i915_hangman (error-state-basic)
[105.105236] Starting subtest: error-state-basic
[105.114217] Subtest error-state-basic: SUCCESS (0.009s)
[105.265453] [053/162] (905s left) i915_pciid
[105.517021] [054/162] (905s left) intel_hwmon (hwmon-read)
[105.585191] Subtest hwmon-read: SKIP (0.000s)
[105.676108] [055/162] (905s left) intel_hwmon (hwmon-write)
[105.743567] Subtest hwmon-write: SKIP (0.000s)
[105.825546] [056/162] (905s left) kms_addfb_basic (addfb25-4-tiled)
[105.957445] Starting subtest: addfb25-4-tiled
[105.958518] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[106.167286] [057/162] (904s left) kms_addfb_basic (addfb25-bad-modifier)
[106.292155] Starting subtest: addfb25-bad-modifier
[106.293127] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[106.489056] [058/162] (904s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[106.618176] Starting subtest: addfb25-framebuffer-vs-set-tiling
[106.619116] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[106.801847] [059/162] (904s left) kms_addfb_basic (addfb25-modifier-no-flag)
[106.922399] Starting subtest: addfb25-modifier-no-flag
[106.923371] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[107.121258] [060/162] (904s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[107.245106] Starting subtest: addfb25-x-tiled-legacy
[107.246058] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[107.453395] [061/162] (903s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[107.579117] Starting subtest: addfb25-x-tiled-mismatch-legacy
[107.580129] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[107.778930] [062/162] (903s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[107.908244] Starting subtest: addfb25-yf-tiled-legacy
[107.909132] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[108.096900] [063/162] (903s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[108.224124] Starting subtest: addfb25-y-tiled-legacy
[108.225017] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[108.435944] [064/162] (903s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[108.559227] Starting subtest: addfb25-y-tiled-small-legacy
[108.560122] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[108.747954] [065/162] (902s left) kms_addfb_basic (bad-pitch-0)
[108.820863] Starting subtest: bad-pitch-0
[108.821853] Subtest bad-pitch-0: SUCCESS (0.000s)
[109.079747] [066/162] (902s left) kms_addfb_basic (bad-pitch-1024)
[109.147548] Starting subtest: bad-pitch-1024
[109.148561] Subtest bad-pitch-1024: SUCCESS (0.000s)
[109.402190] [067/162] (902s left) kms_addfb_basic (bad-pitch-128)
[109.469442] Starting subtest: bad-pitch-128
[109.470506] Subtest bad-pitch-128: SUCCESS (0.000s)
[109.731195] [068/162] (902s left) kms_addfb_basic (bad-pitch-256)
[109.798491] Starting subtest: bad-pitch-256
[109.799441] Subtest bad-pitch-256: SUCCESS (0.000s)
[110.035875] [069/162] (901s left) kms_addfb_basic (bad-pitch-32)
[110.103337] Starting subtest: bad-pitch-32
[110.104305] Subtest bad-pitch-32: SUCCESS (0.000s)
[110.358250] [070/162] (901s left) kms_addfb_basic (bad-pitch-63)
[110.426309] Starting subtest: bad-pitch-63
[110.427333] Subtest bad-pitch-63: SUCCESS (0.000s)
[110.684083] [071/162] (901s left) kms_addfb_basic (bad-pitch-65536)
[110.751515] Starting subtest: bad-pitch-65536
[110.752511] Subtest bad-pitch-65536: SUCCESS (0.000s)
[111.007706] [072/162] (901s left) kms_addfb_basic (bad-pitch-999)
[111.076405] Starting subtest: bad-pitch-999
[111.077402] Subtest bad-pitch-999: SUCCESS (0.000s)
[111.342165] [073/162] (900s left) kms_addfb_basic (basic)
[111.409384] Starting subtest: basic
[111.410370] Subtest basic: SUCCESS (0.000s)
[111.697519] [074/162] (900s left) kms_addfb_basic (basic-x-tiled-legacy)
[111.765110] Starting subtest: basic-x-tiled-legacy
[111.766049] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[112.019347] [075/162] (900s left) kms_addfb_basic (basic-y-tiled-legacy)
[112.088871] Starting subtest: basic-y-tiled-legacy
[112.089864] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[112.335270] [076/162] (900s left) kms_addfb_basic (bo-too-small)
[112.454121] Starting subtest: bo-too-small
[112.455132] Subtest bo-too-small: SUCCESS (0.000s)
[112.649946] [077/162] (900s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[112.770228] Starting subtest: bo-too-small-due-to-tiling
[112.771180] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[112.982725] [078/162] (899s left) kms_addfb_basic (clobberred-modifier)
[113.048504] Starting subtest: clobberred-modifier
[113.049482] Subtest clobberred-modifier: SUCCESS (0.000s)
[113.309272] [079/162] (899s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[113.378374] Starting subtest: framebuffer-vs-set-tiling
[113.379322] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[113.618373] [080/162] (899s left) kms_addfb_basic (invalid-get-prop)
[113.687100] Starting subtest: invalid-get-prop
[113.688055] Subtest invalid-get-prop: SUCCESS (0.000s)
[113.934200] [081/162] (899s left) kms_addfb_basic (invalid-get-prop-any)
[114.000390] Starting subtest: invalid-get-prop-any
[114.001396] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[114.273372] [082/162] (898s left) kms_addfb_basic (invalid-set-prop)
[114.341557] Starting subtest: invalid-set-prop
[114.342580] Subtest invalid-set-prop: SUCCESS (0.000s)
[114.600141] [083/162] (898s left) kms_addfb_basic (invalid-set-prop-any)
[114.667232] Starting subtest: invalid-set-prop-any
[114.668157] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[114.947612] [084/162] (898s left) kms_addfb_basic (no-handle)
[115.014221] Starting subtest: no-handle
[115.015189] Subtest no-handle: SUCCESS (0.000s)
[115.264038] [085/162] (898s left) kms_addfb_basic (size-max)
[115.394115] Starting subtest: size-max
[115.394977] Subtest size-max: SUCCESS (0.000s)
[115.583607] [086/162] (897s left) kms_addfb_basic (small-bo)
[115.704122] Starting subtest: small-bo
[115.704960] Subtest small-bo: SUCCESS (0.000s)
[115.910832] [087/162] (897s left) kms_addfb_basic (tile-pitch-mismatch)
[115.979270] Starting subtest: tile-pitch-mismatch
[115.980345] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[116.237452] [088/162] (897s left) kms_addfb_basic (too-high)
[116.356779] Starting subtest: too-high
[116.359559] Subtest too-high: SUCCESS (0.001s)
[116.572985] [089/162] (897s left) kms_addfb_basic (too-wide)
[116.702093] Starting subtest: too-wide
[116.702943] Subtest too-wide: SUCCESS (0.000s)
[116.911213] [090/162] (896s left) kms_addfb_basic (unused-handle)
[116.977586] Starting subtest: unused-handle
[116.978770] Subtest unused-handle: SUCCESS (0.000s)
[117.234638] [091/162] (896s left) kms_addfb_basic (unused-modifier)
[117.301404] Starting subtest: unused-modifier
[117.302290] Subtest unused-modifier: SUCCESS (0.000s)
[117.572692] [092/162] (896s left) kms_addfb_basic (unused-offsets)
[117.640517] Starting subtest: unused-offsets
[117.641498] Subtest unused-offsets: SUCCESS (0.000s)
[117.918388] [093/162] (896s left) kms_addfb_basic (unused-pitches)
[117.985194] Starting subtest: unused-pitches
[117.986380] Subtest unused-pitches: SUCCESS (0.000s)
[118.246148] [094/162] (895s left) kms_busy (basic)
[118.379348] Starting subtest: basic
[118.380346] Starting dynamic subtest: flip
[119.229319] Dynamic subtest flip: SUCCESS (0.847s)
[119.265049] Starting dynamic subtest: modeset
[123.009683] Dynamic subtest modeset: SUCCESS (3.778s)
[123.012262] Subtest basic: SUCCESS (4.630s)
[123.998883] [095/162] (890s left) kms_prop_blob (basic)
[124.066119] Starting subtest: basic
[124.067217] Subtest basic: SUCCESS (0.000s)
[124.255233] [096/162] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[124.380137] Starting subtest: basic-busy-flip-before-cursor-atomic
[124.381957] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[124.544862] [097/162] (889s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[124.670214] Starting subtest: basic-busy-flip-before-cursor-legacy
[124.672046] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[124.841858] [098/162] (889s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[124.962206] Starting subtest: basic-flip-after-cursor-atomic
[126.290842] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.327s)
[127.259652] [099/162] (887s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[127.379331] Starting subtest: basic-flip-after-cursor-legacy
[128.661040] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.277s)
[129.602802] [100/162] (885s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[129.727407] Starting subtest: basic-flip-after-cursor-varying-size
[131.063575] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.333s)
[132.024635] [101/162] (882s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[132.147190] Starting subtest: basic-flip-before-cursor-atomic
[133.389265] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.238s)
[134.387854] [102/162] (880s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[134.506244] Starting subtest: basic-flip-before-cursor-legacy
[135.833754] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.324s)
[136.798448] [103/162] (878s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[136.922156] Starting subtest: basic-flip-before-cursor-varying-size
[138.244220] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.318s)
[139.164899] [104/162] (875s left) kms_dsc (dsc-basic)
[139.284987] Subtest dsc-basic: SKIP (0.000s)
[139.373663] [105/162] (875s left) kms_flip (basic-flip-vs-dpms)
[139.493537] Starting subtest: basic-flip-vs-dpms
[139.495736] Starting dynamic subtest: A-eDP1
[142.121467] Dynamic subtest A-eDP1: SUCCESS (2.623s)
[142.186567] Starting dynamic subtest: B-eDP1
[144.503160] Dynamic subtest B-eDP1: SUCCESS (2.375s)
[144.577072] Starting dynamic subtest: C-eDP1
[146.820241] Dynamic subtest C-eDP1: SUCCESS (2.307s)
[146.829521] Subtest basic-flip-vs-dpms: SUCCESS (7.335s)
[147.846426] [106/162] (867s left) kms_flip (basic-flip-vs-modeset)
[147.968424] Starting subtest: basic-flip-vs-modeset
[147.970645] Starting dynamic subtest: A-eDP1
[150.679076] Dynamic subtest A-eDP1: SUCCESS (2.704s)
[150.748956] Starting dynamic subtest: B-eDP1
[152.999222] Dynamic subtest B-eDP1: SUCCESS (2.311s)
[153.069537] Starting dynamic subtest: C-eDP1
[155.321595] Dynamic subtest C-eDP1: SUCCESS (2.316s)
[155.337223] Subtest basic-flip-vs-modeset: SUCCESS (7.363s)
[156.244596] [107/162] (859s left) kms_flip (basic-flip-vs-wf_vblank)
[156.369215] Starting subtest: basic-flip-vs-wf_vblank
[156.372870] Starting dynamic subtest: A-eDP1
[158.557339] Dynamic subtest A-eDP1: SUCCESS (2.185s)
[158.576177] Starting dynamic subtest: B-eDP1
[160.769943] Dynamic subtest B-eDP1: SUCCESS (2.211s)
[160.792953] Starting dynamic subtest: C-eDP1
[162.953511] Dynamic subtest C-eDP1: SUCCESS (2.181s)
[162.957351] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.588s)
[164.309788] [108/162] (851s left) kms_flip (basic-plain-flip)
[164.435695] Starting subtest: basic-plain-flip
[164.438216] Starting dynamic subtest: A-eDP1
[166.321783] Dynamic subtest A-eDP1: SUCCESS (1.882s)
[166.393742] Starting dynamic subtest: B-eDP1
[168.245884] Dynamic subtest B-eDP1: SUCCESS (1.913s)
[168.312606] Starting dynamic subtest: C-eDP1
[170.249922] Dynamic subtest C-eDP1: SUCCESS (1.992s)
[170.259374] Subtest basic-plain-flip: SUCCESS (5.823s)
[171.751777] [109/162] (843s left) kms_force_connector_basic (force-connector-state)
[171.861989] Starting subtest: force-connector-state
[172.449353] Subtest force-connector-state: SUCCESS (0.586s)
[173.790131] [110/162] (841s left) kms_force_connector_basic (force-edid)
[173.888148] Starting subtest: force-edid
[173.928315] Subtest force-edid: SUCCESS (0.040s)
[174.183822] [111/162] (841s left) kms_force_connector_basic (force-load-detect)
[174.279857] Starting subtest: force-load-detect
[174.281759] Subtest force-load-detect: SKIP (0.000s)
[174.521063] [112/162] (841s left) kms_force_connector_basic (prune-stale-modes)
[174.620742] Starting subtest: prune-stale-modes
[174.661173] Subtest prune-stale-modes: SUCCESS (0.040s)
[174.919522] [113/162] (841s left) kms_frontbuffer_tracking (basic)
[175.049365] Starting subtest: basic
[178.692164] Subtest basic: SUCCESS (3.643s)
[178.936334] [114/162] (837s left) kms_hdmi_inject (inject-audio)
[179.334564] Starting subtest: inject-audio
[179.464861] Subtest inject-audio: SUCCESS (0.130s)
[180.328133] [115/162] (835s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[180.453178] Starting subtest: compare-crc-sanitycheck-xr24
[180.456060] Starting dynamic subtest: pipe-A-eDP-1
[181.186564] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.727s)
[181.268495] Starting dynamic subtest: pipe-B-eDP-1
[182.680405] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.476s)
[182.754893] Starting dynamic subtest: pipe-C-eDP-1
[184.172934] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.474s)
[184.176313] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.717s)
[185.141502] [116/162] (831s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[185.267675] Starting subtest: compare-crc-sanitycheck-nv12
[185.270236] Starting dynamic subtest: pipe-A-eDP-1
[185.271986] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[185.289508] Starting dynamic subtest: pipe-B-eDP-1
[185.292321] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[185.307695] Starting dynamic subtest: pipe-C-eDP-1
[185.309483] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[185.311244] Subtest compare-crc-sanitycheck-nv12: SKIP (0.014s)
[185.470148] [117/162] (830s left) kms_pipe_crc_basic (hang-read-crc)
[185.589298] Starting subtest: hang-read-crc
[185.590480] Starting dynamic subtest: pipe-A-eDP-1
[188.099578] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.506s)
[188.183748] Starting dynamic subtest: pipe-B-eDP-1
[191.254625] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.140s)
[191.341287] Starting dynamic subtest: pipe-C-eDP-1
[194.465282] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.190s)
[194.467255] Subtest hang-read-crc: SUCCESS (8.876s)
[195.444483] [118/162] (821s left) kms_pipe_crc_basic (nonblocking-crc)
[195.568045] Starting subtest: nonblocking-crc
[195.570794] Starting dynamic subtest: pipe-A-eDP-1
[196.581690] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.006s)
[196.657422] Starting dynamic subtest: pipe-B-eDP-1
[198.375581] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.777s)
[198.471999] Starting dynamic subtest: pipe-C-eDP-1
[200.057901] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.665s)
[200.060488] Subtest nonblocking-crc: SUCCESS (4.491s)
[201.102390] [119/162] (815s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[201.225389] Starting subtest: nonblocking-crc-frame-sequence
[201.226799] Starting dynamic subtest: pipe-A-eDP-1
[202.214616] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.981s)
[202.298126] Starting dynamic subtest: pipe-B-eDP-1
[203.974519] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.745s)
[204.052906] Starting dynamic subtest: pipe-C-eDP-1
[205.670984] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.679s)
[205.673686] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.447s)
[206.604768] [120/162] (810s left) kms_pipe_crc_basic (read-crc)
[206.746053] Starting subtest: read-crc
[206.748968] Starting dynamic subtest: pipe-A-eDP-1
[207.680687] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.928s)
[207.761315] Starting dynamic subtest: pipe-B-eDP-1
[209.247823] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.546s)
[209.334096] Starting dynamic subtest: pipe-C-eDP-1
[210.820206] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.557s)
[210.822513] Subtest read-crc: SUCCESS (4.074s)
[211.812487] [121/162] (804s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[211.939057] Starting subtest: read-crc-frame-sequence
[211.940515] Starting dynamic subtest: pipe-A-eDP-1
[212.845301] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.894s)
[212.929194] Starting dynamic subtest: pipe-B-eDP-1
[214.358188] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.496s)
[214.442078] Starting dynamic subtest: pipe-C-eDP-1
[215.915969] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.537s)
[215.918833] Subtest read-crc-frame-sequence: SUCCESS (3.975s)
[216.826447] [122/162] (800s left) kms_pm_backlight (basic-brightness)
[216.954338] Starting subtest: basic-brightness
[217.006399] Starting dynamic subtest: eDP-1
[217.596517] Dynamic subtest eDP-1: SUCCESS (0.590s)
[217.609976] Subtest basic-brightness: SUCCESS (0.645s)
[218.546206] [123/162] (798s left) kms_pm_rpm (basic-pci-d3-state)
[220.288127] Starting subtest: basic-pci-d3-state
[220.920470] Subtest basic-pci-d3-state: SUCCESS (0.634s)
[221.186029] [124/162] (795s left) kms_pm_rpm (basic-rte)
[221.251372] Starting subtest: basic-rte
[223.497969] Subtest basic-rte: SUCCESS (2.243s)
[224.942765] [125/162] (792s left) kms_psr (psr-primary-page-flip)
[225.063956] Starting subtest: psr-primary-page-flip
[225.066886] Starting dynamic subtest: eDP-1
[227.204248] Dynamic subtest eDP-1: SUCCESS (2.131s)
[227.206566] Subtest psr-primary-page-flip: SUCCESS (2.141s)
[228.261507] [126/162] (789s left) kms_psr (psr-cursor-plane-move)
[228.387619] Starting subtest: psr-cursor-plane-move
[228.391270] Starting dynamic subtest: eDP-1
[230.456393] Dynamic subtest eDP-1: SUCCESS (2.062s)
[230.464637] Subtest psr-cursor-plane-move: SUCCESS (2.071s)
[231.448099] [127/162] (785s left) kms_psr (psr-sprite-plane-onoff)
[231.651722] Starting subtest: psr-sprite-plane-onoff
[231.655674] Starting dynamic subtest: eDP-1
[233.763915] Dynamic subtest eDP-1: SUCCESS (2.102s)
[233.766355] Subtest psr-sprite-plane-onoff: SUCCESS (2.109s)
[234.768847] [128/162] (782s left) kms_psr (psr-primary-mmap-gtt)
[234.897985] Starting subtest: psr-primary-mmap-gtt
[234.899021] Starting dynamic subtest: eDP-1
[236.804785] Dynamic subtest eDP-1: SUCCESS (1.902s)
[236.807439] Subtest psr-primary-mmap-gtt: SUCCESS (1.910s)
[237.737378] [129/162] (779s left) kms_setmode (basic-clone-single-crtc)
[237.807110] Starting subtest: basic-clone-single-crtc
[237.810167] Subtest basic-clone-single-crtc: SKIP (0.000s)
[238.002819] [130/162] (779s left) i915_pm_rps (basic-api)
[238.085424] Starting subtest: basic-api
[238.091662] Subtest basic-api: SUCCESS (0.006s)
[238.272045] [131/162] (779s left) prime_self_import (basic-llseek-bad)
[238.337890] Starting subtest: basic-llseek-bad
[238.343252] Subtest basic-llseek-bad: SUCCESS (0.003s)
[238.566517] [132/162] (779s left) prime_self_import (basic-llseek-size)
[238.632849] Starting subtest: basic-llseek-size
[238.638263] Subtest basic-llseek-size: SUCCESS (0.005s)
[238.838337] [133/162] (779s left) prime_self_import (basic-with_fd_dup)
[238.904206] Starting subtest: basic-with_fd_dup
[238.909133] Subtest basic-with_fd_dup: SUCCESS (0.004s)
[239.120961] [134/162] (778s left) prime_self_import (basic-with_one_bo)
[239.189723] Starting subtest: basic-with_one_bo
[239.191805] Subtest basic-with_one_bo: SUCCESS (0.005s)
[239.374078] [135/162] (778s left) prime_self_import (basic-with_one_bo_two_files)
[239.440350] Starting subtest: basic-with_one_bo_two_files
[239.445125] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[239.641616] [136/162] (778s left) prime_self_import (basic-with_two_bos)
[239.707936] Starting subtest: basic-with_two_bos
[239.712678] Subtest basic-with_two_bos: SUCCESS (0.005s)
[239.923689] [137/162] (778s left) prime_vgem (basic-fence-flip)
[240.005256] Starting subtest: basic-fence-flip
[240.516292] Subtest basic-fence-flip: SUCCESS (0.505s)
[240.741877] [138/162] (777s left) prime_vgem (basic-fence-mmap)
[240.821881] Starting subtest: basic-fence-mmap
[240.856220] Subtest basic-fence-mmap: SUCCESS (0.034s)
[241.053653] [139/162] (777s left) prime_vgem (basic-fence-read)
[241.150313] Starting subtest: basic-fence-read
[241.228438] Subtest basic-fence-read: SUCCESS (0.078s)
[241.415909] [140/162] (777s left) prime_vgem (basic-gtt)
[241.505055] Starting subtest: basic-gtt
[241.520795] Subtest basic-gtt: SUCCESS (0.016s)
[241.735071] [141/162] (776s left) prime_vgem (basic-read)
[241.823955] Starting subtest: basic-read
[241.861956] Subtest basic-read: SUCCESS (0.038s)
[242.071208] [142/162] (776s left) prime_vgem (basic-write)
[242.169403] Starting subtest: basic-write
[242.209608] Subtest basic-write: SUCCESS (0.040s)
[242.411442] [143/162] (776s left) vgem_basic (setversion)
[242.480873] Starting subtest: setversion
[242.482882] Subtest setversion: SUCCESS (0.000s)
[242.650907] [144/162] (776s left) vgem_basic (create)
[242.731554] Starting subtest: create
[242.732462] Subtest create: SUCCESS (0.000s)
[242.915062] [145/162] (776s left) vgem_basic (debugfs)
[242.998135] Starting subtest: debugfs
[242.999136] Subtest debugfs: SUCCESS (0.001s)
[243.180593] [146/162] (775s left) vgem_basic (dmabuf-export)
[243.264328] Starting subtest: dmabuf-export
[243.268212] Subtest dmabuf-export: SUCCESS (0.004s)
[243.477906] [147/162] (775s left) vgem_basic (dmabuf-fence)
[243.566045] Starting subtest: dmabuf-fence
[243.567117] Subtest dmabuf-fence: SUCCESS (0.001s)
[243.717090] [148/162] (775s left) vgem_basic (dmabuf-fence-before)
[243.799122] Starting subtest: dmabuf-fence-before
[243.800200] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[243.970829] [149/162] (775s left) vgem_basic (dmabuf-mmap)
[244.048634] Starting subtest: dmabuf-mmap
[244.061525] Subtest dmabuf-mmap: SUCCESS (0.015s)
[244.238719] [150/162] (775s left) vgem_basic (mmap)
[244.315277] Starting subtest: mmap
[244.323399] Subtest mmap: SUCCESS (0.010s)
[244.490282] [151/162] (775s left) vgem_basic (second-client)
[244.566080] Starting subtest: second-client
[244.596146] Subtest second-client: SUCCESS (0.032s)
[244.776029] [152/162] (774s left) vgem_basic (sysfs)
[244.848445] Starting subtest: sysfs
[244.849458] Subtest sysfs: SUCCESS (0.001s)
[245.027331] [153/162] (774s left) core_hotunplug (unbind-rebind)
[245.166617] Starting subtest: unbind-rebind
[250.328516] Subtest unbind-rebind: SUCCESS (5.163s)
[250.406932] [154/162] (769s left) vgem_basic (unload)
[250.473221] Starting subtest: unload
[279.633449] Subtest unload: SUCCESS (29.116s)
[279.842759] [155/162] (740s left) i915_module_load (reload)
[279.909549] Starting subtest: reload
[284.045025] Subtest reload: SUCCESS (4.134s)
[284.141593] [156/162] (735s left) gem_lmem_swapping (basic)
[284.225194] Subtest basic: SKIP (0.000s)
[284.308490] [157/162] (735s left) gem_lmem_swapping (parallel-random-engines)
[284.382969] Subtest parallel-random-engines: SKIP (0.000s)
[284.474651] [158/162] (735s left) gem_lmem_swapping (random-engines)
[284.548502] Subtest random-engines: SKIP (0.000s)
[284.642046] [159/162] (735s left) gem_lmem_swapping (verify-random)
[284.716976] Subtest verify-random: SKIP (0.000s)
[284.808273] [160/162] (735s left) i915_pm_rpm (module-reload)
[287.136225] Starting subtest: module-reload
[300.076889] Subtest module-reload: SUCCESS (12.941s)
[300.125303] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[300.127442] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[300.132178] [161/162] (720s left) i915_selftest (live)
[300.203972] Starting subtest: live
[300.207039] Starting dynamic subtest: sanitycheck
[302.904812] Dynamic subtest sanitycheck: SUCCESS (2.691s)
[302.923465] Starting dynamic subtest: uncore
[305.671771] Dynamic subtest uncore: SUCCESS (2.764s)
[305.687062] Starting dynamic subtest: workarounds
[308.760122] Dynamic subtest workarounds: SUCCESS (3.085s)
[308.788725] Starting dynamic subtest: gt_engines
[311.856639] Dynamic subtest gt_engines: SUCCESS (3.094s)
[311.872537] Starting dynamic subtest: gt_timelines
[324.651930] Dynamic subtest gt_timelines: SUCCESS (12.793s)
[324.669215] Starting dynamic subtest: gt_contexts
[327.484988] Dynamic subtest gt_contexts: SUCCESS (2.830s)
[327.508512] Starting dynamic subtest: gt_lrc
[332.479882] Dynamic subtest gt_lrc: SUCCESS (4.992s)
[332.500346] Starting dynamic subtest: gt_mocs
[335.319693] Dynamic subtest gt_mocs: SUCCESS (2.837s)
[335.332603] Starting dynamic subtest: gt_pm
[345.018239] Dynamic subtest gt_pm: SUCCESS (9.696s)
[345.041967] Starting dynamic subtest: gt_heartbeat
[347.812418] Dynamic subtest gt_heartbeat: SUCCESS (2.791s)
[347.840251] Starting dynamic subtest: gt_tlb
[352.151909] Dynamic subtest gt_tlb: SUCCESS (4.336s)
[352.172589] Starting dynamic subtest: requests
[360.864943] Dynamic subtest requests: SUCCESS (8.710s)
[360.878316] Starting dynamic subtest: migrate
[366.155524] Dynamic subtest migrate: SUCCESS (5.288s)
[366.171942] Starting dynamic subtest: active