[27.007677] Initializing watchdogs [27.007803] /dev/watchdog0 [27.022773] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:591d Intel Kabylake (Gen9) HD Graphics P630 [27.030179] [001/162] (960s left) i915_module_load (load) [27.095216] Starting subtest: load [29.128481] Subtest load: SUCCESS (2.030s) [29.160279] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [29.161481] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [29.166230] [002/162] (958s left) core_auth (basic-auth) [29.227451] Starting subtest: basic-auth [29.230531] Subtest basic-auth: SUCCESS (0.001s) [29.268809] [003/162] (958s left) core_debugfs (read-all-entries) [29.319280] Starting subtest: read-all-entries [29.322506] Subtest read-all-entries: SUCCESS (0.002s) [29.363765] [004/162] (958s left) core_sysfs (read-all-entries) [29.413050] Starting subtest: read-all-entries [29.416151] Subtest read-all-entries: SUCCESS (0.003s) [29.454212] [005/162] (958s left) fbdev (eof) [29.508729] Subtest eof: SKIP (0.000s) [29.545981] [006/162] (958s left) fbdev (info) [29.601068] Subtest info: SKIP (0.000s) [29.638190] [007/162] (958s left) fbdev (nullptr) [29.692414] Subtest nullptr: SKIP (0.000s) [29.729754] [008/162] (958s left) fbdev (read) [29.783622] Subtest read: SKIP (0.000s) [29.821808] [009/162] (958s left) fbdev (write) [29.875708] Subtest write: SKIP (0.000s) [29.915063] [010/162] (957s left) gem_basic (bad-close) [29.965979] Starting subtest: bad-close [29.972989] Subtest bad-close: SUCCESS (0.000s) [30.016064] [011/162] (957s left) gem_basic (create-close) [30.067307] Starting subtest: create-close [30.073844] Subtest create-close: SUCCESS (0.000s) [30.127729] [012/162] (957s left) gem_basic (create-fd-close) [30.177070] Starting subtest: create-fd-close [30.183816] Subtest create-fd-close: SUCCESS (0.000s) [30.224830] [013/162] (957s left) gem_busy (busy@all-engines) [30.291459] Starting subtest: busy [30.294515] Starting dynamic subtest: all-engines [30.332109] Dynamic subtest all-engines: SUCCESS (0.041s) [30.335268] Subtest busy: SUCCESS (0.041s) [30.646797] [014/162] (957s left) gem_close_race (basic-process) [30.708236] Starting subtest: basic-process [30.767712] Subtest basic-process: SUCCESS (0.061s) [30.918940] [015/162] (957s left) gem_close_race (basic-threads) [30.976546] Starting subtest: basic-threads [32.259644] Subtest basic-threads: SUCCESS (1.283s) [32.413302] [016/162] (955s left) gem_ctx_create (basic) [32.471604] Starting subtest: basic [32.474616] Subtest basic: SUCCESS (0.000s) [32.616097] [017/162] (955s left) gem_ctx_create (basic-files) [32.670528] Starting subtest: basic-files [34.681189] Subtest basic-files: SUCCESS (2.011s) [35.284872] [018/162] (953s left) gem_ctx_exec (basic) [35.743548] Starting subtest: basic [35.807980] Subtest basic: SUCCESS (0.005s) [36.185290] [019/162] (952s left) gem_exec_basic (basic) [36.238227] Starting subtest: basic [36.241176] Starting dynamic subtest: rcs0-smem [36.244150] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [36.254189] Starting dynamic subtest: bcs0-smem [36.257552] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [36.267620] Starting dynamic subtest: vcs0-smem [36.270990] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [36.281720] Starting dynamic subtest: vecs0-smem [36.284977] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [36.288367] Subtest basic: SUCCESS (0.005s) [36.416379] [020/162] (952s left) gem_exec_create (basic) [36.472435] Starting subtest: basic [36.475499] Starting dynamic subtest: smem [38.517521] Dynamic subtest smem: SUCCESS (2.045s) [38.520780] Subtest basic: SUCCESS (2.045s) [38.666360] [021/162] (950s left) gem_exec_fence (basic-busy) [38.838201] Starting subtest: basic-busy [38.841263] Starting dynamic subtest: rcs0 [38.867234] Dynamic subtest rcs0: SUCCESS (0.032s) [38.885140] Starting dynamic subtest: bcs0 [38.905049] Dynamic subtest bcs0: SUCCESS (0.037s) [38.916323] Starting dynamic subtest: vcs0 [38.942370] Dynamic subtest vcs0: SUCCESS (0.036s) [38.962146] Starting dynamic subtest: vecs0 [38.977150] Dynamic subtest vecs0: SUCCESS (0.035s) [38.980353] Subtest basic-busy: SUCCESS (0.142s) [39.498772] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [39.502887] [022/162] (949s left) gem_exec_fence (basic-wait) [39.680691] Starting subtest: basic-wait [39.683792] Starting dynamic subtest: rcs0 [39.706279] Dynamic subtest rcs0: SUCCESS (0.025s) [39.717323] Starting dynamic subtest: bcs0 [39.732059] Dynamic subtest bcs0: SUCCESS (0.026s) [39.743276] Starting dynamic subtest: vcs0 [39.763466] Dynamic subtest vcs0: SUCCESS (0.031s) [39.778123] Starting dynamic subtest: vecs0 [39.791509] Dynamic subtest vecs0: SUCCESS (0.028s) [39.794826] Subtest basic-wait: SUCCESS (0.111s) [40.296194] [023/162] (948s left) gem_exec_fence (basic-await) [40.467378] Starting subtest: basic-await [40.470482] Starting dynamic subtest: rcs0 [40.543574] Dynamic subtest rcs0: SUCCESS (0.076s) [40.562384] Starting dynamic subtest: bcs0 [40.633936] Dynamic subtest bcs0: SUCCESS (0.090s) [40.652939] Starting dynamic subtest: vcs0 [40.724505] Dynamic subtest vcs0: SUCCESS (0.090s) [40.743414] Starting dynamic subtest: vecs0 [40.814621] Dynamic subtest vecs0: SUCCESS (0.089s) [40.818296] Subtest basic-await: SUCCESS (0.347s) [41.312870] [024/162] (947s left) gem_exec_fence (nb-await) [41.486044] Starting subtest: nb-await [41.489325] Starting dynamic subtest: rcs0 [41.550372] Dynamic subtest rcs0: SUCCESS (0.064s) [41.570334] Starting dynamic subtest: bcs0 [41.617026] Dynamic subtest bcs0: SUCCESS (0.066s) [41.636880] Starting dynamic subtest: vcs0 [41.682771] Dynamic subtest vcs0: SUCCESS (0.065s) [41.702911] Starting dynamic subtest: vecs0 [41.748616] Dynamic subtest vecs0: SUCCESS (0.065s) [41.752280] Subtest nb-await: SUCCESS (0.263s) [42.247151] [025/162] (946s left) gem_exec_gttfill (basic) [42.308551] Starting subtest: basic [45.622066] Subtest basic: SUCCESS (3.316s) [45.789482] [026/162] (943s left) gem_exec_parallel (engines) [45.917299] Starting subtest: engines [45.920493] Starting dynamic subtest: basic [47.614938] Dynamic subtest basic: SUCCESS (1.748s) [47.625561] Starting dynamic subtest: contexts [50.319703] Dynamic subtest contexts: SUCCESS (2.785s) [50.330533] Starting dynamic subtest: fds [52.312185] Dynamic subtest fds: SUCCESS (2.036s) [52.323167] Starting dynamic subtest: userptr [54.748484] Dynamic subtest userptr: SUCCESS (2.475s) [54.751676] Subtest engines: SUCCESS (9.046s) [54.916206] [027/162] (934s left) gem_exec_store (basic) [54.982747] Starting subtest: basic [55.033604] Subtest basic: SUCCESS (0.054s) [55.192226] [028/162] (934s left) gem_flink_basic (bad-flink) [55.243448] Starting subtest: bad-flink [55.246458] Subtest bad-flink: SUCCESS (0.000s) [55.289347] [029/162] (934s left) gem_flink_basic (bad-open) [55.337527] Starting subtest: bad-open [55.340623] Subtest bad-open: SUCCESS (0.000s) [55.382762] [030/162] (934s left) gem_flink_basic (basic) [55.430334] Starting subtest: basic [55.433669] Subtest basic: SUCCESS (0.000s) [55.494614] [031/162] (933s left) gem_flink_basic (double-flink) [55.540364] Starting subtest: double-flink [55.543522] Subtest double-flink: SUCCESS (0.000s) [55.601327] [032/162] (933s left) gem_flink_basic (flink-lifetime) [55.649123] Starting subtest: flink-lifetime [55.652312] Subtest flink-lifetime: SUCCESS (0.001s) [55.714738] [033/162] (933s left) gem_huc_copy (huc-copy) [55.766152] Starting subtest: huc-copy [55.772467] Subtest huc-copy: SKIP (0.000s) [55.839307] [034/162] (933s left) gem_linear_blits (basic) [55.957409] Starting subtest: basic [55.966623] Subtest basic: SUCCESS (0.009s) [56.087756] [035/162] (933s left) gem_mmap (basic) [56.211685] Starting subtest: basic [56.226775] Subtest basic: SUCCESS (0.000s) [56.284673] [036/162] (933s left) gem_mmap_gtt (basic) [56.335087] Starting subtest: basic [56.338348] Subtest basic: SUCCESS (0.001s) [56.392135] [037/162] (933s left) gem_render_linear_blits (basic) [56.443208] Starting subtest: basic [56.472206] Subtest basic: SUCCESS (0.031s) [56.531331] [038/162] (933s left) gem_render_tiled_blits (basic) [56.584566] Starting subtest: basic [56.612185] Subtest basic: SUCCESS (0.029s) [56.673474] [039/162] (933s left) gem_ringfill (basic-all) [56.901985] Starting subtest: basic-all [58.032461] Subtest basic-all: SUCCESS (1.136s) [58.100906] [040/162] (931s left) gem_softpin (allocator-basic) [58.155456] Starting subtest: allocator-basic [58.250163] Subtest allocator-basic: SUCCESS (0.098s) [58.332947] [041/162] (931s left) gem_softpin (allocator-basic-reserve) [58.387489] Starting subtest: allocator-basic-reserve [58.482436] Subtest allocator-basic-reserve: SUCCESS (0.099s) [58.567455] [042/162] (931s left) gem_softpin (safe-alignment) [58.620646] Starting subtest: safe-alignment [58.629202] Subtest safe-alignment: SUCCESS (0.010s) [58.699919] [043/162] (931s left) gem_sync (basic-all) [58.786615] Starting subtest: basic-all [60.838336] Subtest basic-all: SUCCESS (2.095s) [60.988438] [044/162] (929s left) gem_sync (basic-each) [61.065637] Starting subtest: basic-each [63.149728] Subtest basic-each: SUCCESS (2.118s) [63.303515] [045/162] (926s left) gem_tiled_blits (basic) [63.408473] Starting subtest: basic [63.425278] Subtest basic: SUCCESS (0.017s) [63.515438] [046/162] (926s left) gem_tiled_fence_blits (basic) [63.699454] Starting subtest: basic [63.707517] Subtest basic: SUCCESS (0.008s) [63.791599] [047/162] (926s left) gem_tiled_pread_basic (basic) [63.923580] Starting subtest: basic [64.035869] Subtest basic: SUCCESS (0.117s) [64.087829] [048/162] (926s left) gem_wait (busy@all-engines) [64.149767] Starting subtest: busy [64.152849] Starting dynamic subtest: all-engines [64.666629] Dynamic subtest all-engines: SUCCESS (0.522s) [64.669929] Subtest busy: SUCCESS (0.522s) [64.826707] [049/162] (925s left) gem_wait (wait@all-engines) [64.886288] Starting subtest: wait [64.889188] Starting dynamic subtest: all-engines [65.911739] Dynamic subtest all-engines: SUCCESS (1.029s) [65.914835] Subtest wait: SUCCESS (1.029s) [66.070185] [050/162] (924s left) i915_getparams_basic (basic-eu-total) [66.124439] Starting subtest: basic-eu-total [66.130670] Subtest basic-eu-total: SUCCESS (0.000s) [66.173636] [051/162] (924s left) i915_getparams_basic (basic-subslice-total) [66.224467] Starting subtest: basic-subslice-total [66.230537] Subtest basic-subslice-total: SUCCESS (0.000s) [66.274462] [052/162] (924s left) i915_hangman (error-state-basic) [66.331959] Starting subtest: error-state-basic [66.340590] Subtest error-state-basic: SUCCESS (0.005s) [66.408532] [053/162] (924s left) i915_pciid [66.502948] [054/162] (924s left) intel_hwmon (hwmon-read) [66.555894] Subtest hwmon-read: SKIP (0.000s) [66.599607] [055/162] (923s left) intel_hwmon (hwmon-write) [66.656054] Subtest hwmon-write: SKIP (0.000s) [66.699952] [056/162] (923s left) kms_addfb_basic (addfb25-4-tiled) [66.798484] Starting subtest: addfb25-4-tiled [66.801294] Subtest addfb25-4-tiled: SUCCESS (0.000s) [67.254141] [057/162] (923s left) kms_addfb_basic (addfb25-bad-modifier) [67.350130] Starting subtest: addfb25-bad-modifier [67.352918] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [67.527461] [058/162] (923s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [67.607188] Starting subtest: addfb25-framebuffer-vs-set-tiling [67.610480] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [67.667535] [059/162] (923s left) kms_addfb_basic (addfb25-modifier-no-flag) [67.747312] Starting subtest: addfb25-modifier-no-flag [67.750305] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [67.812760] [060/162] (923s left) kms_addfb_basic (addfb25-x-tiled-legacy) [67.892254] Starting subtest: addfb25-x-tiled-legacy [67.895293] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [67.944577] [061/162] (922s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [68.020822] Starting subtest: addfb25-x-tiled-mismatch-legacy [68.023726] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [68.088396] [062/162] (922s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [68.166884] Starting subtest: addfb25-yf-tiled-legacy [68.170190] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [68.230472] [063/162] (922s left) kms_addfb_basic (addfb25-y-tiled-legacy) [68.309258] Starting subtest: addfb25-y-tiled-legacy [68.312253] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [68.368403] [064/162] (922s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [68.447556] Starting subtest: addfb25-y-tiled-small-legacy [68.450794] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [68.541804] [065/162] (922s left) kms_addfb_basic (bad-pitch-0) [68.593092] Starting subtest: bad-pitch-0 [68.596518] Subtest bad-pitch-0: SUCCESS (0.000s) [68.679123] [066/162] (922s left) kms_addfb_basic (bad-pitch-1024) [68.729302] Starting subtest: bad-pitch-1024 [68.733824] Subtest bad-pitch-1024: SUCCESS (0.000s) [68.810976] [067/162] (922s left) kms_addfb_basic (bad-pitch-128) [68.859170] Starting subtest: bad-pitch-128 [68.862570] Subtest bad-pitch-128: SUCCESS (0.000s) [68.948871] [068/162] (922s left) kms_addfb_basic (bad-pitch-256) [69.005985] Starting subtest: bad-pitch-256 [69.009345] Subtest bad-pitch-256: SUCCESS (0.000s) [69.080369] [069/162] (922s left) kms_addfb_basic (bad-pitch-32) [69.130035] Starting subtest: bad-pitch-32 [69.133492] Subtest bad-pitch-32: SUCCESS (0.000s) [69.214144] [070/162] (922s left) kms_addfb_basic (bad-pitch-63) [69.271246] Starting subtest: bad-pitch-63 [69.274929] Subtest bad-pitch-63: SUCCESS (0.000s) [69.362823] [071/162] (921s left) kms_addfb_basic (bad-pitch-65536) [69.417625] Starting subtest: bad-pitch-65536 [69.420889] Subtest bad-pitch-65536: SUCCESS (0.000s) [69.498601] [072/162] (921s left) kms_addfb_basic (bad-pitch-999) [69.545773] Starting subtest: bad-pitch-999 [69.550905] Subtest bad-pitch-999: SUCCESS (0.000s) [69.646303] [073/162] (921s left) kms_addfb_basic (basic) [69.696609] Starting subtest: basic [69.700495] Subtest basic: SUCCESS (0.000s) [69.797161] [074/162] (921s left) kms_addfb_basic (basic-x-tiled-legacy) [69.846964] Starting subtest: basic-x-tiled-legacy [69.851871] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [69.938182] [075/162] (921s left) kms_addfb_basic (basic-y-tiled-legacy) [69.988177] Starting subtest: basic-y-tiled-legacy [69.992396] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [70.075684] [076/162] (921s left) kms_addfb_basic (bo-too-small) [70.157736] Starting subtest: bo-too-small [70.160822] Subtest bo-too-small: SUCCESS (0.000s) [70.215169] [077/162] (921s left) kms_addfb_basic (bo-too-small-due-to-tiling) [70.294856] Starting subtest: bo-too-small-due-to-tiling [70.298159] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [70.356108] [078/162] (921s left) kms_addfb_basic (clobberred-modifier) [70.406539] Starting subtest: clobberred-modifier [70.410665] Subtest clobberred-modifier: SUCCESS (0.000s) [70.503335] [079/162] (921s left) kms_addfb_basic (framebuffer-vs-set-tiling) [70.556593] Starting subtest: framebuffer-vs-set-tiling [70.559825] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [70.642149] [080/162] (921s left) kms_addfb_basic (invalid-get-prop) [70.692502] Starting subtest: invalid-get-prop [70.696518] Subtest invalid-get-prop: SUCCESS (0.000s) [70.783791] [081/162] (921s left) kms_addfb_basic (invalid-get-prop-any) [70.833874] Starting subtest: invalid-get-prop-any [70.837627] Subtest invalid-get-prop-any: SUCCESS (0.000s) [70.928470] [082/162] (920s left) kms_addfb_basic (invalid-set-prop) [70.978452] Starting subtest: invalid-set-prop [70.982177] Subtest invalid-set-prop: SUCCESS (0.000s) [71.075748] [083/162] (920s left) kms_addfb_basic (invalid-set-prop-any) [71.130597] Starting subtest: invalid-set-prop-any [71.133520] Subtest invalid-set-prop-any: SUCCESS (0.000s) [71.351856] [084/162] (920s left) kms_addfb_basic (no-handle) [71.737098] Starting subtest: no-handle [71.801532] Subtest no-handle: SUCCESS (0.000s) [72.215383] [085/162] (920s left) kms_addfb_basic (size-max) [72.540512] Starting subtest: size-max [72.670816] Subtest size-max: SUCCESS (0.000s) [72.717374] [086/162] (919s left) kms_addfb_basic (small-bo) [72.802077] Starting subtest: small-bo [72.805362] Subtest small-bo: SUCCESS (0.000s) [72.868995] [087/162] (919s left) kms_addfb_basic (tile-pitch-mismatch) [72.918803] Starting subtest: tile-pitch-mismatch [72.924232] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [73.016960] [088/162] (919s left) kms_addfb_basic (too-high) [73.096771] Starting subtest: too-high [73.100121] Subtest too-high: SUCCESS (0.001s) [73.156192] [089/162] (919s left) kms_addfb_basic (too-wide) [73.235502] Starting subtest: too-wide [73.238923] Subtest too-wide: SUCCESS (0.000s) [73.294897] [090/162] (919s left) kms_addfb_basic (unused-handle) [73.346711] Starting subtest: unused-handle [73.350646] Subtest unused-handle: SUCCESS (0.000s) [73.433780] [091/162] (919s left) kms_addfb_basic (unused-modifier) [73.484746] Starting subtest: unused-modifier [73.488669] Subtest unused-modifier: SUCCESS (0.000s) [73.566480] [092/162] (919s left) kms_addfb_basic (unused-offsets) [73.617046] Starting subtest: unused-offsets [73.620515] Subtest unused-offsets: SUCCESS (0.000s) [73.702081] [093/162] (919s left) kms_addfb_basic (unused-pitches) [73.753610] Starting subtest: unused-pitches [73.757612] Subtest unused-pitches: SUCCESS (0.000s) [73.844496] [094/162] (918s left) kms_busy (basic) [73.930820] Subtest basic: SKIP (0.000s) [73.974250] [095/162] (918s left) kms_prop_blob (basic) [74.043291] Starting subtest: basic [74.049991] Subtest basic: SUCCESS (0.000s) [74.103557] [096/162] (918s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [74.203953] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [74.255450] [097/162] (918s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [74.363088] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [74.408359] [098/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [74.508371] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s) [74.554375] [099/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [74.656211] Subtest basic-flip-after-cursor-legacy: SKIP (0.000s) [74.704483] [100/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [74.814340] Subtest basic-flip-after-cursor-varying-size: SKIP (0.000s) [74.866378] [101/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [74.966911] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s) [75.022561] [102/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [75.118457] Subtest basic-flip-before-cursor-legacy: SKIP (0.000s) [75.164283] [103/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [75.264184] Subtest basic-flip-before-cursor-varying-size: SKIP (0.000s) [75.310348] [104/162] (918s left) kms_dsc (dsc-basic) [75.390602] Subtest dsc-basic: SKIP (0.000s) [75.433607] [105/162] (917s left) kms_flip (basic-flip-vs-dpms) [75.513272] Starting subtest: basic-flip-vs-dpms [75.518897] Subtest basic-flip-vs-dpms: SKIP (0.001s) [75.570453] [106/162] (917s left) kms_flip (basic-flip-vs-modeset) [75.652833] Starting subtest: basic-flip-vs-modeset [75.658800] Subtest basic-flip-vs-modeset: SKIP (0.001s) [75.701590] [107/162] (917s left) kms_flip (basic-flip-vs-wf_vblank) [75.785135] Starting subtest: basic-flip-vs-wf_vblank [75.791652] Subtest basic-flip-vs-wf_vblank: SKIP (0.001s) [75.859041] [108/162] (917s left) kms_flip (basic-plain-flip) [75.941932] Starting subtest: basic-plain-flip [75.948397] Subtest basic-plain-flip: SKIP (0.001s) [76.006696] [109/162] (917s left) kms_force_connector_basic (force-connector-state) [76.163530] Starting subtest: force-connector-state [76.249662] Subtest force-connector-state: SUCCESS (0.087s) [76.415396] [110/162] (917s left) kms_force_connector_basic (force-edid) [76.582446] Starting subtest: force-edid [76.774555] Subtest force-edid: SUCCESS (0.194s) [76.859958] [111/162] (916s left) kms_force_connector_basic (force-load-detect) [77.022780] Starting subtest: force-load-detect [77.028611] Subtest force-load-detect: SKIP (0.000s) [77.091319] [112/162] (916s left) kms_force_connector_basic (prune-stale-modes) [77.253113] Starting subtest: prune-stale-modes [77.428411] Subtest prune-stale-modes: SUCCESS (0.179s) [77.513333] [113/162] (916s left) kms_frontbuffer_tracking (basic) [77.599292] Subtest basic: SKIP (0.000s) [77.787255] [114/162] (916s left) kms_hdmi_inject (inject-audio) [78.170851] Starting subtest: inject-audio [78.299324] Subtest inject-audio: SKIP (0.000s) [78.712925] [115/162] (915s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [79.087547] Subtest compare-crc-sanitycheck-xr24: SKIP (0.000s) [79.134816] [116/162] (915s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [79.213687] Subtest compare-crc-sanitycheck-nv12: SKIP (0.000s) [79.259392] [117/162] (915s left) kms_pipe_crc_basic (hang-read-crc) [79.339510] Subtest hang-read-crc: SKIP (0.000s) [79.382810] [118/162] (915s left) kms_pipe_crc_basic (nonblocking-crc) [79.463689] Subtest nonblocking-crc: SKIP (0.000s) [79.506675] [119/162] (914s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [79.589369] Subtest nonblocking-crc-frame-sequence: SKIP (0.000s) [79.632243] [120/162] (914s left) kms_pipe_crc_basic (read-crc) [79.712699] Subtest read-crc: SKIP (0.000s) [79.756234] [121/162] (914s left) kms_pipe_crc_basic (read-crc-frame-sequence) [79.834484] Subtest read-crc-frame-sequence: SKIP (0.000s) [79.877647] [122/162] (914s left) kms_pm_backlight (basic-brightness) [79.957529] Subtest basic-brightness: SKIP (0.000s) [80.000619] [123/162] (914s left) kms_pm_rpm (basic-pci-d3-state) [91.413703] Starting subtest: basic-pci-d3-state [91.592544] Subtest basic-pci-d3-state: SUCCESS (0.176s) [91.645792] [124/162] (903s left) kms_pm_rpm (basic-rte) [91.694312] Starting subtest: basic-rte [103.158514] Subtest basic-rte: SUCCESS (11.465s) [103.463638] [125/162] (891s left) kms_psr (psr-primary-page-flip) [103.545521] Subtest psr-primary-page-flip: SKIP (0.000s) [103.588888] [126/162] (891s left) kms_psr (psr-cursor-plane-move) [103.671830] Subtest psr-cursor-plane-move: SKIP (0.000s) [103.715717] [127/162] (891s left) kms_psr (psr-sprite-plane-onoff) [103.796294] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [103.840365] [128/162] (891s left) kms_psr (psr-primary-mmap-gtt) [103.931107] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [103.975077] [129/162] (890s left) kms_setmode (basic-clone-single-crtc) [104.029643] Starting subtest: basic-clone-single-crtc [104.039530] Subtest basic-clone-single-crtc: SKIP (0.000s) [104.085378] [130/162] (890s left) i915_pm_rps (basic-api) [104.142653] Starting subtest: basic-api [104.145682] Subtest basic-api: SUCCESS (0.003s) [104.192378] [131/162] (890s left) prime_self_import (basic-llseek-bad) [104.242638] Starting subtest: basic-llseek-bad [104.248532] Subtest basic-llseek-bad: SUCCESS (0.002s) [104.305457] [132/162] (890s left) prime_self_import (basic-llseek-size) [104.351518] Starting subtest: basic-llseek-size [104.357468] Subtest basic-llseek-size: SUCCESS (0.003s) [104.423516] [133/162] (890s left) prime_self_import (basic-with_fd_dup) [104.469980] Starting subtest: basic-with_fd_dup [104.476220] Subtest basic-with_fd_dup: SUCCESS (0.002s) [104.536608] [134/162] (890s left) prime_self_import (basic-with_one_bo) [104.585435] Starting subtest: basic-with_one_bo [104.591760] Subtest basic-with_one_bo: SUCCESS (0.003s) [104.637223] [135/162] (890s left) prime_self_import (basic-with_one_bo_two_files) [104.687062] Starting subtest: basic-with_one_bo_two_files [104.693290] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [104.749758] [136/162] (890s left) prime_self_import (basic-with_two_bos) [104.795877] Starting subtest: basic-with_two_bos [104.802233] Subtest basic-with_two_bos: SUCCESS (0.002s) [104.865388] [137/162] (890s left) prime_vgem (basic-fence-flip) [104.921940] Starting subtest: basic-fence-flip [104.976895] Subtest basic-fence-flip: SKIP (0.055s) [105.024288] [138/162] (890s left) prime_vgem (basic-fence-mmap) [105.080389] Starting subtest: basic-fence-mmap [105.100643] Subtest basic-fence-mmap: SUCCESS (0.022s) [105.179692] [139/162] (890s left) prime_vgem (basic-fence-read) [105.233557] Starting subtest: basic-fence-read [105.277634] Subtest basic-fence-read: SUCCESS (0.045s) [105.344552] [140/162] (890s left) prime_vgem (basic-gtt) [105.401161] Starting subtest: basic-gtt [105.407969] Subtest basic-gtt: SUCCESS (0.011s) [105.487291] [141/162] (890s left) prime_vgem (basic-read) [105.541139] Starting subtest: basic-read [105.561120] Subtest basic-read: SUCCESS (0.022s) [105.626048] [142/162] (889s left) prime_vgem (basic-write) [105.682126] Starting subtest: basic-write [105.700201] Subtest basic-write: SUCCESS (0.022s) [105.773329] [143/162] (889s left) vgem_basic (setversion) [105.824814] Starting subtest: setversion [105.831160] Subtest setversion: SUCCESS (0.000s) [105.880214] [144/162] (889s left) vgem_basic (create) [105.931428] Starting subtest: create [105.934486] Subtest create: SUCCESS (0.000s) [105.996451] [145/162] (889s left) vgem_basic (debugfs) [106.046279] Starting subtest: debugfs [106.049352] Subtest debugfs: SUCCESS (0.000s) [106.093103] [146/162] (889s left) vgem_basic (dmabuf-export) [106.142790] Starting subtest: dmabuf-export [106.148721] Subtest dmabuf-export: SUCCESS (0.002s) [106.212734] [147/162] (889s left) vgem_basic (dmabuf-fence) [106.262132] Starting subtest: dmabuf-fence [106.265240] Subtest dmabuf-fence: SUCCESS (0.001s) [106.310210] [148/162] (889s left) vgem_basic (dmabuf-fence-before) [106.363575] Starting subtest: dmabuf-fence-before [106.366575] Subtest dmabuf-fence-before: SUCCESS (0.000s) [106.413395] [149/162] (889s left) vgem_basic (dmabuf-mmap) [106.463398] Starting subtest: dmabuf-mmap [106.469577] Subtest dmabuf-mmap: SUCCESS (0.009s) [106.513506] [150/162] (889s left) vgem_basic (mmap) [106.566946] Starting subtest: mmap [106.570180] Subtest mmap: SUCCESS (0.006s) [106.614647] [151/162] (889s left) vgem_basic (second-client) [106.665320] Starting subtest: second-client [106.671733] Subtest second-client: SUCCESS (0.002s) [106.720230] [152/162] (889s left) vgem_basic (sysfs) [106.773276] Starting subtest: sysfs [106.776589] Subtest sysfs: SUCCESS (0.001s) [106.826052] [153/162] (889s left) core_hotunplug (unbind-rebind) [106.936584] Starting subtest: unbind-rebind [110.689043] Subtest unbind-rebind: SUCCESS (3.753s) [110.733971] [154/162] (885s left) vgem_basic (unload) [110.782126] Starting subtest: unload [139.629934] Subtest unload: SUCCESS (28.847s) [139.691589] [155/162] (856s left) i915_module_load (reload) [139.738804] Starting subtest: reload [142.148832] Subtest reload: SUCCESS (2.412s) [142.192116] [156/162] (854s left) gem_lmem_swapping (basic) [142.253865] Subtest basic: SKIP (0.000s) [142.297639] [157/162] (853s left) gem_lmem_swapping (parallel-random-engines) [142.353165] Subtest parallel-random-engines: SKIP (0.000s) [142.395827] [158/162] (853s left) gem_lmem_swapping (random-engines) [142.451107] Subtest random-engines: SKIP (0.000s) [142.492995] [159/162] (853s left) gem_lmem_swapping (verify-random) [142.548423] Subtest verify-random: SKIP (0.000s) [142.592230] [160/162] (853s left) i915_pm_rpm (module-reload) [153.901981] Starting subtest: module-reload [181.582548] Subtest module-reload: SUCCESS (27.681s) [181.613513] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [181.614519] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [181.618599] [161/162] (814s left) i915_selftest (live)