[42.011702] Initializing watchdogs [42.011763] /dev/watchdog0 [42.022846] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3e92 Intel Coffeelake (Gen9) CoffeeLake-S GT2 [UHD Graphics 630] [42.029482] [001/161] (960s left) i915_module_load (load) [42.089528] Starting subtest: load [44.194196] Subtest load: SUCCESS (2.131s) [44.237538] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [44.239095] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [44.246950] [002/161] (958s left) core_auth (basic-auth) [44.327268] Starting subtest: basic-auth [44.329989] Subtest basic-auth: SUCCESS (0.000s) [44.403192] [003/161] (958s left) debugfs_test (read_all_entries) [44.456032] Starting subtest: read_all_entries [44.521625] Subtest read_all_entries: SUCCESS (0.067s) [44.683710] [004/161] (957s left) debugfs_test (basic-hwmon) [44.736317] Starting subtest: basic-hwmon [44.742367] Subtest basic-hwmon: SKIP (0.000s) [44.901258] [005/161] (957s left) debugfs_test (sysfs) [44.956392] Starting subtest: sysfs [44.992447] Subtest sysfs: SUCCESS (0.038s) [45.139097] [006/161] (957s left) fbdev (eof) [45.199440] Starting subtest: eof [45.202478] Subtest eof: SUCCESS (0.000s) [45.268598] [007/161] (957s left) fbdev (info) [45.316852] Starting subtest: info [45.320185] Subtest info: SUCCESS (0.000s) [45.396074] [008/161] (957s left) fbdev (nullptr) [45.443446] Starting subtest: nullptr [45.446526] Subtest nullptr: SUCCESS (0.000s) [45.518847] [009/161] (957s left) fbdev (read) [45.575456] Starting subtest: read [45.797460] Subtest read: SUCCESS (0.225s) [45.851419] [010/161] (957s left) fbdev (write) [45.904977] Starting subtest: write [46.020945] Subtest write: SUCCESS (0.117s) [46.085389] [011/161] (956s left) gem_basic (bad-close) [46.135943] Starting subtest: bad-close [46.142209] Subtest bad-close: SUCCESS (0.000s) [46.211631] [012/161] (956s left) gem_basic (create-close) [46.265889] Starting subtest: create-close [46.272404] Subtest create-close: SUCCESS (0.000s) [46.423194] [013/161] (956s left) gem_basic (create-fd-close) [46.473083] Starting subtest: create-fd-close [46.479418] Subtest create-fd-close: SUCCESS (0.000s) [46.639811] [014/161] (956s left) gem_busy (busy@all-engines) [46.698309] Starting subtest: busy [46.701372] Starting dynamic subtest: all-engines [46.728311] Dynamic subtest all-engines: SUCCESS (0.030s) [46.731312] Subtest busy: SUCCESS (0.030s) [47.128871] [015/161] (956s left) gem_close_race (basic-process) [47.189085] Starting subtest: basic-process [47.261786] Subtest basic-process: SUCCESS (0.073s) [47.445704] [016/161] (955s left) gem_close_race (basic-threads) [47.502396] Starting subtest: basic-threads [48.826701] Subtest basic-threads: SUCCESS (1.200s) [49.184904] [017/161] (954s left) gem_ctx_create (basic) [49.652680] Starting subtest: basic [49.806220] Subtest basic: SUCCESS (0.000s) [49.842625] [018/161] (953s left) gem_ctx_create (basic-files) [49.895795] Starting subtest: basic-files [51.908032] Subtest basic-files: SUCCESS (2.023s) [52.198229] [019/161] (951s left) gem_ctx_exec (basic) [52.253293] Starting subtest: basic [52.256405] Subtest basic: SUCCESS (0.003s) [52.436515] [020/161] (951s left) gem_exec_basic (basic) [52.490508] Starting subtest: basic [52.493711] Starting dynamic subtest: rcs0-smem [52.496953] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [52.505269] Starting dynamic subtest: bcs0-smem [52.508530] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [52.516470] Starting dynamic subtest: vcs0-smem [52.519467] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [52.528179] Starting dynamic subtest: vecs0-smem [52.531082] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [52.533919] Subtest basic: SUCCESS (0.003s) [52.748434] [021/161] (950s left) gem_exec_create (basic) [52.805363] Starting subtest: basic [52.808568] Starting dynamic subtest: smem [54.860211] Dynamic subtest smem: SUCCESS (2.062s) [54.863213] Subtest basic: SUCCESS (2.063s) [55.109637] [022/161] (948s left) gem_exec_fence (basic-busy) [55.320984] Starting subtest: basic-busy [55.325535] Starting dynamic subtest: rcs0 [55.353580] Dynamic subtest rcs0: SUCCESS (0.032s) [55.369786] Starting dynamic subtest: bcs0 [55.383350] Dynamic subtest bcs0: SUCCESS (0.030s) [55.402974] Starting dynamic subtest: vcs0 [55.427022] Dynamic subtest vcs0: SUCCESS (0.043s) [55.446614] Starting dynamic subtest: vecs0 [55.473568] Dynamic subtest vecs0: SUCCESS (0.047s) [55.477527] Subtest basic-busy: SUCCESS (0.153s) [56.131522] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [56.135475] [023/161] (947s left) gem_exec_fence (basic-wait) [56.346838] Starting subtest: basic-wait [56.350520] Starting dynamic subtest: rcs0 [56.383214] Dynamic subtest rcs0: SUCCESS (0.037s) [56.397732] Starting dynamic subtest: bcs0 [56.427372] Dynamic subtest bcs0: SUCCESS (0.043s) [56.461148] Starting dynamic subtest: vcs0 [56.478474] Dynamic subtest vcs0: SUCCESS (0.051s) [56.496029] Starting dynamic subtest: vecs0 [56.515553] Dynamic subtest vecs0: SUCCESS (0.037s) [56.519162] Subtest basic-wait: SUCCESS (0.169s) [57.085824] [024/161] (946s left) gem_exec_fence (basic-await) [57.294500] Starting subtest: basic-await [57.298035] Starting dynamic subtest: rcs0 [57.384338] Dynamic subtest rcs0: SUCCESS (0.090s) [57.394090] Starting dynamic subtest: bcs0 [57.462137] Dynamic subtest bcs0: SUCCESS (0.078s) [57.475169] Starting dynamic subtest: vcs0 [57.554066] Dynamic subtest vcs0: SUCCESS (0.092s) [57.565266] Starting dynamic subtest: vecs0 [57.634571] Dynamic subtest vecs0: SUCCESS (0.080s) [57.637959] Subtest basic-await: SUCCESS (0.341s) [58.218358] [025/161] (945s left) gem_exec_fence (nb-await) [58.390591] Starting subtest: nb-await [58.393352] Starting dynamic subtest: rcs0 [58.452859] Dynamic subtest rcs0: SUCCESS (0.061s) [58.463605] Starting dynamic subtest: bcs0 [58.513332] Dynamic subtest bcs0: SUCCESS (0.060s) [58.531315] Starting dynamic subtest: vcs0 [58.577829] Dynamic subtest vcs0: SUCCESS (0.064s) [58.609130] Starting dynamic subtest: vecs0 [58.654522] Dynamic subtest vecs0: SUCCESS (0.076s) [58.658776] Subtest nb-await: SUCCESS (0.264s) [59.216494] [026/161] (944s left) gem_exec_gttfill (basic) [59.273953] Starting subtest: basic [64.691588] Subtest basic: SUCCESS (5.424s) [64.895678] [027/161] (939s left) gem_exec_parallel (engines) [65.054397] Starting subtest: engines [65.057483] Starting dynamic subtest: basic [66.963368] Dynamic subtest basic: SUCCESS (1.910s) [66.972485] Starting dynamic subtest: contexts [69.940351] Dynamic subtest contexts: SUCCESS (2.978s) [69.949519] Starting dynamic subtest: fds [73.079194] Dynamic subtest fds: SUCCESS (3.140s) [73.089142] Starting dynamic subtest: userptr [75.413654] Dynamic subtest userptr: SUCCESS (2.334s) [75.417221] Subtest engines: SUCCESS (10.363s) [75.658309] [028/161] (928s left) gem_exec_store (basic) [75.719876] Starting subtest: basic [75.782633] Subtest basic: SUCCESS (0.068s) [75.998405] [029/161] (928s left) gem_flink_basic (bad-flink) [76.048422] Starting subtest: bad-flink [76.051618] Subtest bad-flink: SUCCESS (0.000s) [76.165271] [030/161] (928s left) gem_flink_basic (bad-open) [76.215405] Starting subtest: bad-open [76.218481] Subtest bad-open: SUCCESS (0.000s) [76.316955] [031/161] (927s left) gem_flink_basic (basic) [76.369321] Starting subtest: basic [76.372589] Subtest basic: SUCCESS (0.000s) [76.535251] [032/161] (927s left) gem_flink_basic (double-flink) [76.583492] Starting subtest: double-flink [76.586754] Subtest double-flink: SUCCESS (0.000s) [76.719891] [033/161] (927s left) gem_flink_basic (flink-lifetime) [76.769651] Starting subtest: flink-lifetime [76.773072] Subtest flink-lifetime: SUCCESS (0.001s) [76.881548] [034/161] (927s left) gem_huc_copy (huc-copy) [76.933916] Starting subtest: huc-copy [76.940625] Subtest huc-copy: SKIP (0.000s) [77.107478] [035/161] (927s left) gem_linear_blits (basic) [77.235766] Starting subtest: basic [77.242626] Subtest basic: SUCCESS (0.006s) [77.449102] [036/161] (927s left) gem_mmap (basic) [77.559651] Starting subtest: basic [77.575948] Subtest basic: SUCCESS (0.000s) [77.723706] [037/161] (926s left) gem_mmap_gtt (basic) [77.775539] Starting subtest: basic [77.778865] Subtest basic: SUCCESS (0.000s) [77.844285] [038/161] (926s left) gem_render_linear_blits (basic) [77.893925] Starting subtest: basic [77.917065] Subtest basic: SUCCESS (0.023s) [78.002857] [039/161] (926s left) gem_render_tiled_blits (basic) [78.056520] Starting subtest: basic [78.077591] Subtest basic: SUCCESS (0.026s) [78.146361] [040/161] (926s left) gem_ringfill (basic-all) [78.368049] Starting subtest: basic-all [79.547823] Subtest basic-all: SUCCESS (1.133s) [79.896807] [041/161] (925s left) gem_softpin (allocator-basic) [79.977310] Starting subtest: allocator-basic [80.062314] Subtest allocator-basic: SUCCESS (0.083s) [80.236134] [042/161] (924s left) gem_softpin (allocator-basic-reserve) [80.285563] Starting subtest: allocator-basic-reserve [80.369245] Subtest allocator-basic-reserve: SUCCESS (0.084s) [80.583115] [043/161] (924s left) gem_softpin (safe-alignment) [80.641663] Starting subtest: safe-alignment [80.650053] Subtest safe-alignment: SUCCESS (0.009s) [80.837091] [044/161] (924s left) gem_sync (basic-all) [80.911937] Starting subtest: basic-all [83.128328] Subtest basic-all: SUCCESS (2.234s) [83.312768] [045/161] (922s left) gem_sync (basic-each) [83.387152] Starting subtest: basic-each [85.485304] Subtest basic-each: SUCCESS (2.116s) [85.753724] [046/161] (919s left) gem_tiled_blits (basic) [85.871095] Starting subtest: basic [85.886487] Subtest basic: SUCCESS (0.016s) [86.079521] [047/161] (919s left) gem_tiled_fence_blits (basic) [86.245190] Starting subtest: basic [86.253353] Subtest basic: SUCCESS (0.008s) [86.457593] [048/161] (919s left) gem_tiled_pread_basic [86.778920] [049/161] (918s left) gem_wait (busy@all-engines) [86.841187] Starting subtest: busy [86.844356] Starting dynamic subtest: all-engines [87.366141] Dynamic subtest all-engines: SUCCESS (0.525s) [87.369448] Subtest busy: SUCCESS (0.525s) [87.573429] [050/161] (918s left) gem_wait (wait@all-engines) [87.634345] Starting subtest: wait [87.637496] Starting dynamic subtest: all-engines [88.685855] Dynamic subtest all-engines: SUCCESS (1.050s) [88.689143] Subtest wait: SUCCESS (1.051s) [88.920081] [051/161] (916s left) i915_getparams_basic (basic-eu-total) [88.973797] Starting subtest: basic-eu-total [88.979435] Subtest basic-eu-total: SUCCESS (0.000s) [89.057365] [052/161] (916s left) i915_getparams_basic (basic-subslice-total) [89.104531] Starting subtest: basic-subslice-total [89.110173] Subtest basic-subslice-total: SUCCESS (0.000s) [89.250247] [053/161] (916s left) i915_hangman (error-state-basic) [89.306161] Starting subtest: error-state-basic [89.312342] Subtest error-state-basic: SUCCESS (0.005s) [89.506864] [054/161] (916s left) i915_pciid [89.651755] [055/161] (916s left) kms_addfb_basic (addfb25-4-tiled) [89.776954] Starting subtest: addfb25-4-tiled [89.790116] Subtest addfb25-4-tiled: SUCCESS (0.000s) [89.875376] [056/161] (916s left) kms_addfb_basic (addfb25-bad-modifier) [89.993100] Starting subtest: addfb25-bad-modifier [89.996231] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [90.130308] [057/161] (916s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [90.247271] Starting subtest: addfb25-framebuffer-vs-set-tiling [90.250326] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [90.408619] [058/161] (915s left) kms_addfb_basic (addfb25-modifier-no-flag) [90.524886] Starting subtest: addfb25-modifier-no-flag [90.528042] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [90.704397] [059/161] (915s left) kms_addfb_basic (addfb25-x-tiled-legacy) [90.819499] Starting subtest: addfb25-x-tiled-legacy [90.822519] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [90.937941] [060/161] (915s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [91.057572] Starting subtest: addfb25-x-tiled-mismatch-legacy [91.060547] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [91.203371] [061/161] (915s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [91.345426] Starting subtest: addfb25-yf-tiled-legacy [91.348381] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [91.468296] [062/161] (915s left) kms_addfb_basic (addfb25-y-tiled-legacy) [91.602281] Starting subtest: addfb25-y-tiled-legacy [91.605142] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [91.726957] [063/161] (914s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [91.843504] Starting subtest: addfb25-y-tiled-small-legacy [91.846412] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [91.951931] [064/161] (914s left) kms_addfb_basic (bad-pitch-0) [92.001357] Starting subtest: bad-pitch-0 [92.004436] Subtest bad-pitch-0: SUCCESS (0.000s) [92.190662] [065/161] (914s left) kms_addfb_basic (bad-pitch-1024) [92.240384] Starting subtest: bad-pitch-1024 [92.243355] Subtest bad-pitch-1024: SUCCESS (0.000s) [92.454655] [066/161] (914s left) kms_addfb_basic (bad-pitch-128) [92.504681] Starting subtest: bad-pitch-128 [92.507787] Subtest bad-pitch-128: SUCCESS (0.000s) [92.692251] [067/161] (914s left) kms_addfb_basic (bad-pitch-256) [92.742066] Starting subtest: bad-pitch-256 [92.745280] Subtest bad-pitch-256: SUCCESS (0.000s) [92.948468] [068/161] (913s left) kms_addfb_basic (bad-pitch-32) [93.000082] Starting subtest: bad-pitch-32 [93.003210] Subtest bad-pitch-32: SUCCESS (0.000s) [93.227489] [069/161] (913s left) kms_addfb_basic (bad-pitch-63) [93.278505] Starting subtest: bad-pitch-63 [93.281505] Subtest bad-pitch-63: SUCCESS (0.000s) [93.490599] [070/161] (913s left) kms_addfb_basic (bad-pitch-65536) [93.542666] Starting subtest: bad-pitch-65536 [93.545476] Subtest bad-pitch-65536: SUCCESS (0.000s) [93.754468] [071/161] (913s left) kms_addfb_basic (bad-pitch-999) [93.802203] Starting subtest: bad-pitch-999 [93.805264] Subtest bad-pitch-999: SUCCESS (0.000s) [94.057351] [072/161] (912s left) kms_addfb_basic (basic) [94.107649] Starting subtest: basic [94.110476] Subtest basic: SUCCESS (0.000s) [94.284820] [073/161] (912s left) kms_addfb_basic (basic-x-tiled-legacy) [94.338197] Starting subtest: basic-x-tiled-legacy [94.341208] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [94.517914] [074/161] (912s left) kms_addfb_basic (basic-y-tiled-legacy) [94.569557] Starting subtest: basic-y-tiled-legacy [94.572520] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [94.816518] [075/161] (912s left) kms_addfb_basic (bo-too-small) [94.936353] Starting subtest: bo-too-small [94.939421] Subtest bo-too-small: SUCCESS (0.000s) [95.055543] [076/161] (912s left) kms_addfb_basic (bo-too-small-due-to-tiling) [95.172740] Starting subtest: bo-too-small-due-to-tiling [95.175950] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [95.290690] [077/161] (912s left) kms_addfb_basic (clobberred-modifier) [95.343228] Starting subtest: clobberred-modifier [95.346529] Subtest clobberred-modifier: SUCCESS (0.000s) [95.547018] [078/161] (911s left) kms_addfb_basic (framebuffer-vs-set-tiling) [95.599222] Starting subtest: framebuffer-vs-set-tiling [95.602186] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [95.838119] [079/161] (911s left) kms_addfb_basic (invalid-get-prop) [95.893794] Starting subtest: invalid-get-prop [95.896885] Subtest invalid-get-prop: SUCCESS (0.000s) [96.108505] [080/161] (911s left) kms_addfb_basic (invalid-get-prop-any) [96.160475] Starting subtest: invalid-get-prop-any [96.163453] Subtest invalid-get-prop-any: SUCCESS (0.000s) [96.406947] [081/161] (911s left) kms_addfb_basic (invalid-set-prop) [96.460509] Starting subtest: invalid-set-prop [96.463488] Subtest invalid-set-prop: SUCCESS (0.000s) [96.636019] [082/161] (911s left) kms_addfb_basic (invalid-set-prop-any) [96.690518] Starting subtest: invalid-set-prop-any [96.693496] Subtest invalid-set-prop-any: SUCCESS (0.000s) [96.918154] [083/161] (910s left) kms_addfb_basic (no-handle) [96.978483] Starting subtest: no-handle [96.982291] Subtest no-handle: SUCCESS (0.000s) [97.167084] [084/161] (910s left) kms_addfb_basic (size-max) [97.282437] Starting subtest: size-max [97.284993] Subtest size-max: SUCCESS (0.000s) [97.443403] [085/161] (910s left) kms_addfb_basic (small-bo) [97.559722] Starting subtest: small-bo [97.562403] Subtest small-bo: SUCCESS (0.000s) [97.696876] [086/161] (910s left) kms_addfb_basic (tile-pitch-mismatch) [97.751227] Starting subtest: tile-pitch-mismatch [97.754209] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [98.008443] [087/161] (910s left) kms_addfb_basic (too-high) [98.164362] Starting subtest: too-high [98.167147] Subtest too-high: SUCCESS (0.001s) [98.261959] [088/161] (909s left) kms_addfb_basic (too-wide) [98.379492] Starting subtest: too-wide [98.382396] Subtest too-wide: SUCCESS (0.000s) [98.557630] [089/161] (909s left) kms_addfb_basic (unused-handle) [98.610715] Starting subtest: unused-handle [98.613565] Subtest unused-handle: SUCCESS (0.000s) [98.857284] [090/161] (909s left) kms_addfb_basic (unused-modifier) [98.909218] Starting subtest: unused-modifier [98.912286] Subtest unused-modifier: SUCCESS (0.000s) [99.117262] [091/161] (909s left) kms_addfb_basic (unused-offsets) [99.171227] Starting subtest: unused-offsets [99.174012] Subtest unused-offsets: SUCCESS (0.000s) [99.355085] [092/161] (909s left) kms_addfb_basic (unused-pitches) [99.403619] Starting subtest: unused-pitches [99.406411] Subtest unused-pitches: SUCCESS (0.000s) [99.583786] [093/161] (908s left) kms_busy (basic) [99.700416] Starting subtest: basic [99.703090] Starting dynamic subtest: flip [100.394600] Dynamic subtest flip: SUCCESS (0.629s) [100.459736] Starting dynamic subtest: modeset [101.112740] Dynamic subtest modeset: SUCCESS (0.708s) [101.178135] Subtest basic: SUCCESS (1.339s) [101.559868] [094/161] (907s left) kms_prop_blob (basic) [101.645464] Starting subtest: basic [101.648809] Subtest basic: SUCCESS (0.000s) [101.753692] [095/161] (907s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [101.869943] Starting subtest: basic-busy-flip-before-cursor-atomic [101.876229] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [101.967713] [096/161] (906s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [102.083316] Starting subtest: basic-busy-flip-before-cursor-legacy [102.089870] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [102.184544] [097/161] (906s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [102.301772] Starting subtest: basic-flip-after-cursor-atomic [103.236086] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.930s) [103.460265] [098/161] (905s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [103.574665] Starting subtest: basic-flip-after-cursor-legacy [104.517474] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.936s) [104.699843] [099/161] (904s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [104.815714] Starting subtest: basic-flip-after-cursor-varying-size [105.746312] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.926s) [105.980426] [100/161] (903s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [106.095579] Starting subtest: basic-flip-before-cursor-atomic [107.026059] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.927s) [107.237268] [101/161] (902s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [107.352811] Starting subtest: basic-flip-before-cursor-legacy [108.285456] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.927s) [108.437984] [102/161] (900s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [108.554753] Starting subtest: basic-flip-before-cursor-varying-size [109.485530] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.927s) [109.691442] [103/161] (899s left) kms_dsc (dsc-basic) [109.808171] Subtest dsc-basic: SKIP (0.000s) [109.847963] [104/161] (899s left) kms_flip (basic-flip-vs-dpms) [109.966198] Starting subtest: basic-flip-vs-dpms [109.969183] Starting dynamic subtest: A-HDMI-A1 [110.915342] Dynamic subtest A-HDMI-A1: SUCCESS (0.948s) [110.925320] Starting dynamic subtest: B-HDMI-A1 [111.787737] Dynamic subtest B-HDMI-A1: SUCCESS (0.873s) [111.799553] Starting dynamic subtest: C-HDMI-A1 [112.704365] Dynamic subtest C-HDMI-A1: SUCCESS (0.912s) [112.707212] Subtest basic-flip-vs-dpms: SUCCESS (2.740s) [112.864606] [105/161] (896s left) kms_flip (basic-flip-vs-modeset) [112.991522] Starting subtest: basic-flip-vs-modeset [112.994236] Starting dynamic subtest: A-HDMI-A1 [113.922699] Dynamic subtest A-HDMI-A1: SUCCESS (0.930s) [113.934769] Starting dynamic subtest: B-HDMI-A1 [114.818870] Dynamic subtest B-HDMI-A1: SUCCESS (0.895s) [114.829984] Starting dynamic subtest: C-HDMI-A1 [115.692447] Dynamic subtest C-HDMI-A1: SUCCESS (0.872s) [115.695142] Subtest basic-flip-vs-modeset: SUCCESS (2.704s) [115.838939] [106/161] (893s left) kms_flip (basic-flip-vs-wf_vblank) [115.966398] Starting subtest: basic-flip-vs-wf_vblank [115.969027] Starting dynamic subtest: A-HDMI-A1 [117.238829] Dynamic subtest A-HDMI-A1: SUCCESS (1.171s) [117.304635] Starting dynamic subtest: B-HDMI-A1 [118.283758] Dynamic subtest B-HDMI-A1: SUCCESS (1.145s) [118.294160] Starting dynamic subtest: C-HDMI-A1 [119.442051] Dynamic subtest C-HDMI-A1: SUCCESS (1.154s) [119.445202] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.476s) [119.634371] [107/161] (889s left) kms_flip (basic-plain-flip) [119.752338] Starting subtest: basic-plain-flip [119.755386] Starting dynamic subtest: A-HDMI-A1 [120.640803] Dynamic subtest A-HDMI-A1: SUCCESS (0.879s) [120.655147] Starting dynamic subtest: B-HDMI-A1 [121.517292] Dynamic subtest B-HDMI-A1: SUCCESS (0.877s) [121.532945] Starting dynamic subtest: C-HDMI-A1 [122.387388] Dynamic subtest C-HDMI-A1: SUCCESS (0.861s) [122.390436] Subtest basic-plain-flip: SUCCESS (2.639s) [122.631169] [108/161] (886s left) kms_force_connector_basic (force-connector-state) [122.784512] Starting subtest: force-connector-state [123.140150] Subtest force-connector-state: SUCCESS (0.354s) [123.470518] [109/161] (886s left) kms_force_connector_basic (force-edid) [123.621457] Starting subtest: force-edid [123.761460] Subtest force-edid: SUCCESS (0.140s) [123.993061] [110/161] (885s left) kms_force_connector_basic (force-load-detect) [124.146531] Starting subtest: force-load-detect [124.152439] Subtest force-load-detect: SKIP (0.000s) [124.368015] [111/161] (885s left) kms_force_connector_basic (prune-stale-modes) [124.515558] Starting subtest: prune-stale-modes [124.656474] Subtest prune-stale-modes: SUCCESS (0.140s) [124.859718] [112/161] (884s left) kms_frontbuffer_tracking (basic) [124.990857] Starting subtest: basic [127.582719] Subtest basic: SUCCESS (2.602s) [127.757142] [113/161] (882s left) kms_hdmi_inject (inject-audio) [127.870643] Starting subtest: inject-audio [127.986552] Subtest inject-audio: SUCCESS (0.116s) [128.189195] [114/161] (881s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [128.314625] Starting subtest: compare-crc-sanitycheck-xr24 [128.317530] Starting dynamic subtest: pipe-A-HDMI-A-1 [128.731259] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.414s) [128.741472] Starting dynamic subtest: pipe-B-HDMI-A-1 [129.108518] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.374s) [129.119641] Starting dynamic subtest: pipe-C-HDMI-A-1 [129.502426] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.393s) [129.505705] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.187s) [129.679254] [115/161] (880s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [129.796516] Starting subtest: compare-crc-sanitycheck-nv12 [129.799653] Starting dynamic subtest: pipe-A-HDMI-A-1 [130.274228] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.474s) [130.283857] Starting dynamic subtest: pipe-B-HDMI-A-1 [130.680117] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.405s) [130.690218] Starting dynamic subtest: pipe-C-HDMI-A-1 [130.696770] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [130.700118] Subtest compare-crc-sanitycheck-nv12: SUCCESS (0.884s) [130.839367] [116/161] (879s left) kms_pipe_crc_basic (hang-read-crc) [130.956467] Starting subtest: hang-read-crc [130.959609] Starting dynamic subtest: pipe-A-HDMI-A-1 [132.019532] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.061s) [132.029827] Starting dynamic subtest: pipe-B-HDMI-A-1 [132.941170] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.920s) [132.954730] Starting dynamic subtest: pipe-C-HDMI-A-1 [133.924867] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.983s) [133.927436] Subtest hang-read-crc: SUCCESS (2.969s) [134.066825] [117/161] (876s left) kms_pipe_crc_basic (nonblocking-crc) [134.185210] Starting subtest: nonblocking-crc [134.187852] Starting dynamic subtest: pipe-A-HDMI-A-1 [134.780147] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.592s) [134.791792] Starting dynamic subtest: pipe-B-HDMI-A-1 [135.317551] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.535s) [135.327653] Starting dynamic subtest: pipe-C-HDMI-A-1 [135.854613] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.536s) [135.857653] Subtest nonblocking-crc: SUCCESS (1.668s) [135.996673] [118/161] (874s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [136.114319] Starting subtest: nonblocking-crc-frame-sequence [136.116985] Starting dynamic subtest: pipe-A-HDMI-A-1 [136.678106] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.561s) [136.703308] Starting dynamic subtest: pipe-B-HDMI-A-1 [137.223735] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.543s) [137.242653] Starting dynamic subtest: pipe-C-HDMI-A-1 [137.728449] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.504s) [137.731552] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.613s) [137.877528] [119/161] (872s left) kms_pipe_crc_basic (read-crc) [137.994104] Starting subtest: read-crc [137.996696] Starting dynamic subtest: pipe-A-HDMI-A-1 [138.497786] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.501s) [138.510165] Starting dynamic subtest: pipe-B-HDMI-A-1 [139.007486] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.508s) [139.018070] Starting dynamic subtest: pipe-C-HDMI-A-1 [139.478794] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.469s) [139.481830] Subtest read-crc: SUCCESS (1.483s) [139.615510] [120/161] (870s left) kms_pipe_crc_basic (read-crc-frame-sequence) [139.730111] Starting subtest: read-crc-frame-sequence [139.732716] Starting dynamic subtest: pipe-A-HDMI-A-1 [140.256965] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.524s) [140.270455] Starting dynamic subtest: pipe-B-HDMI-A-1 [140.703644] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.445s) [140.715985] Starting dynamic subtest: pipe-C-HDMI-A-1 [141.181988] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.475s) [141.185143] Subtest read-crc-frame-sequence: SUCCESS (1.450s) [141.324329] [121/161] (868s left) kms_pm_backlight (basic-brightness) [141.446465] Subtest basic-brightness: SKIP (0.000s) [141.502878] [122/161] (868s left) kms_pm_rpm (basic-pci-d3-state) [143.966396] Starting subtest: basic-pci-d3-state [144.312231] Subtest basic-pci-d3-state: SUCCESS (0.344s) [144.480710] [123/161] (865s left) kms_pm_rpm (basic-rte) [144.529539] Starting subtest: basic-rte [147.264718] Subtest basic-rte: SUCCESS (2.736s) [148.908594] [124/161] (861s left) kms_psr (psr-primary-page-flip) [149.029988] Subtest psr-primary-page-flip: SKIP (0.000s) [149.084765] [125/161] (861s left) kms_psr (psr-cursor-plane-move) [149.207923] Subtest psr-cursor-plane-move: SKIP (0.000s) [149.250463] [126/161] (861s left) kms_psr (psr-sprite-plane-onoff) [149.370020] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [149.418213] [127/161] (861s left) kms_psr (psr-primary-mmap-gtt) [149.537053] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [149.583914] [128/161] (861s left) kms_setmode (basic-clone-single-crtc) [149.637210] Starting subtest: basic-clone-single-crtc [149.646474] Subtest basic-clone-single-crtc: SKIP (0.000s) [149.749081] [129/161] (860s left) i915_pm_rps (basic-api) [149.803080] Starting subtest: basic-api [149.806610] Subtest basic-api: SUCCESS (0.002s) [149.902481] [130/161] (860s left) prime_self_import (basic-llseek-bad) [149.953191] Starting subtest: basic-llseek-bad [149.959709] Subtest basic-llseek-bad: SUCCESS (0.001s) [150.136883] [131/161] (860s left) prime_self_import (basic-llseek-size) [150.184828] Starting subtest: basic-llseek-size [150.192362] Subtest basic-llseek-size: SUCCESS (0.002s) [150.348499] [132/161] (860s left) prime_self_import (basic-with_fd_dup) [150.396613] Starting subtest: basic-with_fd_dup [150.403737] Subtest basic-with_fd_dup: SUCCESS (0.002s) [150.555362] [133/161] (860s left) prime_self_import (basic-with_one_bo) [150.606604] Starting subtest: basic-with_one_bo [150.616581] Subtest basic-with_one_bo: SUCCESS (0.002s) [150.726840] [134/161] (860s left) prime_self_import (basic-with_one_bo_two_files) [150.773837] Starting subtest: basic-with_one_bo_two_files [150.780430] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [150.948271] [135/161] (860s left) prime_self_import (basic-with_two_bos) [150.995193] Starting subtest: basic-with_two_bos [151.001623] Subtest basic-with_two_bos: SUCCESS (0.002s) [151.163396] [136/161] (860s left) prime_vgem (basic-fence-flip) [151.222807] Starting subtest: basic-fence-flip [151.752244] Subtest basic-fence-flip: SUCCESS (0.428s) [152.108690] [137/161] (859s left) prime_vgem (basic-fence-mmap) [152.568579] Starting subtest: basic-fence-mmap [152.634214] Subtest basic-fence-mmap: SUCCESS (0.019s) [152.927681] [138/161] (858s left) prime_vgem (basic-fence-read) [153.000933] Starting subtest: basic-fence-read [153.043238] Subtest basic-fence-read: SUCCESS (0.042s) [153.213156] [139/161] (858s left) prime_vgem (basic-gtt) [153.288033] Starting subtest: basic-gtt [153.295526] Subtest basic-gtt: SUCCESS (0.008s) [153.454726] [140/161] (858s left) prime_vgem (basic-read) [153.537593] Starting subtest: basic-read [153.559098] Subtest basic-read: SUCCESS (0.021s) [153.741783] [141/161] (858s left) prime_vgem (basic-write) [153.806220] Starting subtest: basic-write [153.824336] Subtest basic-write: SUCCESS (0.019s) [154.013854] [142/161] (857s left) vgem_basic (setversion) [154.070335] Starting subtest: setversion [154.076692] Subtest setversion: SUCCESS (0.000s) [154.139740] [143/161] (857s left) vgem_basic (create) [154.203322] Starting subtest: create [154.206627] Subtest create: SUCCESS (0.000s) [154.287547] [144/161] (857s left) vgem_basic (debugfs) [154.362641] Starting subtest: debugfs [154.366825] Subtest debugfs: SUCCESS (0.002s) [154.475992] [145/161] (857s left) vgem_basic (dmabuf-export) [154.536690] Starting subtest: dmabuf-export [154.548949] Subtest dmabuf-export: SUCCESS (0.015s) [154.706809] [146/161] (857s left) vgem_basic (dmabuf-fence) [154.769792] Starting subtest: dmabuf-fence [154.773200] Subtest dmabuf-fence: SUCCESS (0.000s) [154.865929] [147/161] (857s left) vgem_basic (dmabuf-fence-before) [154.920246] Starting subtest: dmabuf-fence-before [154.923706] Subtest dmabuf-fence-before: SUCCESS (0.000s) [155.074314] [148/161] (857s left) vgem_basic (dmabuf-mmap) [155.136331] Starting subtest: dmabuf-mmap [155.142863] Subtest dmabuf-mmap: SUCCESS (0.008s) [155.246396] [149/161] (857s left) vgem_basic (mmap) [155.304359] Starting subtest: mmap [155.307584] Subtest mmap: SUCCESS (0.005s) [155.425311] [150/161] (857s left) vgem_basic (second-client) [155.486955] Starting subtest: second-client [155.520120] Subtest second-client: SUCCESS (0.033s) [155.641908] [151/161] (856s left) vgem_basic (sysfs) [155.703269] Starting subtest: sysfs [155.706368] Subtest sysfs: SUCCESS (0.000s) [155.779732] [152/161] (856s left) core_hotunplug (unbind-rebind) [155.905037] Starting subtest: unbind-rebind [160.222871] Subtest unbind-rebind: SUCCESS (4.322s) [160.259969] [153/161] (852s left) vgem_basic (unload) [160.310613] Starting subtest: unload [189.422491] Subtest unload: SUCCESS (29.113s) [189.536643] [154/161] (823s left) i915_module_load (reload) [189.586136] Starting subtest: reload [193.452231] Subtest reload: SUCCESS (3.868s) [193.490968] [FACT i915_module_load (reload)] deleted: kernel.kmod_is_loaded.vgem: true [193.498049] [155/161] (819s left) gem_lmem_swapping (basic) [193.568316] Subtest basic: SKIP (0.000s) [193.615962] [156/161] (819s left) gem_lmem_swapping (parallel-random-engines) [193.668571] Subtest parallel-random-engines: SKIP (0.000s) [193.720820] [157/161] (819s left) gem_lmem_swapping (random-engines) [193.777475] Subtest random-engines: SKIP (0.000s) [193.821100] [158/161] (819s left) gem_lmem_swapping (verify-random) [193.880433] Subtest verify-random: SKIP (0.000s) [193.922591] [159/161] (819s left) i915_pm_rpm (module-reload) [196.606430] Starting subtest: module-reload [207.470940] Subtest module-reload: SUCCESS (10.864s) [207.517793] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [207.521989] [160/161] (805s left) i915_selftest (live) [207.583106] Starting subtest: live [207.586220] Starting dynamic subtest: sanitycheck [209.986574] Dynamic subtest sanitycheck: SUCCESS (2.402s) [210.006379] Starting dynamic subtest: uncore [212.326620] Dynamic subtest uncore: SUCCESS (2.331s) [212.335389] Starting dynamic subtest: workarounds [214.896620] Dynamic subtest workarounds: SUCCESS (2.570s) [214.905743] Starting dynamic subtest: gt_engines [217.612426] Dynamic subtest gt_engines: SUCCESS (2.723s) [217.621925] Starting dynamic subtest: gt_timelines [225.862973] Dynamic subtest gt_timelines: SUCCESS (8.250s) [225.873608] Starting dynamic subtest: gt_contexts [228.336839] Dynamic subtest gt_contexts: SUCCESS (2.464s) [228.345388] Starting dynamic subtest: gt_lrc [233.019624] Dynamic subtest gt_lrc: SUCCESS (4.690s) [233.029846] Starting dynamic subtest: gt_mocs [235.426974] Dynamic subtest gt_mocs: SUCCESS (2.398s) [235.437256] Starting dynamic subtest: gt_pm [242.169445] Dynamic subtest gt_pm: SUCCESS (6.750s) [242.179712] Starting dynamic subtest: gt_heartbeat [244.547148] Dynamic subtest gt_heartbeat: SUCCESS (2.368s) [244.556408] Starting dynamic subtest: gt_tlb [247.667126] Dynamic subtest gt_tlb: SUCCESS (3.121s) [247.675732] Starting dynamic subtest: requests [256.303745] Dynamic subtest requests: SUCCESS (8.642s) [256.313773] Starting dynamic subtest: migrate [262.160933] Dynamic subtest migrate: SUCCESS (5.856s) [262.171274] Starting dynamic subtest: active [264.487445] Dynamic subtest active: SUCCESS (2.322s) [264.497102] Starting dynamic subtest: objects [267.328684] Dynamic subtest objects: SUCCESS (2.844s) [267.338149] Starting dynamic subtest: mman [272.812441] Dynamic subtest mman: SUCCESS (5.483s) [272.823157] Starting dynamic subtest: dmabuf [275.268445] Dynamic subtest dmabuf: SUCCESS (2.455s) [275.277551] Starting dynamic subtest: vma [277.707345] Dynamic subtest vma: SUCCESS (2.435s) [277.715868] Starting dynamic subtest: coherency [280.698709] Dynamic subtest coherency: SUCCESS (2.993s) [280.712181] Starting dynamic subtest: gtt [290.366444] Dynamic subtest gtt: SUCCESS (9.667s) [290.375812] Starting dynamic subtest: gem [292.907584] Dynamic subtest gem: SUCCESS (2.537s) [292.915772] Starting dynamic subtest: evict [296.183357] Dynamic subtest evict: SUCCESS (3.276s) [296.194730] Starting dynamic subtest: hugepages [304.563319] Dynamic subtest hugepages: SUCCESS (8.380s) [304.572414] Starting dynamic subtest: gem_contexts [319.102231] Dynamic subtest gem_contexts: SUCCESS (14.537s) [319.116353] Starting dynamic subtest: client [321.517964] Dynamic subtest client: SUCCESS (2.406s) [321.528751] Starting dynamic subtest: gem_migrate [323.857815] Dynamic subtest gem_migrate: SUCCESS (2.340s) [323.867111] Starting dynamic subtest: reset [326.387804] Dynamic subtest reset: SUCCESS (2.526s) [326.396377] Starting dynamic subtest: memory_region [328.758027] Dynamic subtest memory_region: SUCCESS (2.368s) [328.766514] Starting dynamic subtest: hangcheck [354.393766] Dynamic subtest hangcheck: SUCCESS (25.644s) [354.402359] Starting dynamic subtest: execlists [365.320701] Dynamic subtest execlists: SUCCESS (10.926s) [365.341803] Starting dynamic subtest: ring_submission [367.628354] Dynamic subtest ring_submission: SUCCESS (2.305s) [367.639440] Starting dynamic subtest: perf [370.048572] Dynamic subtest perf: SUCCESS (2.413s) [370.059107] Starting dynamic subtest: slpc [372.465477] Dynamic subtest slpc: SUCCESS (2.377s) [372.531738] Starting dynamic subtest: guc [374.808694] Dynamic subtest guc: SUCCESS (2.383s) [374.823568] Starting dynamic subtest: guc_multi_lrc [377.154643] Dynamic subtest guc_multi_lrc: SUCCESS (2.349s) [377.163583] Starting dynamic subtest: guc_hang [379.508587] Dynamic subtest guc_hang: SUCCESS (2.345s) [379.517041] Starting dynamic subtest: late_gt_pm [382.018575] Dynamic subtest late_gt_pm: SUCCESS (2.515s) [382.022032] Subtest live: SUCCESS (174.437s) [382.342097] [161/161] (630s left) dmabuf (all-tests) [382.406200] Starting subtest: all-tests [382.409405] Starting dynamic subtest: sanitycheck [382.503040] Dynamic subtest sanitycheck: SUCCESS (0.097s) [382.520937] Starting dynamic subtest: dma_fence [382.738391] Dynamic subtest dma_fence: SUCCESS (0.234s) [382.776462] Starting dynamic subtest: dma_fence_chain [392.002212] Dynamic subtest dma_fence_chain: SUCCESS (9.258s) [392.015229] Starting dynamic subtest: dma_fence_unwrap [392.103767] Dynamic subtest dma_fence_unwrap: SUCCESS (0.098s) [392.127331] Starting dynamic subtest: dma_resv [392.221821] Dynamic subtest dma_resv: SUCCESS (0.114s) [392.226253] Subtest all-tests: SUCCESS (9.818s) [392.263772] Closing watchdogs Done.