[38.860310] Initializing watchdogs [38.860386] /dev/watchdog0 [38.875114] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:591d Intel Kabylake (Gen9) HD Graphics P630 [38.884034] [001/162] (960s left) i915_module_load (load) [38.951761] Starting subtest: load [41.006015] Subtest load: SUCCESS (2.056s) [41.039455] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [41.040667] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [41.045514] [002/162] (958s left) core_auth (basic-auth) [41.114764] Starting subtest: basic-auth [41.117863] Subtest basic-auth: SUCCESS (0.001s) [41.156088] [003/162] (958s left) core_debugfs (read-all-entries) [41.205447] Starting subtest: read-all-entries [41.208492] Subtest read-all-entries: SUCCESS (0.002s) [41.246370] [004/162] (958s left) core_sysfs (read-all-entries) [41.295403] Starting subtest: read-all-entries [41.299107] Subtest read-all-entries: SUCCESS (0.003s) [41.340237] [005/162] (958s left) fbdev (eof) [41.393516] Subtest eof: SKIP (0.000s) [41.431829] [006/162] (958s left) fbdev (info) [41.483013] Subtest info: SKIP (0.000s) [41.520104] [007/162] (958s left) fbdev (nullptr) [41.574217] Subtest nullptr: SKIP (0.000s) [41.612794] [008/162] (958s left) fbdev (read) [41.663776] Subtest read: SKIP (0.000s) [41.701589] [009/162] (958s left) fbdev (write) [41.755520] Subtest write: SKIP (0.000s) [41.796208] [010/162] (957s left) gem_basic (bad-close) [41.843205] Starting subtest: bad-close [41.849753] Subtest bad-close: SUCCESS (0.000s) [41.891807] [011/162] (957s left) gem_basic (create-close) [41.941770] Starting subtest: create-close [41.948235] Subtest create-close: SUCCESS (0.000s) [41.996830] [012/162] (957s left) gem_basic (create-fd-close) [42.042988] Starting subtest: create-fd-close [42.049116] Subtest create-fd-close: SUCCESS (0.000s) [42.098750] [013/162] (957s left) gem_busy (busy@all-engines) [42.160437] Starting subtest: busy [42.163677] Starting dynamic subtest: all-engines [42.187365] Dynamic subtest all-engines: SUCCESS (0.027s) [42.190608] Subtest busy: SUCCESS (0.027s) [42.501548] [014/162] (957s left) gem_close_race (basic-process) [42.565683] Starting subtest: basic-process [42.621624] Subtest basic-process: SUCCESS (0.056s) [42.772396] [015/162] (957s left) gem_close_race (basic-threads) [42.832158] Starting subtest: basic-threads [44.084496] Subtest basic-threads: SUCCESS (1.252s) [44.238362] [016/162] (955s left) gem_ctx_create (basic) [44.299191] Starting subtest: basic [44.302280] Subtest basic: SUCCESS (0.000s) [44.442173] [017/162] (955s left) gem_ctx_create (basic-files) [44.497952] Starting subtest: basic-files [46.508667] Subtest basic-files: SUCCESS (2.010s) [46.708547] [018/162] (953s left) gem_ctx_exec (basic) [46.766662] Starting subtest: basic [46.770100] Subtest basic: SUCCESS (0.004s) [46.841115] [019/162] (953s left) gem_exec_basic (basic) [46.896739] Starting subtest: basic [46.900136] Starting dynamic subtest: rcs0-smem [46.903208] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [46.913616] Starting dynamic subtest: bcs0-smem [46.916643] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [46.926563] Starting dynamic subtest: vcs0-smem [46.929715] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [46.939774] Starting dynamic subtest: vecs0-smem [46.942860] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [46.946043] Subtest basic: SUCCESS (0.006s) [47.087675] [020/162] (953s left) gem_exec_create (basic) [47.145721] Starting subtest: basic [47.149115] Starting dynamic subtest: smem [49.204426] Dynamic subtest smem: SUCCESS (2.120s) [49.207503] Subtest basic: SUCCESS (2.120s) [49.344400] [021/162] (950s left) gem_exec_fence (basic-busy) [49.518918] Starting subtest: basic-busy [49.522657] Starting dynamic subtest: rcs0 [49.547324] Dynamic subtest rcs0: SUCCESS (0.029s) [49.558029] Starting dynamic subtest: bcs0 [49.576228] Dynamic subtest bcs0: SUCCESS (0.029s) [49.587723] Starting dynamic subtest: vcs0 [49.608059] Dynamic subtest vcs0: SUCCESS (0.033s) [49.619073] Starting dynamic subtest: vecs0 [49.637564] Dynamic subtest vecs0: SUCCESS (0.029s) [49.640948] Subtest basic-busy: SUCCESS (0.122s) [50.149811] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [50.153883] [022/162] (950s left) gem_exec_fence (basic-wait) [50.325076] Starting subtest: basic-wait [50.328154] Starting dynamic subtest: rcs0 [50.360652] Dynamic subtest rcs0: SUCCESS (0.036s) [50.378138] Starting dynamic subtest: bcs0 [50.397176] Dynamic subtest bcs0: SUCCESS (0.037s) [50.408440] Starting dynamic subtest: vcs0 [50.433375] Dynamic subtest vcs0: SUCCESS (0.037s) [50.448070] Starting dynamic subtest: vecs0 [50.460114] Dynamic subtest vecs0: SUCCESS (0.027s) [50.463198] Subtest basic-wait: SUCCESS (0.139s) [50.949097] [023/162] (949s left) gem_exec_fence (basic-await) [51.122989] Starting subtest: basic-await [51.126160] Starting dynamic subtest: rcs0 [51.201118] Dynamic subtest rcs0: SUCCESS (0.080s) [51.218755] Starting dynamic subtest: bcs0 [51.289395] Dynamic subtest bcs0: SUCCESS (0.090s) [51.307202] Starting dynamic subtest: vcs0 [51.372123] Dynamic subtest vcs0: SUCCESS (0.084s) [51.389917] Starting dynamic subtest: vecs0 [51.461321] Dynamic subtest vecs0: SUCCESS (0.090s) [51.464935] Subtest basic-await: SUCCESS (0.346s) [51.946929] [024/162] (948s left) gem_exec_fence (nb-await) [52.114748] Starting subtest: nb-await [52.117802] Starting dynamic subtest: rcs0 [52.177691] Dynamic subtest rcs0: SUCCESS (0.064s) [52.198807] Starting dynamic subtest: bcs0 [52.242801] Dynamic subtest bcs0: SUCCESS (0.066s) [52.264155] Starting dynamic subtest: vcs0 [52.307921] Dynamic subtest vcs0: SUCCESS (0.066s) [52.329515] Starting dynamic subtest: vecs0 [52.371337] Dynamic subtest vecs0: SUCCESS (0.064s) [52.375063] Subtest nb-await: SUCCESS (0.262s) [52.868867] [025/162] (947s left) gem_exec_gttfill (basic) [52.929067] Starting subtest: basic [56.080493] Subtest basic: SUCCESS (3.197s) [56.241272] [026/162] (944s left) gem_exec_parallel (engines) [56.368763] Starting subtest: engines [56.371928] Starting dynamic subtest: basic [58.372814] Dynamic subtest basic: SUCCESS (2.023s) [58.384560] Starting dynamic subtest: contexts [60.555949] Dynamic subtest contexts: SUCCESS (2.198s) [60.567785] Starting dynamic subtest: fds [65.155566] Dynamic subtest fds: SUCCESS (4.620s) [65.167612] Starting dynamic subtest: userptr [67.372476] Dynamic subtest userptr: SUCCESS (2.223s) [67.375647] Subtest engines: SUCCESS (11.066s) [67.528746] [027/162] (932s left) gem_exec_store (basic) [67.596942] Starting subtest: basic [67.729404] Subtest basic: SUCCESS (0.133s) [67.880149] [028/162] (932s left) gem_flink_basic (bad-flink) [67.931042] Starting subtest: bad-flink [67.934044] Subtest bad-flink: SUCCESS (0.000s) [67.980329] [029/162] (932s left) gem_flink_basic (bad-open) [68.031885] Starting subtest: bad-open [68.035080] Subtest bad-open: SUCCESS (0.000s) [68.078145] [030/162] (932s left) gem_flink_basic (basic) [68.127187] Starting subtest: basic [68.130278] Subtest basic: SUCCESS (0.000s) [68.178349] [031/162] (932s left) gem_flink_basic (double-flink) [68.227065] Starting subtest: double-flink [68.230185] Subtest double-flink: SUCCESS (0.000s) [68.282744] [032/162] (932s left) gem_flink_basic (flink-lifetime) [68.332197] Starting subtest: flink-lifetime [68.335314] Subtest flink-lifetime: SUCCESS (0.001s) [68.386299] [033/162] (932s left) gem_huc_copy (huc-copy) [68.438214] Starting subtest: huc-copy [68.444536] Subtest huc-copy: SKIP (0.000s) [68.507378] [034/162] (932s left) gem_linear_blits (basic) [68.623867] Starting subtest: basic [68.631734] Subtest basic: SUCCESS (0.004s) [68.734846] [035/162] (932s left) gem_mmap (basic) [68.863256] Starting subtest: basic [68.878652] Subtest basic: SUCCESS (0.000s) [68.931873] [036/162] (931s left) gem_mmap_gtt (basic) [68.983177] Starting subtest: basic [68.986426] Subtest basic: SUCCESS (0.001s) [69.029640] [037/162] (931s left) gem_render_linear_blits (basic) [69.081530] Starting subtest: basic [69.104959] Subtest basic: SUCCESS (0.024s) [69.172205] [038/162] (931s left) gem_render_tiled_blits (basic) [69.227248] Starting subtest: basic [69.256831] Subtest basic: SUCCESS (0.032s) [69.315489] [039/162] (931s left) gem_ringfill (basic-all) [69.566550] Starting subtest: basic-all [70.700340] Subtest basic-all: SUCCESS (1.137s) [70.777440] [040/162] (930s left) gem_softpin (allocator-basic) [70.831818] Starting subtest: allocator-basic [70.927166] Subtest allocator-basic: SUCCESS (0.096s) [71.008648] [041/162] (930s left) gem_softpin (allocator-basic-reserve) [71.062259] Starting subtest: allocator-basic-reserve [71.161607] Subtest allocator-basic-reserve: SUCCESS (0.102s) [71.241302] [042/162] (929s left) gem_softpin (safe-alignment) [71.296481] Starting subtest: safe-alignment [71.303794] Subtest safe-alignment: SUCCESS (0.010s) [71.375189] [043/162] (929s left) gem_sync (basic-all) [71.456532] Starting subtest: basic-all [73.699329] Subtest basic-all: SUCCESS (2.267s) [73.853514] [044/162] (927s left) gem_sync (basic-each) [73.929892] Starting subtest: basic-each [75.982571] Subtest basic-each: SUCCESS (2.072s) [76.141192] [045/162] (925s left) gem_tiled_blits (basic) [76.243988] Starting subtest: basic [76.260089] Subtest basic: SUCCESS (0.016s) [76.356008] [046/162] (925s left) gem_tiled_fence_blits (basic) [76.554955] Starting subtest: basic [76.563895] Subtest basic: SUCCESS (0.008s) [76.660572] [047/162] (924s left) gem_tiled_pread_basic (basic) [76.789471] Starting subtest: basic [76.907723] Subtest basic: SUCCESS (0.122s) [76.970309] [048/162] (924s left) gem_wait (busy@all-engines) [77.032454] Starting subtest: busy [77.035623] Starting dynamic subtest: all-engines [77.551297] Dynamic subtest all-engines: SUCCESS (0.519s) [77.554407] Subtest busy: SUCCESS (0.520s) [77.722373] [049/162] (923s left) gem_wait (wait@all-engines) [77.784318] Starting subtest: wait [77.787519] Starting dynamic subtest: all-engines [78.830290] Dynamic subtest all-engines: SUCCESS (1.044s) [78.833729] Subtest wait: SUCCESS (1.045s) [78.991517] [050/162] (922s left) i915_getparams_basic (basic-eu-total) [79.040218] Starting subtest: basic-eu-total [79.046652] Subtest basic-eu-total: SUCCESS (0.000s) [79.092691] [051/162] (922s left) i915_getparams_basic (basic-subslice-total) [79.141670] Starting subtest: basic-subslice-total [79.148123] Subtest basic-subslice-total: SUCCESS (0.000s) [79.193457] [052/162] (922s left) i915_hangman (error-state-basic) [79.250639] Starting subtest: error-state-basic [79.262565] Subtest error-state-basic: SUCCESS (0.009s) [79.331387] [053/162] (922s left) i915_pciid [79.428127] [054/162] (922s left) intel_hwmon (hwmon-read) [79.483604] Subtest hwmon-read: SKIP (0.000s) [79.526254] [055/162] (922s left) intel_hwmon (hwmon-write) [79.577860] Subtest hwmon-write: SKIP (0.000s) [79.621700] [056/162] (922s left) kms_addfb_basic (addfb25-4-tiled) [79.718646] Starting subtest: addfb25-4-tiled [79.721645] Subtest addfb25-4-tiled: SUCCESS (0.000s) [79.782992] [057/162] (922s left) kms_addfb_basic (addfb25-bad-modifier) [79.864930] Starting subtest: addfb25-bad-modifier [79.868267] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [79.928854] [058/162] (922s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [80.010828] Starting subtest: addfb25-framebuffer-vs-set-tiling [80.013941] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [80.081309] [059/162] (921s left) kms_addfb_basic (addfb25-modifier-no-flag) [80.162450] Starting subtest: addfb25-modifier-no-flag [80.165890] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [80.228213] [060/162] (921s left) kms_addfb_basic (addfb25-x-tiled-legacy) [80.307490] Starting subtest: addfb25-x-tiled-legacy [80.310817] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [80.372343] [061/162] (921s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [80.447917] Starting subtest: addfb25-x-tiled-mismatch-legacy [80.451175] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [80.510565] [062/162] (921s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [80.591579] Starting subtest: addfb25-yf-tiled-legacy [80.594524] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [80.655772] [063/162] (921s left) kms_addfb_basic (addfb25-y-tiled-legacy) [80.733344] Starting subtest: addfb25-y-tiled-legacy [80.736441] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [80.794141] [064/162] (921s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [80.880905] Starting subtest: addfb25-y-tiled-small-legacy [80.883999] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [80.944814] [065/162] (921s left) kms_addfb_basic (bad-pitch-0) [80.996330] Starting subtest: bad-pitch-0 [81.000262] Subtest bad-pitch-0: SUCCESS (0.000s) [81.087761] [066/162] (921s left) kms_addfb_basic (bad-pitch-1024) [81.137472] Starting subtest: bad-pitch-1024 [81.141748] Subtest bad-pitch-1024: SUCCESS (0.000s) [81.224990] [067/162] (921s left) kms_addfb_basic (bad-pitch-128) [81.278219] Starting subtest: bad-pitch-128 [81.281533] Subtest bad-pitch-128: SUCCESS (0.000s) [81.367208] [068/162] (921s left) kms_addfb_basic (bad-pitch-256) [81.418504] Starting subtest: bad-pitch-256 [81.422490] Subtest bad-pitch-256: SUCCESS (0.000s) [81.509596] [069/162] (920s left) kms_addfb_basic (bad-pitch-32) [81.563889] Starting subtest: bad-pitch-32 [81.566967] Subtest bad-pitch-32: SUCCESS (0.000s) [81.643458] [070/162] (920s left) kms_addfb_basic (bad-pitch-63) [81.696011] Starting subtest: bad-pitch-63 [81.699585] Subtest bad-pitch-63: SUCCESS (0.000s) [81.789936] [071/162] (920s left) kms_addfb_basic (bad-pitch-65536) [81.845158] Starting subtest: bad-pitch-65536 [81.849147] Subtest bad-pitch-65536: SUCCESS (0.000s) [81.937351] [072/162] (920s left) kms_addfb_basic (bad-pitch-999) [81.988318] Starting subtest: bad-pitch-999 [81.991938] Subtest bad-pitch-999: SUCCESS (0.000s) [82.087619] [073/162] (920s left) kms_addfb_basic (basic) [82.141699] Starting subtest: basic [82.144930] Subtest basic: SUCCESS (0.000s) [82.222597] [074/162] (920s left) kms_addfb_basic (basic-x-tiled-legacy) [82.272544] Starting subtest: basic-x-tiled-legacy [82.276959] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [82.353304] [075/162] (920s left) kms_addfb_basic (basic-y-tiled-legacy) [82.404581] Starting subtest: basic-y-tiled-legacy [82.408565] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [82.487642] [076/162] (920s left) kms_addfb_basic (bo-too-small) [82.564974] Starting subtest: bo-too-small [82.568184] Subtest bo-too-small: SUCCESS (0.000s) [82.651347] [077/162] (920s left) kms_addfb_basic (bo-too-small-due-to-tiling) [83.015720] Starting subtest: bo-too-small-due-to-tiling [83.079365] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [83.496481] [078/162] (919s left) kms_addfb_basic (clobberred-modifier) [83.820762] Starting subtest: clobberred-modifier [83.885346] Subtest clobberred-modifier: SUCCESS (0.000s) [84.183431] [079/162] (919s left) kms_addfb_basic (framebuffer-vs-set-tiling) [84.235670] Starting subtest: framebuffer-vs-set-tiling [84.238790] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [84.327251] [080/162] (919s left) kms_addfb_basic (invalid-get-prop) [84.377246] Starting subtest: invalid-get-prop [84.381898] Subtest invalid-get-prop: SUCCESS (0.000s) [84.466869] [081/162] (919s left) kms_addfb_basic (invalid-get-prop-any) [84.517231] Starting subtest: invalid-get-prop-any [84.521609] Subtest invalid-get-prop-any: SUCCESS (0.000s) [84.599493] [082/162] (919s left) kms_addfb_basic (invalid-set-prop) [84.651997] Starting subtest: invalid-set-prop [84.655636] Subtest invalid-set-prop: SUCCESS (0.000s) [84.742987] [083/162] (918s left) kms_addfb_basic (invalid-set-prop-any) [84.793264] Starting subtest: invalid-set-prop-any [84.797148] Subtest invalid-set-prop-any: SUCCESS (0.000s) [84.876201] [084/162] (918s left) kms_addfb_basic (no-handle) [84.925675] Starting subtest: no-handle [84.929193] Subtest no-handle: SUCCESS (0.000s) [85.015594] [085/162] (918s left) kms_addfb_basic (size-max) [85.099315] Starting subtest: size-max [85.102419] Subtest size-max: SUCCESS (0.000s) [85.157938] [086/162] (918s left) kms_addfb_basic (small-bo) [85.237089] Starting subtest: small-bo [85.240535] Subtest small-bo: SUCCESS (0.000s) [85.300906] [087/162] (918s left) kms_addfb_basic (tile-pitch-mismatch) [85.352296] Starting subtest: tile-pitch-mismatch [85.356775] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [85.444220] [088/162] (918s left) kms_addfb_basic (too-high) [85.524899] Starting subtest: too-high [85.528165] Subtest too-high: SUCCESS (0.001s) [85.591648] [089/162] (918s left) kms_addfb_basic (too-wide) [85.669730] Starting subtest: too-wide [85.673167] Subtest too-wide: SUCCESS (0.000s) [85.733612] [090/162] (918s left) kms_addfb_basic (unused-handle) [85.784597] Starting subtest: unused-handle [85.788164] Subtest unused-handle: SUCCESS (0.000s) [85.872839] [091/162] (918s left) kms_addfb_basic (unused-modifier) [85.927100] Starting subtest: unused-modifier [85.930151] Subtest unused-modifier: SUCCESS (0.000s) [86.025697] [092/162] (918s left) kms_addfb_basic (unused-offsets) [86.081028] Starting subtest: unused-offsets [86.084101] Subtest unused-offsets: SUCCESS (0.000s) [86.169982] [093/162] (917s left) kms_addfb_basic (unused-pitches) [86.228075] Starting subtest: unused-pitches [86.231149] Subtest unused-pitches: SUCCESS (0.000s) [86.311244] [094/162] (917s left) kms_busy (basic) [86.397454] Subtest basic: SKIP (0.000s) [86.442528] [095/162] (917s left) kms_prop_blob (basic) [86.506264] Starting subtest: basic [86.509444] Subtest basic: SUCCESS (0.000s) [86.552085] [096/162] (917s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [86.659093] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [86.706704] [097/162] (917s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [86.808591] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [86.853289] [098/162] (917s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [86.953539] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s) [86.999924] [099/162] (917s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [87.101183] Subtest basic-flip-after-cursor-legacy: SKIP (0.000s) [87.147443] [100/162] (917s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [87.257385] Subtest basic-flip-after-cursor-varying-size: SKIP (0.000s) [87.302838] [101/162] (917s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [87.400429] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s) [87.448309] [102/162] (917s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [87.548354] Subtest basic-flip-before-cursor-legacy: SKIP (0.000s) [87.594852] [103/162] (916s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [87.692186] Subtest basic-flip-before-cursor-varying-size: SKIP (0.000s) [87.738539] [104/162] (916s left) kms_dsc (dsc-basic) [87.820927] Subtest dsc-basic: SKIP (0.000s) [87.866706] [105/162] (916s left) kms_flip (basic-flip-vs-dpms) [87.947593] Starting subtest: basic-flip-vs-dpms [87.953559] Subtest basic-flip-vs-dpms: SKIP (0.001s) [88.015737] [106/162] (916s left) kms_flip (basic-flip-vs-modeset) [88.098374] Starting subtest: basic-flip-vs-modeset [88.104100] Subtest basic-flip-vs-modeset: SKIP (0.001s) [88.146787] [107/162] (916s left) kms_flip (basic-flip-vs-wf_vblank) [88.227146] Starting subtest: basic-flip-vs-wf_vblank [88.233165] Subtest basic-flip-vs-wf_vblank: SKIP (0.001s) [88.283841] [108/162] (916s left) kms_flip (basic-plain-flip) [88.366971] Starting subtest: basic-plain-flip [88.373171] Subtest basic-plain-flip: SKIP (0.001s) [88.432916] [109/162] (916s left) kms_force_connector_basic (force-connector-state) [88.584791] Starting subtest: force-connector-state [88.664136] Subtest force-connector-state: SUCCESS (0.080s) [88.820534] [110/162] (916s left) kms_force_connector_basic (force-edid) [88.962831] Starting subtest: force-edid [89.140387] Subtest force-edid: SUCCESS (0.174s) [89.223739] [111/162] (915s left) kms_force_connector_basic (force-load-detect) [89.369104] Starting subtest: force-load-detect [89.376463] Subtest force-load-detect: SKIP (0.000s) [89.440339] [112/162] (915s left) kms_force_connector_basic (prune-stale-modes) [89.592357] Starting subtest: prune-stale-modes [89.762526] Subtest prune-stale-modes: SUCCESS (0.175s) [89.848326] [113/162] (915s left) kms_frontbuffer_tracking (basic) [89.929986] Subtest basic: SKIP (0.000s) [89.986756] [114/162] (915s left) kms_hdmi_inject (inject-audio) [90.040809] Starting subtest: inject-audio [90.047026] Subtest inject-audio: SKIP (0.000s) [90.093441] [115/162] (915s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [90.177646] Subtest compare-crc-sanitycheck-xr24: SKIP (0.000s) [90.221805] [116/162] (914s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [90.304465] Subtest compare-crc-sanitycheck-nv12: SKIP (0.000s) [90.348562] [117/162] (914s left) kms_pipe_crc_basic (hang-read-crc) [90.428870] Subtest hang-read-crc: SKIP (0.000s) [90.472875] [118/162] (914s left) kms_pipe_crc_basic (nonblocking-crc) [90.553109] Subtest nonblocking-crc: SKIP (0.000s) [90.596815] [119/162] (914s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [90.677156] Subtest nonblocking-crc-frame-sequence: SKIP (0.000s) [90.721462] [120/162] (914s left) kms_pipe_crc_basic (read-crc) [90.802876] Subtest read-crc: SKIP (0.000s) [90.846601] [121/162] (914s left) kms_pipe_crc_basic (read-crc-frame-sequence) [90.928169] Subtest read-crc-frame-sequence: SKIP (0.000s) [90.973252] [122/162] (914s left) kms_pm_backlight (basic-brightness) [91.062089] Subtest basic-brightness: SKIP (0.000s) [91.109376] [123/162] (914s left) kms_pm_rpm (basic-pci-d3-state) [102.403617] Starting subtest: basic-pci-d3-state [102.584811] Subtest basic-pci-d3-state: SUCCESS (0.183s) [102.642384] [124/162] (902s left) kms_pm_rpm (basic-rte) [102.720533] Starting subtest: basic-rte [114.250817] Subtest basic-rte: SUCCESS (11.532s) [114.554817] [125/162] (891s left) kms_psr (psr-primary-page-flip) [114.636861] Subtest psr-primary-page-flip: SKIP (0.000s) [114.681402] [126/162] (890s left) kms_psr (psr-cursor-plane-move) [114.765619] Subtest psr-cursor-plane-move: SKIP (0.000s) [114.811428] [127/162] (890s left) kms_psr (psr-sprite-plane-onoff) [114.891121] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [114.934571] [128/162] (890s left) kms_psr (psr-primary-mmap-gtt) [115.017451] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [115.061378] [129/162] (890s left) kms_setmode (basic-clone-single-crtc) [115.118690] Starting subtest: basic-clone-single-crtc [115.128481] Subtest basic-clone-single-crtc: SKIP (0.000s) [115.174657] [130/162] (890s left) i915_pm_rps (basic-api) [115.234413] Starting subtest: basic-api [115.237553] Subtest basic-api: SUCCESS (0.003s) [115.285766] [131/162] (890s left) prime_self_import (basic-llseek-bad) [115.333580] Starting subtest: basic-llseek-bad [115.339738] Subtest basic-llseek-bad: SUCCESS (0.002s) [115.404212] [132/162] (890s left) prime_self_import (basic-llseek-size) [115.450195] Starting subtest: basic-llseek-size [115.456401] Subtest basic-llseek-size: SUCCESS (0.003s) [115.521439] [133/162] (890s left) prime_self_import (basic-with_fd_dup) [115.568087] Starting subtest: basic-with_fd_dup [115.574567] Subtest basic-with_fd_dup: SUCCESS (0.003s) [115.640045] [134/162] (890s left) prime_self_import (basic-with_one_bo) [115.686545] Starting subtest: basic-with_one_bo [115.692871] Subtest basic-with_one_bo: SUCCESS (0.003s) [115.738148] [135/162] (890s left) prime_self_import (basic-with_one_bo_two_files) [115.784198] Starting subtest: basic-with_one_bo_two_files [115.790830] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [115.856913] [136/162] (890s left) prime_self_import (basic-with_two_bos) [115.906191] Starting subtest: basic-with_two_bos [115.912240] Subtest basic-with_two_bos: SUCCESS (0.002s) [115.974661] [137/162] (890s left) prime_vgem (basic-fence-flip) [116.031090] Starting subtest: basic-fence-flip [116.089598] Subtest basic-fence-flip: SKIP (0.058s) [116.136997] [138/162] (890s left) prime_vgem (basic-fence-mmap) [116.193976] Starting subtest: basic-fence-mmap [116.211969] Subtest basic-fence-mmap: SUCCESS (0.021s) [116.298997] [139/162] (889s left) prime_vgem (basic-fence-read) [116.355506] Starting subtest: basic-fence-read [116.395990] Subtest basic-fence-read: SUCCESS (0.044s) [116.471725] [140/162] (889s left) prime_vgem (basic-gtt) [116.525888] Starting subtest: basic-gtt [116.534492] Subtest basic-gtt: SUCCESS (0.010s) [116.616191] [141/162] (889s left) prime_vgem (basic-read) [116.672489] Starting subtest: basic-read [116.690330] Subtest basic-read: SUCCESS (0.021s) [116.760105] [142/162] (889s left) prime_vgem (basic-write) [116.816956] Starting subtest: basic-write [116.835963] Subtest basic-write: SUCCESS (0.022s) [116.887653] [143/162] (889s left) vgem_basic (setversion) [116.937768] Starting subtest: setversion [116.943979] Subtest setversion: SUCCESS (0.000s) [116.995254] [144/162] (889s left) vgem_basic (create) [117.049628] Starting subtest: create [117.052876] Subtest create: SUCCESS (0.000s) [117.113546] [145/162] (889s left) vgem_basic (debugfs) [117.168303] Starting subtest: debugfs [117.171621] Subtest debugfs: SUCCESS (0.000s) [117.223515] [146/162] (889s left) vgem_basic (dmabuf-export) [117.274238] Starting subtest: dmabuf-export [117.280797] Subtest dmabuf-export: SUCCESS (0.002s) [117.340931] [147/162] (889s left) vgem_basic (dmabuf-fence) [117.394567] Starting subtest: dmabuf-fence [117.397509] Subtest dmabuf-fence: SUCCESS (0.001s) [117.448502] [148/162] (889s left) vgem_basic (dmabuf-fence-before) [117.501207] Starting subtest: dmabuf-fence-before [117.504602] Subtest dmabuf-fence-before: SUCCESS (0.001s) [117.556065] [149/162] (889s left) vgem_basic (dmabuf-mmap) [117.608125] Starting subtest: dmabuf-mmap [117.614799] Subtest dmabuf-mmap: SUCCESS (0.009s) [117.660179] [150/162] (889s left) vgem_basic (mmap) [117.713636] Starting subtest: mmap [117.716617] Subtest mmap: SUCCESS (0.009s) [117.762531] [151/162] (889s left) vgem_basic (second-client) [117.812116] Starting subtest: second-client [117.818456] Subtest second-client: SUCCESS (0.002s) [117.870620] [152/162] (889s left) vgem_basic (sysfs) [117.921037] Starting subtest: sysfs [117.924198] Subtest sysfs: SUCCESS (0.001s) [117.977448] [153/162] (888s left) core_hotunplug (unbind-rebind) [118.089532] Starting subtest: unbind-rebind [121.541883] Subtest unbind-rebind: SUCCESS (3.453s) [121.587269] [154/162] (885s left) vgem_basic (unload) [121.635578] Starting subtest: unload [150.481188] Subtest unload: SUCCESS (28.849s) [150.545807] [155/162] (856s left) i915_module_load (reload) [150.593533] Starting subtest: reload [153.039901] Subtest reload: SUCCESS (2.449s) [153.217472] [156/162] (854s left) gem_lmem_swapping (basic) [153.729707] Subtest basic: SKIP (0.000s) [154.148084] [157/162] (853s left) gem_lmem_swapping (parallel-random-engines) [154.535269] Subtest parallel-random-engines: SKIP (0.000s) [154.580647] [158/162] (853s left) gem_lmem_swapping (random-engines) [154.636572] Subtest random-engines: SKIP (0.000s) [154.680982] [159/162] (853s left) gem_lmem_swapping (verify-random) [154.736190] Subtest verify-random: SKIP (0.000s) [154.779826] [160/162] (852s left) i915_pm_rpm (module-reload) [166.176729] Starting subtest: module-reload [193.818100] Subtest module-reload: SUCCESS (27.645s) [193.846472] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [193.847489] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [193.860752] [161/162] (813s left) i915_selftest (live) [193.915522] Starting subtest: live [193.918645] Starting dynamic subtest: sanitycheck [196.064459] Dynamic subtest sanitycheck: SUCCESS (2.151s) [196.075689] Starting dynamic subtest: uncore [198.204629] Dynamic subtest uncore: SUCCESS (2.138s) [198.215994] Starting dynamic subtest: workarounds [200.534405] Dynamic subtest workarounds: SUCCESS (2.329s) [200.545899] Starting dynamic subtest: gt_engines [203.114469] Dynamic subtest gt_engines: SUCCESS (2.579s) [203.125862] Starting dynamic subtest: gt_timelines [211.843608] Dynamic subtest gt_timelines: SUCCESS (8.732s) [211.859102] Starting dynamic subtest: gt_contexts [214.114622] Dynamic subtest gt_contexts: SUCCESS (2.268s) [214.126177] Starting dynamic subtest: gt_lrc [218.721868] Dynamic subtest gt_lrc: SUCCESS (4.608s) [218.737449] Starting dynamic subtest: gt_mocs [221.024550] Dynamic subtest gt_mocs: SUCCESS (2.257s) [221.088382] Starting dynamic subtest: gt_pm [227.432924] Dynamic subtest gt_pm: SUCCESS (6.452s) [227.444640] Starting dynamic subtest: gt_heartbeat [229.595031] Dynamic subtest gt_heartbeat: SUCCESS (2.152s) [229.606305] Starting dynamic subtest: gt_tlb [232.955114] Dynamic subtest gt_tlb: SUCCESS (3.361s) [232.966509] Starting dynamic subtest: requests [241.304274] Dynamic subtest requests: SUCCESS (8.354s) [241.320220] Starting dynamic subtest: migrate [246.434638] Dynamic subtest migrate: SUCCESS (5.131s) [246.446130] Starting dynamic subtest: active [248.585020] Dynamic subtest active: SUCCESS (2.148s) [248.596828] Starting dynamic subtest: objects [251.254496] Dynamic subtest objects: SUCCESS (2.670s) [251.270595] Starting dynamic subtest: mman [256.551908] Dynamic subtest mman: SUCCESS (5.296s) [256.563551] Starting dynamic subtest: dmabuf [258.765116] Dynamic subtest dmabuf: SUCCESS (2.206s) [258.776236] Starting dynamic subtest: vma [260.981351] Dynamic subtest vma: SUCCESS (2.173s) [261.045281] Starting dynamic subtest: coherency [263.829343] Dynamic subtest coherency: SUCCESS (2.894s) [263.842279] Starting dynamic subtest: gtt [273.422748] Dynamic subtest gtt: SUCCESS (9.594s) [273.434529] Starting dynamic subtest: gem [275.736288] Dynamic subtest gem: SUCCESS (2.303s) [275.749222] Starting dynamic subtest: evict [279.026933] Dynamic subtest evict: SUCCESS (3.299s) [279.042339] Starting dynamic subtest: hugepages [287.577987] Dynamic subtest hugepages: SUCCESS (8.550s) [287.589679] Starting dynamic subtest: gem_contexts [302.326744] Dynamic subtest gem_contexts: SUCCESS (14.748s) [302.338648] Starting dynamic subtest: client [304.561413] Dynamic subtest client: SUCCESS (2.192s) [304.627470] Starting dynamic subtest: gem_migrate [306.762375] Dynamic subtest gem_migrate: SUCCESS (2.240s) [306.775288] Starting dynamic subtest: reset [309.115575] Dynamic subtest reset: SUCCESS (2.347s) [309.130730] Starting dynamic subtest: memory_region [311.243062] Dynamic subtest memory_region: SUCCESS (2.133s) [311.254798] Starting dynamic subtest: hangcheck [336.487586] Dynamic subtest hangcheck: SUCCESS (25.244s) [336.499829] Starting dynamic subtest: execlists [346.758797] Dynamic subtest execlists: SUCCESS (10.270s) [346.770501] Starting dynamic subtest: ring_submission [348.896320] Dynamic subtest ring_submission: SUCCESS (2.132s) [348.908310] Starting dynamic subtest: perf [351.137314] Dynamic subtest perf: SUCCESS (2.241s) [351.150193] Starting dynamic subtest: slpc [353.246070] Dynamic subtest slpc: SUCCESS (2.111s) [353.257633] Starting dynamic subtest: guc [355.388348] Dynamic subtest guc: SUCCESS (2.141s) [355.399482] Starting dynamic subtest: guc_multi_lrc [357.516924] Dynamic subtest guc_multi_lrc: SUCCESS (2.128s) [357.529073] Starting dynamic subtest: guc_hang [359.666142] Dynamic subtest guc_hang: SUCCESS (2.145s) [359.678042] Starting dynamic subtest: late_gt_pm [361.856165] Dynamic subtest late_gt_pm: SUCCESS (2.185s) [361.859491] Subtest live: SUCCESS (167.936s) [361.897421] [162/162] (645s left) dmabuf (all-tests) [361.958705] Starting subtest: all-tests [361.961788] Starting dynamic subtest: sanitycheck [362.020511] Dynamic subtest sanitycheck: SUCCESS (0.063s) [362.034294] Starting dynamic subtest: dma_fence [362.203206] Dynamic subtest dma_fence: SUCCESS (0.180s) [362.224286] Starting dynamic subtest: dma_fence_chain [373.197975] Dynamic subtest dma_fence_chain: SUCCESS (10.994s) [373.213716] Starting dynamic subtest: dma_fence_unwrap [373.262835] Dynamic subtest dma_fence_unwrap: SUCCESS (0.062s) [373.290101] Starting dynamic subtest: dma_resv [373.333571] Dynamic subtest dma_resv: SUCCESS (0.067s) [373.338221] Subtest all-tests: SUCCESS (11.377s) [373.371328] 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.