[46.792495] Initializing watchdogs [46.792570] /dev/watchdog0 [46.807392] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:591d Intel Kabylake (Gen9) HD Graphics P630 [46.815633] [001/162] (960s left) i915_module_load (load) [46.884343] Starting subtest: load [48.859597] Subtest load: SUCCESS (1.981s) [48.892088] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [48.893306] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [48.898244] [002/162] (958s left) core_auth (basic-auth) [48.956732] Starting subtest: basic-auth [48.959715] Subtest basic-auth: SUCCESS (0.001s) [49.003391] [003/162] (958s left) core_debugfs (read-all-entries) [49.053226] Starting subtest: read-all-entries [49.056516] Subtest read-all-entries: SUCCESS (0.002s) [49.094819] [004/162] (958s left) core_sysfs (read-all-entries) [49.147570] Starting subtest: read-all-entries [49.150754] Subtest read-all-entries: SUCCESS (0.003s) [49.189341] [005/162] (958s left) fbdev (eof) [49.245466] Subtest eof: SKIP (0.000s) [49.283254] [006/162] (958s left) fbdev (info) [49.340197] Subtest info: SKIP (0.000s) [49.378014] [007/162] (958s left) fbdev (nullptr) [49.430817] Subtest nullptr: SKIP (0.000s) [49.469626] [008/162] (958s left) fbdev (read) [49.524298] Subtest read: SKIP (0.000s) [49.563359] [009/162] (958s left) fbdev (write) [49.615598] Subtest write: SKIP (0.000s) [49.658702] [010/162] (958s left) gem_basic (bad-close) [49.707035] Starting subtest: bad-close [49.713200] Subtest bad-close: SUCCESS (0.000s) [49.752029] [011/162] (957s left) gem_basic (create-close) [49.800859] Starting subtest: create-close [49.807024] Subtest create-close: SUCCESS (0.000s) [49.863529] [012/162] (957s left) gem_basic (create-fd-close) [49.911044] Starting subtest: create-fd-close [49.917226] Subtest create-fd-close: SUCCESS (0.000s) [49.964941] [013/162] (957s left) gem_busy (busy@all-engines) [50.023588] Starting subtest: busy [50.026796] Starting dynamic subtest: all-engines [50.066554] Dynamic subtest all-engines: SUCCESS (0.043s) [50.070308] Subtest busy: SUCCESS (0.043s) [50.371381] [014/162] (957s left) gem_close_race (basic-process) [50.430826] Starting subtest: basic-process [50.488802] Subtest basic-process: SUCCESS (0.057s) [50.634063] [015/162] (957s left) gem_close_race (basic-threads) [50.691092] Starting subtest: basic-threads [51.894714] Subtest basic-threads: SUCCESS (1.203s) [52.055969] [016/162] (955s left) gem_ctx_create (basic) [52.122657] Starting subtest: basic [52.125862] Subtest basic: SUCCESS (0.000s) [52.272106] [017/162] (955s left) gem_ctx_create (basic-files) [52.326164] Starting subtest: basic-files [54.339151] Subtest basic-files: SUCCESS (2.011s) [54.529759] [018/162] (953s left) gem_ctx_exec (basic) [54.582986] Starting subtest: basic [54.586284] Subtest basic: SUCCESS (0.004s) [54.661513] [019/162] (953s left) gem_exec_basic (basic) [54.713840] Starting subtest: basic [54.717137] Starting dynamic subtest: rcs0-smem [54.720269] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [54.731147] Starting dynamic subtest: bcs0-smem [54.734165] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [54.744719] Starting dynamic subtest: vcs0-smem [54.747719] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [54.758009] Starting dynamic subtest: vecs0-smem [54.760944] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [54.764109] Subtest basic: SUCCESS (0.005s) [54.901185] [020/162] (953s left) gem_exec_create (basic) [54.956937] Starting subtest: basic [54.960328] Starting dynamic subtest: smem [57.005786] Dynamic subtest smem: SUCCESS (2.049s) [57.008998] Subtest basic: SUCCESS (2.049s) [57.157949] [021/162] (951s left) gem_exec_fence (basic-busy) [57.326476] Starting subtest: basic-busy [57.329713] Starting dynamic subtest: rcs0 [57.369804] Dynamic subtest rcs0: SUCCESS (0.043s) [57.393195] Starting dynamic subtest: bcs0 [57.406058] Dynamic subtest bcs0: SUCCESS (0.036s) [57.416831] Starting dynamic subtest: vcs0 [57.441468] Dynamic subtest vcs0: SUCCESS (0.035s) [57.453454] Starting dynamic subtest: vecs0 [57.472496] Dynamic subtest vecs0: SUCCESS (0.031s) [57.475879] Subtest basic-busy: SUCCESS (0.146s) [57.990708] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [57.995155] [022/162] (950s left) gem_exec_fence (basic-wait) [58.171725] Starting subtest: basic-wait [58.174945] Starting dynamic subtest: rcs0 [58.204525] Dynamic subtest rcs0: SUCCESS (0.036s) [58.220970] Starting dynamic subtest: bcs0 [58.235379] Dynamic subtest bcs0: SUCCESS (0.031s) [58.252196] Starting dynamic subtest: vcs0 [58.265435] Dynamic subtest vcs0: SUCCESS (0.030s) [58.282568] Starting dynamic subtest: vecs0 [58.292292] Dynamic subtest vecs0: SUCCESS (0.026s) [58.295784] Subtest basic-wait: SUCCESS (0.124s) [58.781338] [023/162] (949s left) gem_exec_fence (basic-await) [58.950635] Starting subtest: basic-await [58.953941] Starting dynamic subtest: rcs0 [59.027157] Dynamic subtest rcs0: SUCCESS (0.076s) [59.046832] Starting dynamic subtest: bcs0 [59.115762] Dynamic subtest bcs0: SUCCESS (0.088s) [59.136716] Starting dynamic subtest: vcs0 [59.212105] Dynamic subtest vcs0: SUCCESS (0.096s) [59.227653] Starting dynamic subtest: vecs0 [59.294867] Dynamic subtest vecs0: SUCCESS (0.082s) [59.298567] Subtest basic-await: SUCCESS (0.345s) [59.794119] [024/162] (948s left) gem_exec_fence (nb-await) [59.964112] Starting subtest: nb-await [59.967749] Starting dynamic subtest: rcs0 [60.026634] Dynamic subtest rcs0: SUCCESS (0.062s) [60.041193] Starting dynamic subtest: bcs0 [60.088449] Dynamic subtest bcs0: SUCCESS (0.061s) [60.110606] Starting dynamic subtest: vcs0 [60.151330] Dynamic subtest vcs0: SUCCESS (0.062s) [60.173676] Starting dynamic subtest: vecs0 [60.215518] Dynamic subtest vecs0: SUCCESS (0.063s) [60.219170] Subtest nb-await: SUCCESS (0.251s) [60.707028] [025/162] (947s left) gem_exec_gttfill (basic) [60.764832] Starting subtest: basic [64.141163] Subtest basic: SUCCESS (3.376s) [64.305823] [026/162] (944s left) gem_exec_parallel (engines) [64.439575] Starting subtest: engines [64.442711] Starting dynamic subtest: basic [66.327441] Dynamic subtest basic: SUCCESS (1.888s) [66.339532] Starting dynamic subtest: contexts [68.574803] Dynamic subtest contexts: SUCCESS (2.247s) [68.586971] Starting dynamic subtest: fds [71.070932] Dynamic subtest fds: SUCCESS (2.496s) [71.083596] Starting dynamic subtest: userptr [73.478478] Dynamic subtest userptr: SUCCESS (2.407s) [73.481930] Subtest engines: SUCCESS (9.039s) [73.652090] [027/162] (934s left) gem_exec_store (basic) [73.713606] Starting subtest: basic [73.768763] Subtest basic: SUCCESS (0.058s) [73.921089] [028/162] (934s left) gem_flink_basic (bad-flink) [73.981142] Starting subtest: bad-flink [73.984187] Subtest bad-flink: SUCCESS (0.000s) [74.028873] [029/162] (934s left) gem_flink_basic (bad-open) [74.075399] Starting subtest: bad-open [74.078786] Subtest bad-open: SUCCESS (0.000s) [74.123228] [030/162] (934s left) gem_flink_basic (basic) [74.171983] Starting subtest: basic [74.175333] Subtest basic: SUCCESS (0.000s) [74.229139] [031/162] (934s left) gem_flink_basic (double-flink) [74.275571] Starting subtest: double-flink [74.278564] Subtest double-flink: SUCCESS (0.000s) [74.331899] [032/162] (934s left) gem_flink_basic (flink-lifetime) [74.389199] Starting subtest: flink-lifetime [74.393350] Subtest flink-lifetime: SUCCESS (0.001s) [74.464467] [033/162] (934s left) gem_huc_copy (huc-copy) [74.518750] Starting subtest: huc-copy [74.525082] Subtest huc-copy: SKIP (0.000s) [74.600120] [034/162] (934s left) gem_linear_blits (basic) [74.724400] Starting subtest: basic [74.731078] Subtest basic: SUCCESS (0.008s) [74.826394] [035/162] (934s left) gem_mmap (basic) [74.960365] Starting subtest: basic [74.976928] Subtest basic: SUCCESS (0.000s) [75.024318] [036/162] (933s left) gem_mmap_gtt (basic) [75.075505] Starting subtest: basic [75.078892] Subtest basic: SUCCESS (0.001s) [75.157877] [037/162] (933s left) gem_render_linear_blits (basic) [75.210925] Starting subtest: basic [75.235469] Subtest basic: SUCCESS (0.026s) [75.297112] [038/162] (933s left) gem_render_tiled_blits (basic) [75.350569] Starting subtest: basic [75.379829] Subtest basic: SUCCESS (0.030s) [75.438878] [039/162] (933s left) gem_ringfill (basic-all) [75.678849] Starting subtest: basic-all [76.821982] Subtest basic-all: SUCCESS (1.138s) [76.908872] [040/162] (932s left) gem_softpin (allocator-basic) [76.961743] Starting subtest: allocator-basic [77.053346] Subtest allocator-basic: SUCCESS (0.092s) [77.148490] [041/162] (932s left) gem_softpin (allocator-basic-reserve) [77.201680] Starting subtest: allocator-basic-reserve [77.294729] Subtest allocator-basic-reserve: SUCCESS (0.093s) [77.377389] [042/162] (931s left) gem_softpin (safe-alignment) [77.430589] Starting subtest: safe-alignment [77.457419] Subtest safe-alignment: SUCCESS (0.010s) [77.516207] [043/162] (931s left) gem_sync (basic-all) [77.598191] Starting subtest: basic-all [79.888614] Subtest basic-all: SUCCESS (2.310s) [80.036468] [044/162] (929s left) gem_sync (basic-each) [80.112615] Starting subtest: basic-each [82.164947] Subtest basic-each: SUCCESS (2.074s) [82.321882] [045/162] (927s left) gem_tiled_blits (basic) [82.430133] Starting subtest: basic [82.447654] Subtest basic: SUCCESS (0.017s) [82.542354] [046/162] (926s left) gem_tiled_fence_blits (basic) [82.735723] Starting subtest: basic [82.746059] Subtest basic: SUCCESS (0.009s) [82.832289] [047/162] (926s left) gem_tiled_pread_basic (basic) [82.962458] Starting subtest: basic [83.070235] Subtest basic: SUCCESS (0.114s) [83.136058] [048/162] (926s left) gem_wait (busy@all-engines) [83.196432] Starting subtest: busy [83.199417] Starting dynamic subtest: all-engines [83.716779] Dynamic subtest all-engines: SUCCESS (0.518s) [83.719746] Subtest busy: SUCCESS (0.518s) [83.896386] [049/162] (925s left) gem_wait (wait@all-engines) [83.956904] Starting subtest: wait [83.960314] Starting dynamic subtest: all-engines [84.982705] Dynamic subtest all-engines: SUCCESS (1.025s) [84.986177] Subtest wait: SUCCESS (1.026s) [85.157987] [050/162] (924s left) i915_getparams_basic (basic-eu-total) [85.526379] Starting subtest: basic-eu-total [85.655935] Subtest basic-eu-total: SUCCESS (0.000s) [86.068559] [051/162] (923s left) i915_getparams_basic (basic-subslice-total) [86.457105] Starting subtest: basic-subslice-total [86.643996] Subtest basic-subslice-total: SUCCESS (0.000s) [86.690068] [052/162] (923s left) i915_hangman (error-state-basic) [86.750153] Starting subtest: error-state-basic [86.754348] Subtest error-state-basic: SUCCESS (0.005s) [86.825341] [053/162] (923s left) i915_pciid [86.921608] [054/162] (923s left) intel_hwmon (hwmon-read) [86.973450] Subtest hwmon-read: SKIP (0.000s) [87.018521] [055/162] (923s left) intel_hwmon (hwmon-write) [87.071485] Subtest hwmon-write: SKIP (0.000s) [87.116169] [056/162] (923s left) kms_addfb_basic (addfb25-4-tiled) [87.212090] Starting subtest: addfb25-4-tiled [87.215390] Subtest addfb25-4-tiled: SUCCESS (0.000s) [87.280421] [057/162] (922s left) kms_addfb_basic (addfb25-bad-modifier) [87.357512] Starting subtest: addfb25-bad-modifier [87.360666] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [87.412358] [058/162] (922s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [87.493741] Starting subtest: addfb25-framebuffer-vs-set-tiling [87.496861] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [87.550985] [059/162] (922s left) kms_addfb_basic (addfb25-modifier-no-flag) [87.632131] Starting subtest: addfb25-modifier-no-flag [87.635358] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [87.694990] [060/162] (922s left) kms_addfb_basic (addfb25-x-tiled-legacy) [87.774517] Starting subtest: addfb25-x-tiled-legacy [87.777573] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [87.829374] [061/162] (922s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [87.908308] Starting subtest: addfb25-x-tiled-mismatch-legacy [87.911606] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [87.971796] [062/162] (922s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [88.052616] Starting subtest: addfb25-yf-tiled-legacy [88.055819] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [88.105090] [063/162] (922s left) kms_addfb_basic (addfb25-y-tiled-legacy) [88.182813] Starting subtest: addfb25-y-tiled-legacy [88.185994] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [88.245398] [064/162] (922s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [88.326430] Starting subtest: addfb25-y-tiled-small-legacy [88.329501] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [88.384191] [065/162] (922s left) kms_addfb_basic (bad-pitch-0) [88.434075] Starting subtest: bad-pitch-0 [88.437550] Subtest bad-pitch-0: SUCCESS (0.000s) [88.530659] [066/162] (922s left) kms_addfb_basic (bad-pitch-1024) [88.581810] Starting subtest: bad-pitch-1024 [88.585601] Subtest bad-pitch-1024: SUCCESS (0.000s) [88.670915] [067/162] (922s left) kms_addfb_basic (bad-pitch-128) [88.722899] Starting subtest: bad-pitch-128 [88.726376] Subtest bad-pitch-128: SUCCESS (0.000s) [88.812364] [068/162] (921s left) kms_addfb_basic (bad-pitch-256) [88.866845] Starting subtest: bad-pitch-256 [88.870077] Subtest bad-pitch-256: SUCCESS (0.000s) [88.961172] [069/162] (921s left) kms_addfb_basic (bad-pitch-32) [89.013666] Starting subtest: bad-pitch-32 [89.017631] Subtest bad-pitch-32: SUCCESS (0.000s) [89.110380] [070/162] (921s left) kms_addfb_basic (bad-pitch-63) [89.164306] Starting subtest: bad-pitch-63 [89.168071] Subtest bad-pitch-63: SUCCESS (0.000s) [89.263338] [071/162] (921s left) kms_addfb_basic (bad-pitch-65536) [89.313772] Starting subtest: bad-pitch-65536 [89.317713] Subtest bad-pitch-65536: SUCCESS (0.000s) [89.404337] [072/162] (921s left) kms_addfb_basic (bad-pitch-999) [89.455634] Starting subtest: bad-pitch-999 [89.460135] Subtest bad-pitch-999: SUCCESS (0.000s) [89.550342] [073/162] (921s left) kms_addfb_basic (basic) [89.602118] Starting subtest: basic [89.605919] Subtest basic: SUCCESS (0.000s) [89.698738] [074/162] (921s left) kms_addfb_basic (basic-x-tiled-legacy) [89.749809] Starting subtest: basic-x-tiled-legacy [89.753183] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [89.829637] [075/162] (921s left) kms_addfb_basic (basic-y-tiled-legacy) [89.885148] Starting subtest: basic-y-tiled-legacy [89.888740] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [89.965901] [076/162] (921s left) kms_addfb_basic (bo-too-small) [90.046430] Starting subtest: bo-too-small [90.049564] Subtest bo-too-small: SUCCESS (0.000s) [90.097118] [077/162] (921s left) kms_addfb_basic (bo-too-small-due-to-tiling) [90.178302] Starting subtest: bo-too-small-due-to-tiling [90.182246] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [90.244107] [078/162] (920s left) kms_addfb_basic (clobberred-modifier) [90.297565] Starting subtest: clobberred-modifier [90.301485] Subtest clobberred-modifier: SUCCESS (0.000s) [90.389064] [079/162] (920s left) kms_addfb_basic (framebuffer-vs-set-tiling) [90.439733] Starting subtest: framebuffer-vs-set-tiling [90.444125] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [90.532332] [080/162] (920s left) kms_addfb_basic (invalid-get-prop) [90.583260] Starting subtest: invalid-get-prop [90.587046] Subtest invalid-get-prop: SUCCESS (0.000s) [90.671610] [081/162] (920s left) kms_addfb_basic (invalid-get-prop-any) [90.727599] Starting subtest: invalid-get-prop-any [90.730576] Subtest invalid-get-prop-any: SUCCESS (0.000s) [90.818555] [082/162] (920s left) kms_addfb_basic (invalid-set-prop) [90.869132] Starting subtest: invalid-set-prop [90.872529] Subtest invalid-set-prop: SUCCESS (0.000s) [90.962629] [083/162] (920s left) kms_addfb_basic (invalid-set-prop-any) [91.013148] Starting subtest: invalid-set-prop-any [91.017397] Subtest invalid-set-prop-any: SUCCESS (0.000s) [91.102761] [084/162] (920s left) kms_addfb_basic (no-handle) [91.152938] Starting subtest: no-handle [91.158288] Subtest no-handle: SUCCESS (0.000s) [91.245480] [085/162] (920s left) kms_addfb_basic (size-max) [91.326515] Starting subtest: size-max [91.329567] Subtest size-max: SUCCESS (0.000s) [91.388377] [086/162] (920s left) kms_addfb_basic (small-bo) [91.469679] Starting subtest: small-bo [91.482544] Subtest small-bo: SUCCESS (0.000s) [91.542991] [087/162] (920s left) kms_addfb_basic (tile-pitch-mismatch) [91.594398] Starting subtest: tile-pitch-mismatch [91.598296] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [91.688222] [088/162] (920s left) kms_addfb_basic (too-high) [91.765447] Starting subtest: too-high [91.768628] Subtest too-high: SUCCESS (0.001s) [91.829147] [089/162] (919s left) kms_addfb_basic (too-wide) [91.907705] Starting subtest: too-wide [91.910703] Subtest too-wide: SUCCESS (0.000s) [91.972418] [090/162] (919s left) kms_addfb_basic (unused-handle) [92.024900] Starting subtest: unused-handle [92.028349] Subtest unused-handle: SUCCESS (0.000s) [92.118996] [091/162] (919s left) kms_addfb_basic (unused-modifier) [92.169724] Starting subtest: unused-modifier [92.173692] Subtest unused-modifier: SUCCESS (0.000s) [92.260623] [092/162] (919s left) kms_addfb_basic (unused-offsets) [92.313487] Starting subtest: unused-offsets [92.317630] Subtest unused-offsets: SUCCESS (0.000s) [92.407958] [093/162] (919s left) kms_addfb_basic (unused-pitches) [92.461529] Starting subtest: unused-pitches [92.464968] Subtest unused-pitches: SUCCESS (0.000s) [92.550155] [094/162] (919s left) kms_busy (basic) [92.635586] Subtest basic: SKIP (0.000s) [92.679423] [095/162] (919s left) kms_prop_blob (basic) [92.728212] Starting subtest: basic [92.731237] Subtest basic: SUCCESS (0.000s) [92.775392] [096/162] (919s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [92.874533] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [92.920458] [097/162] (919s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [93.022030] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [93.069054] [098/162] (919s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [93.168249] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s) [93.214852] [099/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [93.315751] Subtest basic-flip-after-cursor-legacy: SKIP (0.000s) [93.362084] [100/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [93.459553] Subtest basic-flip-after-cursor-varying-size: SKIP (0.000s) [93.505093] [101/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [93.602325] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s) [93.647957] [102/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [93.747065] Subtest basic-flip-before-cursor-legacy: SKIP (0.000s) [93.793720] [103/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [93.897862] Subtest basic-flip-before-cursor-varying-size: SKIP (0.000s) [94.078182] [104/162] (918s left) kms_dsc (dsc-basic) [94.588304] Subtest dsc-basic: SKIP (0.000s) [95.002725] [105/162] (917s left) kms_flip (basic-flip-vs-dpms) [95.397225] Starting subtest: basic-flip-vs-dpms [95.403657] Subtest basic-flip-vs-dpms: SKIP (0.003s) [95.448319] [106/162] (917s left) kms_flip (basic-flip-vs-modeset) [95.526840] Starting subtest: basic-flip-vs-modeset [95.534396] Subtest basic-flip-vs-modeset: SKIP (0.001s) [95.578945] [107/162] (917s left) kms_flip (basic-flip-vs-wf_vblank) [95.662445] Starting subtest: basic-flip-vs-wf_vblank [95.668518] Subtest basic-flip-vs-wf_vblank: SKIP (0.001s) [95.713433] [108/162] (917s left) kms_flip (basic-plain-flip) [95.793204] Starting subtest: basic-plain-flip [95.800624] Subtest basic-plain-flip: SKIP (0.001s) [95.865514] [109/162] (917s left) kms_force_connector_basic (force-connector-state) [96.009067] Starting subtest: force-connector-state [96.092340] Subtest force-connector-state: SUCCESS (0.079s) [96.262174] [110/162] (916s left) kms_force_connector_basic (force-edid) [96.411302] Starting subtest: force-edid [96.577985] Subtest force-edid: SUCCESS (0.167s) [96.666878] [111/162] (916s left) kms_force_connector_basic (force-load-detect) [96.818395] Starting subtest: force-load-detect [96.824691] Subtest force-load-detect: SKIP (0.000s) [96.885725] [112/162] (916s left) kms_force_connector_basic (prune-stale-modes) [97.027582] Starting subtest: prune-stale-modes [97.196172] Subtest prune-stale-modes: SUCCESS (0.168s) [97.279660] [113/162] (916s left) kms_frontbuffer_tracking (basic) [97.368449] Subtest basic: SKIP (0.000s) [97.424802] [114/162] (915s left) kms_hdmi_inject (inject-audio) [97.475554] Starting subtest: inject-audio [97.481934] Subtest inject-audio: SKIP (0.000s) [97.526254] [115/162] (915s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [97.608036] Subtest compare-crc-sanitycheck-xr24: SKIP (0.000s) [97.655711] [116/162] (915s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [97.734362] Subtest compare-crc-sanitycheck-nv12: SKIP (0.000s) [97.779341] [117/162] (915s left) kms_pipe_crc_basic (hang-read-crc) [97.857885] Subtest hang-read-crc: SKIP (0.000s) [97.903637] [118/162] (915s left) kms_pipe_crc_basic (nonblocking-crc) [97.992069] Subtest nonblocking-crc: SKIP (0.000s) [98.037172] [119/162] (915s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [98.114173] Subtest nonblocking-crc-frame-sequence: SKIP (0.000s) [98.159192] [120/162] (915s left) kms_pipe_crc_basic (read-crc) [98.240747] Subtest read-crc: SKIP (0.000s) [98.285559] [121/162] (915s left) kms_pipe_crc_basic (read-crc-frame-sequence) [98.364149] Subtest read-crc-frame-sequence: SKIP (0.000s) [98.408642] [122/162] (915s left) kms_pm_backlight (basic-brightness) [98.487564] Subtest basic-brightness: SKIP (0.000s) [98.531469] [123/162] (915s left) kms_pm_rpm (basic-pci-d3-state) [109.843768] Starting subtest: basic-pci-d3-state [110.031262] Subtest basic-pci-d3-state: SUCCESS (0.186s) [110.086349] [124/162] (903s left) kms_pm_rpm (basic-rte) [110.137715] Starting subtest: basic-rte [121.656950] Subtest basic-rte: SUCCESS (11.523s) [121.964187] [125/162] (891s left) kms_psr (psr-primary-page-flip) [122.046677] Subtest psr-primary-page-flip: SKIP (0.000s) [122.091350] [126/162] (891s left) kms_psr (psr-cursor-plane-move) [122.173163] Subtest psr-cursor-plane-move: SKIP (0.000s) [122.216797] [127/162] (891s left) kms_psr (psr-sprite-plane-onoff) [122.299374] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [122.343325] [128/162] (891s left) kms_psr (psr-primary-mmap-gtt) [122.422630] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [122.468224] [129/162] (891s left) kms_setmode (basic-clone-single-crtc) [122.520920] Starting subtest: basic-clone-single-crtc [122.530151] Subtest basic-clone-single-crtc: SKIP (0.000s) [122.577873] [130/162] (891s left) i915_pm_rps (basic-api) [122.635087] Starting subtest: basic-api [122.638339] Subtest basic-api: SUCCESS (0.002s) [122.685678] [131/162] (891s left) prime_self_import (basic-llseek-bad) [122.732529] Starting subtest: basic-llseek-bad [122.738670] Subtest basic-llseek-bad: SUCCESS (0.002s) [122.809163] [132/162] (891s left) prime_self_import (basic-llseek-size) [122.854182] Starting subtest: basic-llseek-size [122.860189] Subtest basic-llseek-size: SUCCESS (0.003s) [122.923417] [133/162] (891s left) prime_self_import (basic-with_fd_dup) [122.969469] Starting subtest: basic-with_fd_dup [122.975526] Subtest basic-with_fd_dup: SUCCESS (0.003s) [123.058534] [134/162] (891s left) prime_self_import (basic-with_one_bo) [123.104160] Starting subtest: basic-with_one_bo [123.110509] Subtest basic-with_one_bo: SUCCESS (0.003s) [123.156459] [135/162] (891s left) prime_self_import (basic-with_one_bo_two_files) [123.205963] Starting subtest: basic-with_one_bo_two_files [123.212118] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [123.284040] [136/162] (891s left) prime_self_import (basic-with_two_bos) [123.331548] Starting subtest: basic-with_two_bos [123.338110] Subtest basic-with_two_bos: SUCCESS (0.003s) [123.406510] [137/162] (891s left) prime_vgem (basic-fence-flip) [123.466129] Starting subtest: basic-fence-flip [123.503073] Subtest basic-fence-flip: SKIP (0.033s) [123.550889] [138/162] (890s left) prime_vgem (basic-fence-mmap) [123.606861] Starting subtest: basic-fence-mmap [123.627060] Subtest basic-fence-mmap: SUCCESS (0.023s) [123.706380] [139/162] (890s left) prime_vgem (basic-fence-read) [123.761451] Starting subtest: basic-fence-read [123.806702] Subtest basic-fence-read: SUCCESS (0.046s) [123.881086] [140/162] (890s left) prime_vgem (basic-gtt) [123.937965] Starting subtest: basic-gtt [123.947050] Subtest basic-gtt: SUCCESS (0.011s) [124.020260] [141/162] (890s left) prime_vgem (basic-read) [124.075894] Starting subtest: basic-read [124.094308] Subtest basic-read: SUCCESS (0.022s) [124.163812] [142/162] (890s left) prime_vgem (basic-write) [124.216845] Starting subtest: basic-write [124.238152] Subtest basic-write: SUCCESS (0.022s) [124.306739] [143/162] (890s left) vgem_basic (setversion) [124.357495] Starting subtest: setversion [124.363997] Subtest setversion: SUCCESS (0.000s) [124.415553] [144/162] (890s left) vgem_basic (create) [124.467685] Starting subtest: create [124.471111] Subtest create: SUCCESS (0.000s) [124.522234] [145/162] (890s left) vgem_basic (debugfs) [124.572336] Starting subtest: debugfs [124.575579] Subtest debugfs: SUCCESS (0.000s) [124.628367] [146/162] (890s left) vgem_basic (dmabuf-export) [124.677196] Starting subtest: dmabuf-export [124.683980] Subtest dmabuf-export: SUCCESS (0.002s) [124.740532] [147/162] (890s left) vgem_basic (dmabuf-fence) [124.794147] Starting subtest: dmabuf-fence [124.797503] Subtest dmabuf-fence: SUCCESS (0.001s) [124.843036] [148/162] (890s left) vgem_basic (dmabuf-fence-before) [124.891506] Starting subtest: dmabuf-fence-before [124.894568] Subtest dmabuf-fence-before: SUCCESS (0.000s) [124.944599] [149/162] (890s left) vgem_basic (dmabuf-mmap) [124.994923] Starting subtest: dmabuf-mmap [125.001782] Subtest dmabuf-mmap: SUCCESS (0.009s) [125.047594] [150/162] (890s left) vgem_basic (mmap) [125.099115] Starting subtest: mmap [125.102181] Subtest mmap: SUCCESS (0.006s) [125.147388] [151/162] (890s left) vgem_basic (second-client) [125.199700] Starting subtest: second-client [125.205758] Subtest second-client: SUCCESS (0.002s) [125.256443] [152/162] (889s left) vgem_basic (sysfs) [125.306094] Starting subtest: sysfs [125.309211] Subtest sysfs: SUCCESS (0.001s) [125.359384] [153/162] (889s left) core_hotunplug (unbind-rebind) [125.471277] Starting subtest: unbind-rebind [128.980266] Subtest unbind-rebind: SUCCESS (3.446s) [129.326069] [154/162] (886s left) vgem_basic (unload) [129.373879] Starting subtest: unload [158.205359] Subtest unload: SUCCESS (28.834s) [158.268831] [155/162] (857s left) i915_module_load (reload) [158.317937] Starting subtest: reload [160.815246] Subtest reload: SUCCESS (2.499s) [160.860987] [156/162] (854s left) gem_lmem_swapping (basic) [160.919634] Subtest basic: SKIP (0.000s) [160.964268] [157/162] (854s left) gem_lmem_swapping (parallel-random-engines) [161.017576] Subtest parallel-random-engines: SKIP (0.000s) [161.060995] [158/162] (854s left) gem_lmem_swapping (random-engines) [161.110208] Subtest random-engines: SKIP (0.000s) [161.155189] [159/162] (854s left) gem_lmem_swapping (verify-random) [161.210158] Subtest verify-random: SKIP (0.000s) [161.253510] [160/162] (854s left) i915_pm_rpm (module-reload) [172.565555] Starting subtest: module-reload [200.215909] Subtest module-reload: SUCCESS (27.654s) [200.247480] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [200.248822] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [200.252932] [161/162] (815s left) i915_selftest (live) [200.303340] Starting subtest: live [200.306675] Starting dynamic subtest: sanitycheck [202.500979] Dynamic subtest sanitycheck: SUCCESS (2.195s) [202.517061] Starting dynamic subtest: uncore [204.730832] Dynamic subtest uncore: SUCCESS (2.225s) [204.746185] Starting dynamic subtest: workarounds [207.096660] Dynamic subtest workarounds: SUCCESS (2.332s) [207.160468] Starting dynamic subtest: gt_engines [209.600795] Dynamic subtest gt_engines: SUCCESS (2.543s) [209.616958] Starting dynamic subtest: gt_timelines [218.317994] Dynamic subtest gt_timelines: SUCCESS (8.720s) [218.329908] Starting dynamic subtest: gt_contexts [220.580537] Dynamic subtest gt_contexts: SUCCESS (2.255s) [220.592435] Starting dynamic subtest: gt_lrc [225.167611] Dynamic subtest gt_lrc: SUCCESS (4.593s) [225.179397] Starting dynamic subtest: gt_mocs [227.390899] Dynamic subtest gt_mocs: SUCCESS (2.220s) [227.402778] Starting dynamic subtest: gt_pm [233.877016] Dynamic subtest gt_pm: SUCCESS (6.487s) [233.888913] Starting dynamic subtest: gt_heartbeat [235.992241] Dynamic subtest gt_heartbeat: SUCCESS (2.103s) [236.005235] Starting dynamic subtest: gt_tlb [239.288907] Dynamic subtest gt_tlb: SUCCESS (3.307s) [239.300902] Starting dynamic subtest: requests [247.632769] Dynamic subtest requests: SUCCESS (8.343s) [247.644376] Starting dynamic subtest: migrate [252.707473] Dynamic subtest migrate: SUCCESS (5.072s) [252.721410] Starting dynamic subtest: active [254.810662] Dynamic subtest active: SUCCESS (2.098s) [254.822395] Starting dynamic subtest: objects [257.432909] Dynamic subtest objects: SUCCESS (2.627s) [257.444773] Starting dynamic subtest: mman [262.823828] Dynamic subtest mman: SUCCESS (5.390s) [262.835470] Starting dynamic subtest: dmabuf [265.020400] Dynamic subtest dmabuf: SUCCESS (2.195s) [265.037058] Starting dynamic subtest: vma [267.230457] Dynamic subtest vma: SUCCESS (2.203s) [267.247434] Starting dynamic subtest: coherency [270.915797] Dynamic subtest coherency: SUCCESS (3.691s) [270.927805] Starting dynamic subtest: gtt [280.409725] Dynamic subtest gtt: SUCCESS (9.493s) [280.421137] Starting dynamic subtest: gem [282.690345] Dynamic subtest gem: SUCCESS (2.271s) [282.704351] Starting dynamic subtest: evict [285.925303] Dynamic subtest evict: SUCCESS (3.241s) [285.938190] Starting dynamic subtest: hugepages [294.406792] Dynamic subtest hugepages: SUCCESS (8.482s) [294.418610] Starting dynamic subtest: gem_contexts [309.054610] Dynamic subtest gem_contexts: SUCCESS (14.647s) [309.066372] Starting dynamic subtest: client [311.261355] Dynamic subtest client: SUCCESS (2.201s) [311.274278] Starting dynamic subtest: gem_migrate [313.340392] Dynamic subtest gem_migrate: SUCCESS (2.081s) [313.352651] Starting dynamic subtest: reset [315.650621] Dynamic subtest reset: SUCCESS (2.306s) [315.662645] Starting dynamic subtest: memory_region [317.730452] Dynamic subtest memory_region: SUCCESS (2.077s) [317.742437] Starting dynamic subtest: hangcheck [342.892919] Dynamic subtest hangcheck: SUCCESS (25.168s) [342.904879] Starting dynamic subtest: execlists [353.128849] Dynamic subtest execlists: SUCCESS (10.235s) [353.140870] Starting dynamic subtest: ring_submission [355.210455] Dynamic subtest ring_submission: SUCCESS (2.078s) [355.222730] Starting dynamic subtest: perf [357.420375] Dynamic subtest perf: SUCCESS (2.203s) [357.434984] Starting dynamic subtest: slpc [359.500190] Dynamic subtest slpc: SUCCESS (2.087s) [359.512096] Starting dynamic subtest: guc [361.584611] Dynamic subtest guc: SUCCESS (2.084s) [361.596712] Starting dynamic subtest: guc_multi_lrc [363.655662] Dynamic subtest guc_multi_lrc: SUCCESS (2.070s) [363.671774] Starting dynamic subtest: guc_hang [365.740445] Dynamic subtest guc_hang: SUCCESS (2.076s) [365.752256] Starting dynamic subtest: late_gt_pm [367.850138] Dynamic subtest late_gt_pm: SUCCESS (2.110s) [367.853297] Subtest live: SUCCESS (167.545s) [367.891159] [162/162] (648s left) dmabuf (all-tests) [367.945067] Starting subtest: all-tests [367.951152] Subtest all-tests: SKIP (0.000s) [367.988297] Closing watchdogs results: parsing output: 0/ for test: i915_module_load results: parsing output: 1/ for test: core_auth results: parsing output: 2/ for test: core_debugfs results: parsing output: 3/ for test: core_sysfs results: parsing output: 4/ for test: fbdev results: parsing output: 5/ for test: fbdev results: parsing output: 6/ for test: fbdev results: parsing output: 7/ for test: fbdev results: parsing output: 8/ for test: fbdev results: parsing output: 9/ for test: gem_basic results: parsing output: 10/ for test: gem_basic results: parsing output: 11/ for test: gem_basic results: parsing output: 12/ for test: gem_busy results: parsing output: 13/ for test: gem_close_race results: parsing output: 14/ for test: gem_close_race results: parsing output: 15/ for test: gem_ctx_create results: parsing output: 16/ for test: gem_ctx_create results: parsing output: 17/ for test: gem_ctx_exec results: parsing output: 18/ for test: gem_exec_basic results: parsing output: 19/ for test: gem_exec_create results: parsing output: 20/ for test: gem_exec_fence results: parsing output: 21/ for test: gem_exec_fence results: parsing output: 22/ for test: gem_exec_fence results: parsing output: 23/ for test: gem_exec_fence results: parsing output: 24/ for test: gem_exec_gttfill results: parsing output: 25/ for test: gem_exec_parallel results: parsing output: 26/ for test: gem_exec_store results: parsing output: 27/ for test: gem_flink_basic results: parsing output: 28/ for test: gem_flink_basic results: parsing output: 29/ for test: gem_flink_basic results: parsing output: 30/ for test: gem_flink_basic results: parsing output: 31/ for test: gem_flink_basic results: parsing output: 32/ for test: gem_huc_copy results: parsing output: 33/ for test: gem_linear_blits results: parsing output: 34/ for test: gem_mmap results: parsing output: 35/ for test: gem_mmap_gtt results: parsing output: 36/ for test: gem_render_linear_blits results: parsing output: 37/ for test: gem_render_tiled_blits results: parsing output: 38/ for test: gem_ringfill results: parsing output: 39/ for test: gem_softpin results: parsing output: 40/ for test: gem_softpin results: parsing output: 41/ for test: gem_softpin results: parsing output: 42/ for test: gem_sync results: parsing output: 43/ for test: gem_sync results: parsing output: 44/ for test: gem_tiled_blits results: parsing output: 45/ for test: gem_tiled_fence_blits results: parsing output: 46/ for test: gem_tiled_pread_basic results: parsing output: 47/ for test: gem_wait results: parsing output: 48/ for test: gem_wait results: parsing output: 49/ for test: i915_getparams_basic results: parsing output: 50/ for test: i915_getparams_basic results: parsing output: 51/ for test: i915_hangman results: parsing output: 52/ for test: i915_pciid results: parsing output: 53/ for test: intel_hwmon results: parsing output: 54/ for test: intel_hwmon results: parsing output: 55/ for test: kms_addfb_basic results: parsing output: 56/ for test: kms_addfb_basic results: parsing output: 57/ for test: kms_addfb_basic results: parsing output: 58/ for test: kms_addfb_basic results: parsing output: 59/ for test: kms_addfb_basic results: parsing output: 60/ for test: kms_addfb_basic results: parsing output: 61/ for test: kms_addfb_basic results: parsing output: 62/ for test: kms_addfb_basic results: parsing output: 63/ for test: kms_addfb_basic results: parsing output: 64/ for test: kms_addfb_basic results: parsing output: 65/ for test: kms_addfb_basic results: parsing output: 66/ for test: kms_addfb_basic results: parsing output: 67/ for test: kms_addfb_basic results: parsing output: 68/ for test: kms_addfb_basic results: parsing output: 69/ for test: kms_addfb_basic results: parsing output: 70/ for test: kms_addfb_basic results: parsing output: 71/ for test: kms_addfb_basic results: parsing output: 72/ for test: kms_addfb_basic results: parsing output: 73/ for test: kms_addfb_basic results: parsing output: 74/ for test: kms_addfb_basic results: parsing output: 75/ for test: kms_addfb_basic results: parsing output: 76/ for test: kms_addfb_basic results: parsing output: 77/ for test: kms_addfb_basic results: parsing output: 78/ for test: kms_addfb_basic results: parsing output: 79/ for test: kms_addfb_basic results: parsing output: 80/ for test: kms_addfb_basic results: parsing output: 81/ for test: kms_addfb_basic results: parsing output: 82/ for test: kms_addfb_basic results: parsing output: 83/ for test: kms_addfb_basic results: parsing output: 84/ for test: kms_addfb_basic results: parsing output: 85/ for test: kms_addfb_basic results: parsing output: 86/ for test: kms_addfb_basic results: parsing output: 87/ for test: kms_addfb_basic results: parsing output: 88/ for test: kms_addfb_basic results: parsing output: 89/ for test: kms_addfb_basic results: parsing output: 90/ for test: kms_addfb_basic results: parsing output: 91/ for test: kms_addfb_basic results: parsing output: 92/ for test: kms_addfb_basic results: parsing output: 93/ for test: kms_busy results: parsing output: 94/ for test: kms_prop_blob results: parsing output: 95/ for test: kms_cursor_legacy results: parsing output: 96/ for test: kms_cursor_legacy results: parsing output: 97/ for test: kms_cursor_legacy results: parsing output: 98/ for test: kms_cursor_legacy results: parsing output: 99/ for test: kms_cursor_legacy results: parsing output: 100/ for test: kms_cursor_legacy results: parsing output: 101/ for test: kms_cursor_legacy results: parsing output: 102/ for test: kms_cursor_legacy results: parsing output: 103/ for test: kms_dsc results: parsing output: 104/ for test: kms_flip results: parsing output: 105/ for test: kms_flip results: parsing output: 106/ for test: kms_flip results: parsing output: 107/ for test: kms_flip results: parsing output: 108/ for test: kms_force_connector_basic results: parsing output: 109/ for test: kms_force_connector_basic results: parsing output: 110/ for test: kms_force_connector_basic results: parsing output: 111/ for test: kms_force_connector_basic results: parsing output: 112/ for test: kms_frontbuffer_tracking results: parsing output: 113/ for test: kms_hdmi_inject results: parsing output: 114/ for test: kms_pipe_crc_basic results: parsing output: 115/ for test: kms_pipe_crc_basic results: parsing output: 116/ for test: kms_pipe_crc_basic results: parsing output: 117/ for test: kms_pipe_crc_basic results: parsing output: 118/ for test: kms_pipe_crc_basic results: parsing output: 119/ for test: kms_pipe_crc_basic results: parsing output: 120/ for test: kms_pipe_crc_basic results: parsing output: 121/ for test: kms_pm_backlight results: parsing output: 122/ for test: kms_pm_rpm results: parsing output: 123/ for test: kms_pm_rpm results: parsing output: 124/ for test: kms_psr results: parsing output: 125/ for test: kms_psr results: parsing output: 126/ for test: kms_psr results: parsing output: 127/ for test: kms_psr results: parsing output: 128/ for test: kms_setmode results: parsing output: 129/ for test: i915_pm_rps results: parsing output: 130/ for test: prime_self_import results: parsing output: 131/ for test: prime_self_import results: parsing output: 132/ for test: prime_self_import results: parsing output: 133/ for test: prime_self_import results: parsing output: 134/ for test: prime_self_import results: parsing output: 135/ for test: prime_self_import results: parsing output: 136/ for test: prime_vgem results: parsing output: 137/ for test: prime_vgem results: parsing output: 138/ for test: prime_vgem results: parsing output: 139/ for test: prime_vgem results: parsing output: 140/ for test: prime_vgem results: parsing output: 141/ for test: prime_vgem results: parsing output: 142/ for test: vgem_basic results: parsing output: 143/ for test: vgem_basic results: parsing output: 144/ for test: vgem_basic results: parsing output: 145/ for test: vgem_basic results: parsing output: 146/ for test: vgem_basic results: parsing output: 147/ for test: vgem_basic results: parsing output: 148/ for test: vgem_basic results: parsing output: 149/ for test: vgem_basic results: parsing output: 150/ for test: vgem_basic results: parsing output: 151/ for test: vgem_basic results: parsing output: 152/ for test: core_hotunplug results: parsing output: 153/ for test: vgem_basic results: parsing output: 154/ for test: i915_module_load results: parsing output: 155/ for test: gem_lmem_swapping results: parsing output: 156/ for test: gem_lmem_swapping results: parsing output: 157/ for test: gem_lmem_swapping results: parsing output: 158/ for test: gem_lmem_swapping results: parsing output: 159/ for test: i915_pm_rpm results: parsing output: 160/ for test: i915_selftest results: parsing output: 161/ for test: dmabuf Done.