[38.108442] Initializing watchdogs [38.108516] /dev/watchdog0 [38.123481] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:591d Intel Kabylake (Gen9) HD Graphics P630 [38.130820] [001/162] (960s left) i915_module_load (load) [38.191503] Starting subtest: load [40.241437] Subtest load: SUCCESS (2.049s) [40.273646] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [40.275226] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [40.280132] [002/162] (958s left) core_auth (basic-auth) [40.350116] Starting subtest: basic-auth [40.353276] Subtest basic-auth: SUCCESS (0.001s) [40.390177] [003/162] (958s left) core_debugfs (read-all-entries) [40.437887] Starting subtest: read-all-entries [40.441008] Subtest read-all-entries: SUCCESS (0.002s) [40.480469] [004/162] (958s left) core_sysfs (read-all-entries) [40.534116] Starting subtest: read-all-entries [40.537542] Subtest read-all-entries: SUCCESS (0.003s) [40.576098] [005/162] (958s left) fbdev (eof) [40.951709] Subtest eof: SKIP (0.000s) [41.332692] [006/162] (957s left) fbdev (info) [41.656112] Subtest info: SKIP (0.000s) [42.083291] [007/162] (957s left) fbdev (nullptr) [42.140176] Subtest nullptr: SKIP (0.000s) [42.178418] [008/162] (957s left) fbdev (read) [42.232574] Subtest read: SKIP (0.000s) [42.270775] [009/162] (957s left) fbdev (write) [42.325231] Subtest write: SKIP (0.000s) [42.367158] [010/162] (957s left) gem_basic (bad-close) [42.415494] Starting subtest: bad-close [42.421497] Subtest bad-close: SUCCESS (0.000s) [42.463380] [011/162] (957s left) gem_basic (create-close) [42.512190] Starting subtest: create-close [42.518469] Subtest create-close: SUCCESS (0.000s) [42.573134] [012/162] (957s left) gem_basic (create-fd-close) [42.619370] Starting subtest: create-fd-close [42.625633] Subtest create-fd-close: SUCCESS (0.000s) [42.673397] [013/162] (957s left) gem_busy (busy@all-engines) [42.732756] Starting subtest: busy [42.735669] Starting dynamic subtest: all-engines [42.759501] Dynamic subtest all-engines: SUCCESS (0.027s) [42.762932] Subtest busy: SUCCESS (0.027s) [43.070480] [014/162] (956s left) gem_close_race (basic-process) [43.129190] Starting subtest: basic-process [43.186863] Subtest basic-process: SUCCESS (0.057s) [43.338416] [015/162] (956s left) gem_close_race (basic-threads) [43.396011] Starting subtest: basic-threads [44.625556] Subtest basic-threads: SUCCESS (1.230s) [44.778645] [016/162] (955s left) gem_ctx_create (basic) [44.835731] Starting subtest: basic [44.838842] Subtest basic: SUCCESS (0.000s) [44.981384] [017/162] (954s left) gem_ctx_create (basic-files) [45.037129] Starting subtest: basic-files [47.045909] Subtest basic-files: SUCCESS (2.025s) [47.243335] [018/162] (952s left) gem_ctx_exec (basic) [47.310036] Starting subtest: basic [47.313348] Subtest basic: SUCCESS (0.004s) [47.389127] [019/162] (952s left) gem_exec_basic (basic) [47.439723] Starting subtest: basic [47.442985] Starting dynamic subtest: rcs0-smem [47.446623] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [47.456953] Starting dynamic subtest: bcs0-smem [47.460182] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [47.470106] Starting dynamic subtest: vcs0-smem [47.473093] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [47.483176] Starting dynamic subtest: vecs0-smem [47.486352] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [47.489454] Subtest basic: SUCCESS (0.005s) [47.627893] [020/162] (952s left) gem_exec_create (basic) [47.683490] Starting subtest: basic [47.686648] Starting dynamic subtest: smem [49.728142] Dynamic subtest smem: SUCCESS (2.076s) [49.731334] Subtest basic: SUCCESS (2.077s) [49.880515] [021/162] (950s left) gem_exec_fence (basic-busy) [50.056798] Starting subtest: basic-busy [50.060124] Starting dynamic subtest: rcs0 [50.105360] Dynamic subtest rcs0: SUCCESS (0.050s) [50.119325] Starting dynamic subtest: bcs0 [50.139170] Dynamic subtest bcs0: SUCCESS (0.034s) [50.150293] Starting dynamic subtest: vcs0 [50.168248] Dynamic subtest vcs0: SUCCESS (0.029s) [50.178776] Starting dynamic subtest: vecs0 [50.196142] Dynamic subtest vecs0: SUCCESS (0.028s) [50.199272] Subtest basic-busy: SUCCESS (0.142s) [50.726342] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [50.730857] [022/162] (949s left) gem_exec_fence (basic-wait) [50.900207] Starting subtest: basic-wait [50.903555] Starting dynamic subtest: rcs0 [50.938617] Dynamic subtest rcs0: SUCCESS (0.039s) [50.952573] Starting dynamic subtest: bcs0 [50.971289] Dynamic subtest bcs0: SUCCESS (0.033s) [50.985403] Starting dynamic subtest: vcs0 [50.999047] Dynamic subtest vcs0: SUCCESS (0.028s) [51.013646] Starting dynamic subtest: vecs0 [51.032385] Dynamic subtest vecs0: SUCCESS (0.033s) [51.035864] Subtest basic-wait: SUCCESS (0.134s) [51.514187] [023/162] (948s left) gem_exec_fence (basic-await) [51.684444] Starting subtest: basic-await [51.687735] Starting dynamic subtest: rcs0 [51.760782] Dynamic subtest rcs0: SUCCESS (0.077s) [51.779765] Starting dynamic subtest: bcs0 [51.848488] Dynamic subtest bcs0: SUCCESS (0.088s) [51.869060] Starting dynamic subtest: vcs0 [51.941240] Dynamic subtest vcs0: SUCCESS (0.093s) [51.962847] Starting dynamic subtest: vecs0 [52.031615] Dynamic subtest vecs0: SUCCESS (0.091s) [52.034938] Subtest basic-await: SUCCESS (0.351s) [52.538686] [024/162] (947s left) gem_exec_fence (nb-await) [52.710131] Starting subtest: nb-await [52.713151] Starting dynamic subtest: rcs0 [52.769070] Dynamic subtest rcs0: SUCCESS (0.062s) [52.791558] Starting dynamic subtest: bcs0 [52.834434] Dynamic subtest bcs0: SUCCESS (0.065s) [52.855869] Starting dynamic subtest: vcs0 [52.898443] Dynamic subtest vcs0: SUCCESS (0.064s) [52.919917] Starting dynamic subtest: vecs0 [52.963631] Dynamic subtest vecs0: SUCCESS (0.065s) [52.966994] Subtest nb-await: SUCCESS (0.259s) [53.446913] [025/162] (946s left) gem_exec_gttfill (basic) [53.510369] Starting subtest: basic [56.773744] Subtest basic: SUCCESS (3.289s) [56.935363] [026/162] (943s left) gem_exec_parallel (engines) [57.072110] Starting subtest: engines [57.075099] Starting dynamic subtest: basic [59.002415] Dynamic subtest basic: SUCCESS (1.938s) [59.013933] Starting dynamic subtest: contexts [61.021737] Dynamic subtest contexts: SUCCESS (2.025s) [61.033648] Starting dynamic subtest: fds [62.940648] Dynamic subtest fds: SUCCESS (1.923s) [62.952572] Starting dynamic subtest: userptr [65.278666] Dynamic subtest userptr: SUCCESS (2.342s) [65.281989] Subtest engines: SUCCESS (8.230s) [65.456719] [027/162] (934s left) gem_exec_store (basic) [65.522782] Starting subtest: basic [65.576420] Subtest basic: SUCCESS (0.060s) [65.728855] [028/162] (934s left) gem_flink_basic (bad-flink) [65.778679] Starting subtest: bad-flink [65.781656] Subtest bad-flink: SUCCESS (0.000s) [65.833008] [029/162] (934s left) gem_flink_basic (bad-open) [65.877998] Starting subtest: bad-open [65.881232] Subtest bad-open: SUCCESS (0.000s) [65.925804] [030/162] (934s left) gem_flink_basic (basic) [65.975582] Starting subtest: basic [65.978621] Subtest basic: SUCCESS (0.000s) [66.047370] [031/162] (934s left) gem_flink_basic (double-flink) [66.420556] Starting subtest: double-flink [66.484052] Subtest double-flink: SUCCESS (0.000s) [66.926705] [032/162] (934s left) gem_flink_basic (flink-lifetime) [67.315705] Starting subtest: flink-lifetime [67.380341] Subtest flink-lifetime: SUCCESS (0.001s) [67.574944] [033/162] (933s left) gem_huc_copy (huc-copy) [67.628783] Starting subtest: huc-copy [67.635160] Subtest huc-copy: SKIP (0.000s) [67.699487] [034/162] (933s left) gem_linear_blits (basic) [67.825644] Starting subtest: basic [67.833683] Subtest basic: SUCCESS (0.006s) [67.929730] [035/162] (933s left) gem_mmap (basic) [68.062511] Starting subtest: basic [68.077672] Subtest basic: SUCCESS (0.000s) [68.120839] [036/162] (933s left) gem_mmap_gtt (basic) [68.172390] Starting subtest: basic [68.175646] Subtest basic: SUCCESS (0.001s) [68.220863] [037/162] (933s left) gem_render_linear_blits (basic) [68.274780] Starting subtest: basic [68.300049] Subtest basic: SUCCESS (0.027s) [68.356042] [038/162] (932s left) gem_render_tiled_blits (basic) [68.405724] Starting subtest: basic [68.436242] Subtest basic: SUCCESS (0.031s) [68.497040] [039/162] (932s left) gem_ringfill (basic-all) [68.717970] Starting subtest: basic-all [69.845356] Subtest basic-all: SUCCESS (1.130s) [69.938661] [040/162] (931s left) gem_softpin (allocator-basic) [69.997131] Starting subtest: allocator-basic [70.088215] Subtest allocator-basic: SUCCESS (0.092s) [70.170417] [041/162] (931s left) gem_softpin (allocator-basic-reserve) [70.223443] Starting subtest: allocator-basic-reserve [70.314756] Subtest allocator-basic-reserve: SUCCESS (0.094s) [70.403579] [042/162] (931s left) gem_softpin (safe-alignment) [70.455959] Starting subtest: safe-alignment [70.465880] Subtest safe-alignment: SUCCESS (0.010s) [70.541656] [043/162] (930s left) gem_sync (basic-all) [70.621370] Starting subtest: basic-all [72.662574] Subtest basic-all: SUCCESS (2.062s) [72.819239] [044/162] (928s left) gem_sync (basic-each) [72.896488] Starting subtest: basic-each [74.972375] Subtest basic-each: SUCCESS (2.097s) [75.124057] [045/162] (926s left) gem_tiled_blits (basic) [75.239857] Starting subtest: basic [75.254675] Subtest basic: SUCCESS (0.013s) [75.348486] [046/162] (926s left) gem_tiled_fence_blits (basic) [75.538713] Starting subtest: basic [75.545973] Subtest basic: SUCCESS (0.009s) [75.636085] [047/162] (926s left) gem_tiled_pread_basic (basic) [75.794388] Starting subtest: basic [75.907122] Subtest basic: SUCCESS (0.116s) [75.976059] [048/162] (925s left) gem_wait (busy@all-engines) [76.037692] Starting subtest: busy [76.040677] Starting dynamic subtest: all-engines [76.563290] Dynamic subtest all-engines: SUCCESS (0.524s) [76.566657] Subtest busy: SUCCESS (0.524s) [76.727396] [049/162] (925s left) gem_wait (wait@all-engines) [76.788422] Starting subtest: wait [76.791572] Starting dynamic subtest: all-engines [77.828735] Dynamic subtest all-engines: SUCCESS (1.040s) [77.832149] Subtest wait: SUCCESS (1.041s) [77.993388] [050/162] (923s left) i915_getparams_basic (basic-eu-total) [78.042108] Starting subtest: basic-eu-total [78.048312] Subtest basic-eu-total: SUCCESS (0.000s) [78.091555] [051/162] (923s left) i915_getparams_basic (basic-subslice-total) [78.138530] Starting subtest: basic-subslice-total [78.144470] Subtest basic-subslice-total: SUCCESS (0.000s) [78.190796] [052/162] (923s left) i915_hangman (error-state-basic) [78.251022] Starting subtest: error-state-basic [78.254861] Subtest error-state-basic: SUCCESS (0.005s) [78.308316] [053/162] (923s left) i915_pciid [78.415969] [054/162] (923s left) intel_hwmon (hwmon-read) [78.470683] Subtest hwmon-read: SKIP (0.000s) [78.516883] [055/162] (923s left) intel_hwmon (hwmon-write) [78.570658] Subtest hwmon-write: SKIP (0.000s) [78.615071] [056/162] (923s left) kms_addfb_basic (addfb25-4-tiled) [78.709171] Starting subtest: addfb25-4-tiled [78.712170] Subtest addfb25-4-tiled: SUCCESS (0.000s) [78.769251] [057/162] (923s left) kms_addfb_basic (addfb25-bad-modifier) [78.850213] Starting subtest: addfb25-bad-modifier [78.853274] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [78.913630] [058/162] (923s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [78.995760] Starting subtest: addfb25-framebuffer-vs-set-tiling [78.998975] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [79.046488] [059/162] (923s left) kms_addfb_basic (addfb25-modifier-no-flag) [79.125067] Starting subtest: addfb25-modifier-no-flag [79.128404] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [79.187293] [060/162] (923s left) kms_addfb_basic (addfb25-x-tiled-legacy) [79.267304] Starting subtest: addfb25-x-tiled-legacy [79.270639] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [79.326951] [061/162] (923s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [79.409737] Starting subtest: addfb25-x-tiled-mismatch-legacy [79.412800] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [79.459916] [062/162] (922s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [79.540769] Starting subtest: addfb25-yf-tiled-legacy [79.543972] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [79.600108] [063/162] (922s left) kms_addfb_basic (addfb25-y-tiled-legacy) [79.682160] Starting subtest: addfb25-y-tiled-legacy [79.685164] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [79.740197] [064/162] (922s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [79.821215] Starting subtest: addfb25-y-tiled-small-legacy [79.824342] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [79.882897] [065/162] (922s left) kms_addfb_basic (bad-pitch-0) [79.932635] Starting subtest: bad-pitch-0 [79.936768] Subtest bad-pitch-0: SUCCESS (0.000s) [80.013031] [066/162] (922s left) kms_addfb_basic (bad-pitch-1024) [80.063187] Starting subtest: bad-pitch-1024 [80.066953] Subtest bad-pitch-1024: SUCCESS (0.000s) [80.155535] [067/162] (922s left) kms_addfb_basic (bad-pitch-128) [80.208300] Starting subtest: bad-pitch-128 [80.212397] Subtest bad-pitch-128: SUCCESS (0.000s) [80.298506] [068/162] (922s left) kms_addfb_basic (bad-pitch-256) [80.351255] Starting subtest: bad-pitch-256 [80.354657] Subtest bad-pitch-256: SUCCESS (0.000s) [80.433553] [069/162] (922s left) kms_addfb_basic (bad-pitch-32) [80.485223] Starting subtest: bad-pitch-32 [80.489651] Subtest bad-pitch-32: SUCCESS (0.000s) [80.580356] [070/162] (922s left) kms_addfb_basic (bad-pitch-63) [80.629155] Starting subtest: bad-pitch-63 [80.633293] Subtest bad-pitch-63: SUCCESS (0.000s) [80.717385] [071/162] (922s left) kms_addfb_basic (bad-pitch-65536) [80.773372] Starting subtest: bad-pitch-65536 [80.778204] Subtest bad-pitch-65536: SUCCESS (0.000s) [80.851827] [072/162] (921s left) kms_addfb_basic (bad-pitch-999) [80.903788] Starting subtest: bad-pitch-999 [80.907751] Subtest bad-pitch-999: SUCCESS (0.000s) [80.993889] [073/162] (921s left) kms_addfb_basic (basic) [81.046519] Starting subtest: basic [81.050053] Subtest basic: SUCCESS (0.000s) [81.137549] [074/162] (921s left) kms_addfb_basic (basic-x-tiled-legacy) [81.188784] Starting subtest: basic-x-tiled-legacy [81.193204] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [81.281844] [075/162] (921s left) kms_addfb_basic (basic-y-tiled-legacy) [81.333662] Starting subtest: basic-y-tiled-legacy [81.337746] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [81.430460] [076/162] (921s left) kms_addfb_basic (bo-too-small) [81.510965] Starting subtest: bo-too-small [81.514148] Subtest bo-too-small: SUCCESS (0.000s) [81.572847] [077/162] (921s left) kms_addfb_basic (bo-too-small-due-to-tiling) [81.655266] Starting subtest: bo-too-small-due-to-tiling [81.658270] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [81.722780] [078/162] (921s left) kms_addfb_basic (clobberred-modifier) [81.774545] Starting subtest: clobberred-modifier [81.778264] Subtest clobberred-modifier: SUCCESS (0.000s) [81.895512] [079/162] (921s left) kms_addfb_basic (framebuffer-vs-set-tiling) [81.945998] Starting subtest: framebuffer-vs-set-tiling [81.950680] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [82.032745] [080/162] (921s left) kms_addfb_basic (invalid-get-prop) [82.084833] Starting subtest: invalid-get-prop [82.087833] Subtest invalid-get-prop: SUCCESS (0.000s) [82.165331] [081/162] (921s left) kms_addfb_basic (invalid-get-prop-any) [82.216177] Starting subtest: invalid-get-prop-any [82.221274] Subtest invalid-get-prop-any: SUCCESS (0.000s) [82.302703] [082/162] (921s left) kms_addfb_basic (invalid-set-prop) [82.356483] Starting subtest: invalid-set-prop [82.359750] Subtest invalid-set-prop: SUCCESS (0.000s) [82.440355] [083/162] (920s left) kms_addfb_basic (invalid-set-prop-any) [82.494178] Starting subtest: invalid-set-prop-any [82.497060] Subtest invalid-set-prop-any: SUCCESS (0.000s) [82.622597] [084/162] (920s left) kms_addfb_basic (no-handle) [82.676855] Starting subtest: no-handle [82.680525] Subtest no-handle: SUCCESS (0.000s) [82.759661] [085/162] (920s left) kms_addfb_basic (size-max) [82.838521] Starting subtest: size-max [82.841506] Subtest size-max: SUCCESS (0.000s) [82.912066] [086/162] (920s left) kms_addfb_basic (small-bo) [82.995653] Starting subtest: small-bo [82.998930] Subtest small-bo: SUCCESS (0.000s) [83.128804] [087/162] (920s left) kms_addfb_basic (tile-pitch-mismatch) [83.180470] Starting subtest: tile-pitch-mismatch [83.184495] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [83.272147] [088/162] (920s left) kms_addfb_basic (too-high) [83.352592] Starting subtest: too-high [83.355606] Subtest too-high: SUCCESS (0.001s) [83.409383] [089/162] (920s left) kms_addfb_basic (too-wide) [83.489569] Starting subtest: too-wide [83.492956] Subtest too-wide: SUCCESS (0.000s) [83.560661] [090/162] (920s left) kms_addfb_basic (unused-handle) [83.616696] Starting subtest: unused-handle [83.621962] Subtest unused-handle: SUCCESS (0.000s) [83.700574] [091/162] (920s left) kms_addfb_basic (unused-modifier) [83.777704] Starting subtest: unused-modifier [83.780802] Subtest unused-modifier: SUCCESS (0.000s) [83.973923] [092/162] (920s left) kms_addfb_basic (unused-offsets) [84.587833] Starting subtest: unused-offsets [84.652308] Subtest unused-offsets: SUCCESS (0.000s) [85.134470] [093/162] (919s left) kms_addfb_basic (unused-pitches) [85.318184] Starting subtest: unused-pitches [85.322034] Subtest unused-pitches: SUCCESS (0.000s) [85.405741] [094/162] (919s left) kms_busy (basic) [85.490509] Subtest basic: SKIP (0.000s) [85.534514] [095/162] (919s left) kms_prop_blob (basic) [85.586817] Starting subtest: basic [85.589792] Subtest basic: SUCCESS (0.000s) [85.634225] [096/162] (919s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [85.739610] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [85.786208] [097/162] (918s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [85.886280] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [85.932503] [098/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [86.035171] Subtest basic-flip-after-cursor-atomic: SKIP (0.000s) [86.079872] [099/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [86.178107] Subtest basic-flip-after-cursor-legacy: SKIP (0.000s) [86.224160] [100/162] (918s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [86.325169] Subtest basic-flip-after-cursor-varying-size: SKIP (0.000s) [86.370680] [101/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [86.471477] Subtest basic-flip-before-cursor-atomic: SKIP (0.000s) [86.517200] [102/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [86.615116] Subtest basic-flip-before-cursor-legacy: SKIP (0.000s) [86.661499] [103/162] (918s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [86.761632] Subtest basic-flip-before-cursor-varying-size: SKIP (0.000s) [86.807135] [104/162] (918s left) kms_dsc (dsc-basic) [86.886302] Subtest dsc-basic: SKIP (0.000s) [86.929657] [105/162] (918s left) kms_flip (basic-flip-vs-dpms) [87.010396] Starting subtest: basic-flip-vs-dpms [87.016338] Subtest basic-flip-vs-dpms: SKIP (0.001s) [87.074222] [106/162] (918s left) kms_flip (basic-flip-vs-modeset) [87.156225] Starting subtest: basic-flip-vs-modeset [87.162447] Subtest basic-flip-vs-modeset: SKIP (0.001s) [87.206332] [107/162] (917s left) kms_flip (basic-flip-vs-wf_vblank) [87.286305] Starting subtest: basic-flip-vs-wf_vblank [87.292171] Subtest basic-flip-vs-wf_vblank: SKIP (0.001s) [87.344140] [108/162] (917s left) kms_flip (basic-plain-flip) [87.435109] Starting subtest: basic-plain-flip [87.441611] Subtest basic-plain-flip: SKIP (0.001s) [87.486821] [109/162] (917s left) kms_force_connector_basic (force-connector-state) [87.631145] Starting subtest: force-connector-state [87.711273] Subtest force-connector-state: SUCCESS (0.081s) [87.882969] [110/162] (917s left) kms_force_connector_basic (force-edid) [88.035334] Starting subtest: force-edid [88.208189] Subtest force-edid: SUCCESS (0.174s) [88.292609] [111/162] (917s left) kms_force_connector_basic (force-load-detect) [88.444206] Starting subtest: force-load-detect [88.450143] Subtest force-load-detect: SKIP (0.000s) [88.511639] [112/162] (916s left) kms_force_connector_basic (prune-stale-modes) [88.659308] Starting subtest: prune-stale-modes [88.835255] Subtest prune-stale-modes: SUCCESS (0.176s) [88.918136] [113/162] (916s left) kms_frontbuffer_tracking (basic) [88.998823] Subtest basic: SKIP (0.000s) [89.053547] [114/162] (916s left) kms_hdmi_inject (inject-audio) [89.106054] Starting subtest: inject-audio [89.112203] Subtest inject-audio: SKIP (0.000s) [89.161381] [115/162] (916s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [89.240443] Subtest compare-crc-sanitycheck-xr24: SKIP (0.000s) [89.284615] [116/162] (916s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [89.365919] Subtest compare-crc-sanitycheck-nv12: SKIP (0.000s) [89.409759] [117/162] (916s left) kms_pipe_crc_basic (hang-read-crc) [89.489311] Subtest hang-read-crc: SKIP (0.000s) [89.533613] [118/162] (916s left) kms_pipe_crc_basic (nonblocking-crc) [89.613450] Subtest nonblocking-crc: SKIP (0.000s) [89.656117] [119/162] (916s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [89.739994] Subtest nonblocking-crc-frame-sequence: SKIP (0.000s) [89.784121] [120/162] (915s left) kms_pipe_crc_basic (read-crc) [89.862930] Subtest read-crc: SKIP (0.000s) [89.905973] [121/162] (915s left) kms_pipe_crc_basic (read-crc-frame-sequence) [89.985696] Subtest read-crc-frame-sequence: SKIP (0.000s) [90.028733] [122/162] (915s left) kms_pm_backlight (basic-brightness) [90.109761] Subtest basic-brightness: SKIP (0.000s) [90.286651] [123/162] (915s left) kms_pm_rpm (basic-pci-d3-state) [101.795918] Starting subtest: basic-pci-d3-state [101.983577] Subtest basic-pci-d3-state: SUCCESS (0.187s) [102.040848] [124/162] (904s left) kms_pm_rpm (basic-rte) [102.097247] Starting subtest: basic-rte [113.584494] Subtest basic-rte: SUCCESS (11.495s) [113.897856] [125/162] (892s left) kms_psr (psr-primary-page-flip) [113.981717] Subtest psr-primary-page-flip: SKIP (0.000s) [114.026479] [126/162] (892s left) kms_psr (psr-cursor-plane-move) [114.110237] Subtest psr-cursor-plane-move: SKIP (0.000s) [114.153076] [127/162] (892s left) kms_psr (psr-sprite-plane-onoff) [114.234963] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [114.278949] [128/162] (892s left) kms_psr (psr-primary-mmap-gtt) [114.361626] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [114.405979] [129/162] (891s left) kms_setmode (basic-clone-single-crtc) [114.459459] Starting subtest: basic-clone-single-crtc [114.468637] Subtest basic-clone-single-crtc: SKIP (0.000s) [114.517427] [130/162] (891s left) i915_pm_rps (basic-api) [114.571927] Starting subtest: basic-api [114.574910] Subtest basic-api: SUCCESS (0.003s) [114.621275] [131/162] (891s left) prime_self_import (basic-llseek-bad) [114.669066] Starting subtest: basic-llseek-bad [114.675161] Subtest basic-llseek-bad: SUCCESS (0.002s) [114.739995] [132/162] (891s left) prime_self_import (basic-llseek-size) [114.787148] Starting subtest: basic-llseek-size [114.793481] Subtest basic-llseek-size: SUCCESS (0.003s) [114.855807] [133/162] (891s left) prime_self_import (basic-with_fd_dup) [114.898790] Starting subtest: basic-with_fd_dup [114.905138] Subtest basic-with_fd_dup: SUCCESS (0.003s) [114.969469] [134/162] (891s left) prime_self_import (basic-with_one_bo) [115.018358] Starting subtest: basic-with_one_bo [115.024069] Subtest basic-with_one_bo: SUCCESS (0.003s) [115.069180] [135/162] (891s left) prime_self_import (basic-with_one_bo_two_files) [115.115976] Starting subtest: basic-with_one_bo_two_files [115.122160] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [115.194791] [136/162] (891s left) prime_self_import (basic-with_two_bos) [115.243816] Starting subtest: basic-with_two_bos [115.249917] Subtest basic-with_two_bos: SUCCESS (0.003s) [115.315049] [137/162] (891s left) prime_vgem (basic-fence-flip) [115.372839] Starting subtest: basic-fence-flip [115.429824] Subtest basic-fence-flip: SKIP (0.057s) [115.477442] [138/162] (891s left) prime_vgem (basic-fence-mmap) [115.531966] Starting subtest: basic-fence-mmap [115.554146] Subtest basic-fence-mmap: SUCCESS (0.023s) [115.624466] [139/162] (891s left) prime_vgem (basic-fence-read) [115.677176] Starting subtest: basic-fence-read [115.721077] Subtest basic-fence-read: SUCCESS (0.045s) [115.816862] [140/162] (891s left) prime_vgem (basic-gtt) [115.874152] Starting subtest: basic-gtt [115.881588] Subtest basic-gtt: SUCCESS (0.012s) [115.957358] [141/162] (890s left) prime_vgem (basic-read) [116.010220] Starting subtest: basic-read [116.030982] Subtest basic-read: SUCCESS (0.022s) [116.110055] [142/162] (890s left) prime_vgem (basic-write) [116.163594] Starting subtest: basic-write [116.185150] Subtest basic-write: SUCCESS (0.022s) [116.261325] [143/162] (890s left) vgem_basic (setversion) [116.315159] Starting subtest: setversion [116.321794] Subtest setversion: SUCCESS (0.000s) [116.371132] [144/162] (890s left) vgem_basic (create) [116.421394] Starting subtest: create [116.424489] Subtest create: SUCCESS (0.000s) [116.473977] [145/162] (890s left) vgem_basic (debugfs) [116.520729] Starting subtest: debugfs [116.523807] Subtest debugfs: SUCCESS (0.000s) [116.576300] [146/162] (890s left) vgem_basic (dmabuf-export) [116.625382] Starting subtest: dmabuf-export [116.631588] Subtest dmabuf-export: SUCCESS (0.002s) [116.692552] [147/162] (890s left) vgem_basic (dmabuf-fence) [116.745218] Starting subtest: dmabuf-fence [116.748155] Subtest dmabuf-fence: SUCCESS (0.001s) [116.798921] [148/162] (890s left) vgem_basic (dmabuf-fence-before) [116.851600] Starting subtest: dmabuf-fence-before [116.854619] Subtest dmabuf-fence-before: SUCCESS (0.000s) [116.905693] [149/162] (890s left) vgem_basic (dmabuf-mmap) [116.958511] Starting subtest: dmabuf-mmap [116.961503] Subtest dmabuf-mmap: SUCCESS (0.009s) [117.007090] [150/162] (890s left) vgem_basic (mmap) [117.057216] Starting subtest: mmap [117.060214] Subtest mmap: SUCCESS (0.007s) [117.104471] [151/162] (890s left) vgem_basic (second-client) [117.155017] Starting subtest: second-client [117.161591] Subtest second-client: SUCCESS (0.002s) [117.213423] [152/162] (890s left) vgem_basic (sysfs) [117.260976] Starting subtest: sysfs [117.264160] Subtest sysfs: SUCCESS (0.000s) [117.319175] [153/162] (890s left) core_hotunplug (unbind-rebind) [117.430552] Starting subtest: unbind-rebind [120.856672] Subtest unbind-rebind: SUCCESS (3.426s) [120.900089] [154/162] (886s left) vgem_basic (unload) [120.951993] Starting subtest: unload [149.781454] Subtest unload: SUCCESS (28.835s) [149.847270] [155/162] (857s left) i915_module_load (reload) [149.897383] Starting subtest: reload [152.408472] Subtest reload: SUCCESS (2.514s) [152.456075] [156/162] (855s left) gem_lmem_swapping (basic) [152.516762] Subtest basic: SKIP (0.000s) [152.559108] [157/162] (855s left) gem_lmem_swapping (parallel-random-engines) [152.614619] Subtest parallel-random-engines: SKIP (0.000s) [152.657486] [158/162] (855s left) gem_lmem_swapping (random-engines) [152.710570] Subtest random-engines: SKIP (0.000s) [152.753830] [159/162] (855s left) gem_lmem_swapping (verify-random) [152.809647] Subtest verify-random: SKIP (0.000s) [152.853644] [160/162] (854s left) i915_pm_rpm (module-reload) [164.183025] Starting subtest: module-reload [191.877204] Subtest module-reload: SUCCESS (27.694s) [191.908918] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [191.909905] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true [191.913766] [161/162] (815s left) i915_selftest (live) [191.964481] Starting subtest: live [191.967636] Starting dynamic subtest: sanitycheck [194.120114] Dynamic subtest sanitycheck: SUCCESS (2.152s) [194.131563] Starting dynamic subtest: uncore [196.250873] Dynamic subtest uncore: SUCCESS (2.137s) [196.262638] Starting dynamic subtest: workarounds [198.720024] Dynamic subtest workarounds: SUCCESS (2.462s) [198.740103] Starting dynamic subtest: gt_engines [201.460262] Dynamic subtest gt_engines: SUCCESS (2.745s) [201.471559] Starting dynamic subtest: gt_timelines [210.212577] Dynamic subtest gt_timelines: SUCCESS (8.753s) [210.225127] Starting dynamic subtest: gt_contexts [212.499813] Dynamic subtest gt_contexts: SUCCESS (2.279s) [212.511589] Starting dynamic subtest: gt_lrc [217.129045] Dynamic subtest gt_lrc: SUCCESS (4.635s) [217.140870] Starting dynamic subtest: gt_mocs [219.389779] Dynamic subtest gt_mocs: SUCCESS (2.258s) [219.400813] Starting dynamic subtest: gt_pm [225.901732] Dynamic subtest gt_pm: SUCCESS (6.513s) [225.913986] Starting dynamic subtest: gt_heartbeat [228.014321] Dynamic subtest gt_heartbeat: SUCCESS (2.110s) [228.027249] Starting dynamic subtest: gt_tlb [231.367858] Dynamic subtest gt_tlb: SUCCESS (3.354s) [231.379445] Starting dynamic subtest: requests [239.698869] Dynamic subtest requests: SUCCESS (8.330s) [239.714152] Starting dynamic subtest: migrate [244.829890] Dynamic subtest migrate: SUCCESS (5.130s) [244.841625] Starting dynamic subtest: active [247.009938] Dynamic subtest active: SUCCESS (2.175s) [247.021806] Starting dynamic subtest: objects [249.693667] Dynamic subtest objects: SUCCESS (2.687s) [249.709264] Starting dynamic subtest: mman [255.005277] Dynamic subtest mman: SUCCESS (5.309s) [255.018233] Starting dynamic subtest: dmabuf [257.269573] Dynamic subtest dmabuf: SUCCESS (2.261s) [257.281544] Starting dynamic subtest: vma [259.538365] Dynamic subtest vma: SUCCESS (2.238s) [259.602394] Starting dynamic subtest: coherency [262.530384] Dynamic subtest coherency: SUCCESS (3.023s) [262.542477] Starting dynamic subtest: gtt [272.068626] Dynamic subtest gtt: SUCCESS (9.539s) [272.080129] Starting dynamic subtest: gem [274.369402] Dynamic subtest gem: SUCCESS (2.299s) [274.381192] Starting dynamic subtest: evict [277.659925] Dynamic subtest evict: SUCCESS (3.290s) [277.671404] Starting dynamic subtest: hugepages [286.260195] Dynamic subtest hugepages: SUCCESS (8.599s) [286.271702] Starting dynamic subtest: gem_contexts [300.951416] Dynamic subtest gem_contexts: SUCCESS (14.691s) [300.962884] Starting dynamic subtest: client [303.189612] Dynamic subtest client: SUCCESS (2.231s) [303.201547] Starting dynamic subtest: gem_migrate [305.423325] Dynamic subtest gem_migrate: SUCCESS (2.237s) [305.437430] Starting dynamic subtest: reset [307.819108] Dynamic subtest reset: SUCCESS (2.391s) [307.831196] Starting dynamic subtest: memory_region [309.979360] Dynamic subtest memory_region: SUCCESS (2.156s) [309.992435] Starting dynamic subtest: hangcheck [335.199991] Dynamic subtest hangcheck: SUCCESS (25.228s) [335.215917] Starting dynamic subtest: execlists [345.612284] Dynamic subtest execlists: SUCCESS (10.410s) [345.629208] Starting dynamic subtest: ring_submission [347.759431] Dynamic subtest ring_submission: SUCCESS (2.144s) [347.770852] Starting dynamic subtest: perf [349.977570] Dynamic subtest perf: SUCCESS (2.220s) [349.989533] Starting dynamic subtest: slpc [352.139496] Dynamic subtest slpc: SUCCESS (2.153s) [352.160451] Starting dynamic subtest: guc [354.319208] Dynamic subtest guc: SUCCESS (2.183s) [354.332279] Starting dynamic subtest: guc_multi_lrc [356.468987] Dynamic subtest guc_multi_lrc: SUCCESS (2.147s) [356.480820] Starting dynamic subtest: guc_hang [358.610300] Dynamic subtest guc_hang: SUCCESS (2.141s) [358.623310] Starting dynamic subtest: late_gt_pm [360.839060] Dynamic subtest late_gt_pm: SUCCESS (2.227s) [360.842478] Subtest live: SUCCESS (168.874s) [360.879785] [162/162] (647s left) dmabuf (all-tests) [360.938201] Starting subtest: all-tests [360.944131] Subtest all-tests: SKIP (0.000s) [360.984006] 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.