[40.609639] Initializing watchdogs [40.609688] /dev/watchdog0 [40.614117] [001/170] (960s left) i915_module_load (load) [40.655142] Starting subtest: load [41.233047] Subtest load: SUCCESS (0.578s) [41.242181] [002/170] (959s left) core_auth (basic-auth) [41.303388] Starting subtest: basic-auth [41.304212] Subtest basic-auth: SUCCESS (0.000s) [41.359179] [003/170] (959s left) debugfs_test (read_all_entries) [41.400839] Starting subtest: read_all_entries [41.450881] Subtest read_all_entries: SUCCESS (0.049s) [41.542614] [004/170] (959s left) debugfs_test (basic-hwmon) [41.588928] Starting subtest: basic-hwmon [41.590562] Subtest basic-hwmon: SKIP (0.000s) [41.678508] [005/170] (959s left) fbdev (eof) [41.734391] Starting subtest: eof [41.735393] Subtest eof: SUCCESS (0.000s) [41.794967] [006/170] (959s left) fbdev (info) [41.851939] Starting subtest: info [41.852659] Subtest info: SUCCESS (0.000s) [41.922460] [007/170] (959s left) fbdev (nullptr) [41.983121] Starting subtest: nullptr [41.983879] Subtest nullptr: SUCCESS (0.000s) [42.029631] [008/170] (959s left) fbdev (read) [42.083107] Starting subtest: read [42.662421] Subtest read: SUCCESS (0.579s) [42.692402] [009/170] (958s left) fbdev (write) [42.733168] Starting subtest: write [43.121883] Subtest write: SUCCESS (0.388s) [43.159317] [010/170] (958s left) gem_basic (bad-close) [43.206471] Starting subtest: bad-close [43.207865] Subtest bad-close: SUCCESS (0.000s) [43.268283] [011/170] (957s left) gem_basic (create-close) [43.314173] Starting subtest: create-close [43.315602] Subtest create-close: SUCCESS (0.000s) [43.389971] [012/170] (957s left) gem_basic (create-fd-close) [43.447416] Starting subtest: create-fd-close [43.449113] Subtest create-fd-close: SUCCESS (0.000s) [43.511401] [013/170] (957s left) gem_busy (busy@all-engines) [43.566984] Starting subtest: busy [43.567794] Starting dynamic subtest: all-engines [43.585242] Dynamic subtest all-engines: SUCCESS (0.018s) [43.585884] Subtest busy: SUCCESS (0.018s) [43.942572] [014/170] (957s left) gem_close_race (basic-process) [44.007121] Starting subtest: basic-process [44.039456] Subtest basic-process: SUCCESS (0.032s) [44.209525] [015/170] (957s left) gem_close_race (basic-threads) [44.269039] Starting subtest: basic-threads [45.462750] Subtest basic-threads: SUCCESS (1.193s) [45.658591] [016/170] (955s left) gem_ctx_create (basic) [45.725379] Starting subtest: basic [45.726197] Subtest basic: SUCCESS (0.000s) [45.910513] [017/170] (955s left) gem_ctx_create (basic-files) [45.966257] Starting subtest: basic-files [47.973966] Subtest basic-files: SUCCESS (2.008s) [48.168204] [018/170] (953s left) gem_ctx_exec (basic) [48.226962] Starting subtest: basic [48.228355] Subtest basic: SUCCESS (0.002s) [48.336509] [019/170] (953s left) gem_exec_basic (basic) [48.394173] Starting subtest: basic [48.395060] Starting dynamic subtest: rcs0-smem [48.395773] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [48.398814] Starting dynamic subtest: bcs0-smem [48.399471] Dynamic subtest bcs0-smem: SUCCESS (0.000s) [48.402604] Starting dynamic subtest: vcs0-smem [48.403330] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [48.406197] Starting dynamic subtest: vecs0-smem [48.406899] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [48.407563] Subtest basic: SUCCESS (0.002s) [48.578219] [020/170] (952s left) gem_exec_create (basic) [48.638592] Starting subtest: basic [48.639335] Starting dynamic subtest: smem [50.685040] Dynamic subtest smem: SUCCESS (2.046s) [50.685791] Subtest basic: SUCCESS (2.046s) [50.860587] [021/170] (950s left) gem_exec_fence (basic-busy) [51.042113] Starting subtest: basic-busy [51.043893] Starting dynamic subtest: rcs0 [51.088279] Dynamic subtest rcs0: SUCCESS (0.046s) [51.100170] Starting dynamic subtest: bcs0 [51.117695] Dynamic subtest bcs0: SUCCESS (0.029s) [51.125257] Starting dynamic subtest: vcs0 [51.151399] Dynamic subtest vcs0: SUCCESS (0.033s) [51.162342] Starting dynamic subtest: vecs0 [51.177050] Dynamic subtest vecs0: SUCCESS (0.026s) [51.178664] Subtest basic-busy: SUCCESS (0.135s) [51.646296] [022/170] (949s left) gem_exec_fence (basic-wait) [51.831678] Starting subtest: basic-wait [51.833187] Starting dynamic subtest: rcs0 [51.885258] Dynamic subtest rcs0: SUCCESS (0.053s) [51.892864] Starting dynamic subtest: bcs0 [51.920193] Dynamic subtest bcs0: SUCCESS (0.035s) [51.934091] Starting dynamic subtest: vcs0 [51.955141] Dynamic subtest vcs0: SUCCESS (0.035s) [51.969240] Starting dynamic subtest: vecs0 [51.996152] Dynamic subtest vecs0: SUCCESS (0.041s) [51.997890] Subtest basic-wait: SUCCESS (0.164s) [52.445393] [023/170] (949s left) gem_exec_fence (basic-await) [52.643877] Starting subtest: basic-await [52.645552] Starting dynamic subtest: rcs0 [52.717709] Dynamic subtest rcs0: SUCCESS (0.073s) [52.730030] Starting dynamic subtest: bcs0 [52.795329] Dynamic subtest bcs0: SUCCESS (0.077s) [52.808624] Starting dynamic subtest: vcs0 [52.875526] Dynamic subtest vcs0: SUCCESS (0.080s) [52.887654] Starting dynamic subtest: vecs0 [52.954536] Dynamic subtest vecs0: SUCCESS (0.079s) [52.956381] Subtest basic-await: SUCCESS (0.311s) [53.394496] [024/170] (948s left) gem_exec_fence (nb-await) [53.571929] Starting subtest: nb-await [53.573416] Starting dynamic subtest: rcs0 [53.633636] Dynamic subtest rcs0: SUCCESS (0.061s) [53.647402] Starting dynamic subtest: bcs0 [53.696974] Dynamic subtest bcs0: SUCCESS (0.063s) [53.711472] Starting dynamic subtest: vcs0 [53.759734] Dynamic subtest vcs0: SUCCESS (0.063s) [53.774255] Starting dynamic subtest: vecs0 [53.823074] Dynamic subtest vecs0: SUCCESS (0.063s) [53.824819] Subtest nb-await: SUCCESS (0.251s) [54.293510] [025/170] (947s left) gem_exec_gttfill (basic) [54.354843] Starting subtest: basic [56.862178] Subtest basic: SUCCESS (2.507s) [57.047844] [026/170] (944s left) gem_exec_parallel (engines) [57.136286] Starting subtest: engines [57.136981] Starting dynamic subtest: basic [58.533041] Dynamic subtest basic: SUCCESS (1.397s) [58.536710] Starting dynamic subtest: contexts [60.250737] Dynamic subtest contexts: SUCCESS (1.717s) [60.254866] Starting dynamic subtest: fds [62.047334] Dynamic subtest fds: SUCCESS (1.796s) [62.060261] Starting dynamic subtest: userptr [63.428225] Dynamic subtest userptr: SUCCESS (1.381s) [63.428950] Subtest engines: SUCCESS (6.292s) [63.612102] [027/170] (937s left) gem_exec_store (basic) [63.674163] Starting subtest: basic [63.725118] Subtest basic: SUCCESS (0.050s) [63.911304] [028/170] (937s left) gem_flink_basic (bad-flink) [63.956106] Starting subtest: bad-flink [63.957102] Subtest bad-flink: SUCCESS (0.000s) [64.024106] [029/170] (937s left) gem_flink_basic (bad-open) [64.080028] Starting subtest: bad-open [64.080761] Subtest bad-open: SUCCESS (0.000s) [64.137574] [030/170] (937s left) gem_flink_basic (basic) [64.193441] Starting subtest: basic [64.194185] Subtest basic: SUCCESS (0.000s) [64.278861] [031/170] (937s left) gem_flink_basic (double-flink) [64.326120] Starting subtest: double-flink [64.326843] Subtest double-flink: SUCCESS (0.000s) [64.412155] [032/170] (937s left) gem_flink_basic (flink-lifetime) [64.459792] Starting subtest: flink-lifetime [64.460526] Subtest flink-lifetime: SUCCESS (0.001s) [64.562612] [033/170] (937s left) gem_huc_copy (huc-copy) [64.611538] Starting subtest: huc-copy [64.612904] Subtest huc-copy: SKIP (0.000s) [64.730297] [034/170] (936s left) gem_linear_blits (basic) [64.838407] Starting subtest: basic [64.841867] Subtest basic: SUCCESS (0.004s) [64.955570] [035/170] (936s left) gem_mmap (basic) [65.036853] Starting subtest: basic [65.040586] Subtest basic: SUCCESS (0.000s) [65.128286] [036/170] (936s left) gem_mmap_gtt (basic) [65.175945] Starting subtest: basic [65.176856] Subtest basic: SUCCESS (0.000s) [65.231655] [037/170] (936s left) gem_render_linear_blits (basic) [65.275854] Starting subtest: basic [65.301558] Subtest basic: SUCCESS (0.026s) [65.341875] [038/170] (936s left) gem_render_tiled_blits (basic) [65.391327] Starting subtest: basic [65.410663] Subtest basic: SUCCESS (0.019s) [65.467453] [039/170] (936s left) gem_ringfill (basic-all) [65.647489] Starting subtest: basic-all [66.755023] Subtest basic-all: SUCCESS (1.110s) [66.876345] [040/170] (934s left) gem_softpin (allocator-basic) [66.932447] Starting subtest: allocator-basic [66.985748] Subtest allocator-basic: SUCCESS (0.053s) [67.079837] [041/170] (934s left) gem_softpin (allocator-basic-reserve) [67.135646] Starting subtest: allocator-basic-reserve [67.184462] Subtest allocator-basic-reserve: SUCCESS (0.049s) [67.295898] [042/170] (934s left) gem_softpin (safe-alignment) [67.343148] Starting subtest: safe-alignment [67.348429] Subtest safe-alignment: SUCCESS (0.005s) [67.446429] [043/170] (934s left) gem_sync (basic-all) [67.506804] Starting subtest: basic-all [69.617031] Subtest basic-all: SUCCESS (2.111s) [69.793958] [044/170] (932s left) gem_sync (basic-each) [69.845923] Starting subtest: basic-each [71.901052] Subtest basic-each: SUCCESS (2.056s) [72.110468] [045/170] (929s left) gem_tiled_blits (basic) [72.210055] Starting subtest: basic [72.219399] Subtest basic: SUCCESS (0.009s) [72.338650] [046/170] (929s left) gem_tiled_fence_blits (basic) [72.475425] Starting subtest: basic [72.494698] Subtest basic: SUCCESS (0.019s) [72.602846] [047/170] (929s left) gem_tiled_pread_basic [72.738818] [048/170] (929s left) gem_wait (busy@all-engines) [72.795626] Starting subtest: busy [72.796437] Starting dynamic subtest: all-engines [73.315020] Dynamic subtest all-engines: SUCCESS (0.519s) [73.315802] Subtest busy: SUCCESS (0.519s) [73.510110] [049/170] (928s left) gem_wait (wait@all-engines) [73.563433] Starting subtest: wait [73.564126] Starting dynamic subtest: all-engines [74.588166] Dynamic subtest all-engines: SUCCESS (1.024s) [74.589590] Subtest wait: SUCCESS (1.024s) [74.794223] [050/170] (927s left) i915_getparams_basic (basic-eu-total) [74.840835] Starting subtest: basic-eu-total [74.842212] Subtest basic-eu-total: SUCCESS (0.000s) [74.907612] [051/170] (927s left) i915_getparams_basic (basic-subslice-total) [74.964264] Starting subtest: basic-subslice-total [74.965577] Subtest basic-subslice-total: SUCCESS (0.000s) [75.022079] [052/170] (927s left) i915_hangman (error-state-basic) [75.082207] Starting subtest: error-state-basic [75.086871] Subtest error-state-basic: SUCCESS (0.004s) [75.180527] [053/170] (926s left) i915_pciid [75.310441] [054/170] (926s left) kms_addfb_basic (addfb25-bad-modifier) [75.365222] Starting subtest: addfb25-bad-modifier [75.365959] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [75.489550] [055/170] (926s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [75.543772] Starting subtest: addfb25-framebuffer-vs-set-tiling [75.544518] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [75.661469] [056/170] (926s left) kms_addfb_basic (addfb25-modifier-no-flag) [75.714645] Starting subtest: addfb25-modifier-no-flag [75.715516] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [75.836229] [057/170] (926s left) kms_addfb_basic (addfb25-x-tiled-legacy) [75.890930] Starting subtest: addfb25-x-tiled-legacy [75.891716] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [75.993981] [058/170] (926s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [76.035648] Starting subtest: addfb25-x-tiled-mismatch-legacy [76.036360] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [76.148818] [059/170] (926s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [76.222981] Starting subtest: addfb25-yf-tiled-legacy [76.223769] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [76.276444] [060/170] (925s left) kms_addfb_basic (addfb25-y-tiled-legacy) [76.348449] Starting subtest: addfb25-y-tiled-legacy [76.349351] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [76.410279] [061/170] (925s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [76.481962] Starting subtest: addfb25-y-tiled-small-legacy [76.482873] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [76.543836] [062/170] (925s left) kms_addfb_basic (bad-pitch-0) [76.582178] Starting subtest: bad-pitch-0 [76.582945] Subtest bad-pitch-0: SUCCESS (0.000s) [76.702837] [063/170] (925s left) kms_addfb_basic (bad-pitch-1024) [76.750281] Starting subtest: bad-pitch-1024 [76.750991] Subtest bad-pitch-1024: SUCCESS (0.000s) [76.865171] [064/170] (925s left) kms_addfb_basic (bad-pitch-128) [76.909919] Starting subtest: bad-pitch-128 [76.910665] Subtest bad-pitch-128: SUCCESS (0.000s) [77.010125] [065/170] (925s left) kms_addfb_basic (bad-pitch-256) [77.050653] Starting subtest: bad-pitch-256 [77.051501] Subtest bad-pitch-256: SUCCESS (0.000s) [77.143186] [066/170] (925s left) kms_addfb_basic (bad-pitch-32) [77.181306] Starting subtest: bad-pitch-32 [77.182078] Subtest bad-pitch-32: SUCCESS (0.000s) [77.276663] [067/170] (924s left) kms_addfb_basic (bad-pitch-63) [77.316897] Starting subtest: bad-pitch-63 [77.317621] Subtest bad-pitch-63: SUCCESS (0.000s) [77.434628] [068/170] (924s left) kms_addfb_basic (bad-pitch-65536) [77.478028] Starting subtest: bad-pitch-65536 [77.478736] Subtest bad-pitch-65536: SUCCESS (0.000s) [77.576361] [069/170] (924s left) kms_addfb_basic (bad-pitch-999) [77.618867] Starting subtest: bad-pitch-999 [77.619614] Subtest bad-pitch-999: SUCCESS (0.000s) [77.732473] [070/170] (924s left) kms_addfb_basic (basic) [77.786998] Starting subtest: basic [77.787707] Subtest basic: SUCCESS (0.000s) [77.893271] [071/170] (924s left) kms_addfb_basic (basic-x-tiled-legacy) [77.932956] Starting subtest: basic-x-tiled-legacy [77.933704] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [78.052208] [072/170] (924s left) kms_addfb_basic (basic-y-tiled-legacy) [78.108050] Starting subtest: basic-y-tiled-legacy [78.108827] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [78.211292] [073/170] (924s left) kms_addfb_basic (bo-too-small) [78.288766] Starting subtest: bo-too-small [78.289649] Subtest bo-too-small: SUCCESS (0.000s) [78.342745] [074/170] (923s left) kms_addfb_basic (bo-too-small-due-to-tiling) [78.416031] Starting subtest: bo-too-small-due-to-tiling [78.416860] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [78.503860] [075/170] (923s left) kms_addfb_basic (clobberred-modifier) [78.550483] Starting subtest: clobberred-modifier [78.551176] Subtest clobberred-modifier: SUCCESS (0.000s) [78.674838] [076/170] (923s left) kms_addfb_basic (framebuffer-vs-set-tiling) [78.720383] Starting subtest: framebuffer-vs-set-tiling [78.721124] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [78.834056] [077/170] (923s left) kms_addfb_basic (invalid-get-prop) [78.889999] Starting subtest: invalid-get-prop [78.890682] Subtest invalid-get-prop: SUCCESS (0.000s) [78.996725] [078/170] (923s left) kms_addfb_basic (invalid-get-prop-any) [79.053541] Starting subtest: invalid-get-prop-any [79.054397] Subtest invalid-get-prop-any: SUCCESS (0.000s) [79.142711] [079/170] (923s left) kms_addfb_basic (invalid-set-prop) [79.186323] Starting subtest: invalid-set-prop [79.187100] Subtest invalid-set-prop: SUCCESS (0.000s) [79.276190] [080/170] (923s left) kms_addfb_basic (invalid-set-prop-any) [79.319314] Starting subtest: invalid-set-prop-any [79.320074] Subtest invalid-set-prop-any: SUCCESS (0.000s) [79.432770] [081/170] (922s left) kms_addfb_basic (no-handle) [79.485069] Starting subtest: no-handle [79.485794] Subtest no-handle: SUCCESS (0.000s) [79.600493] [082/170] (922s left) kms_addfb_basic (size-max) [79.688845] Starting subtest: size-max [79.689522] Subtest size-max: SUCCESS (0.000s) [79.764560] [083/170] (922s left) kms_addfb_basic (small-bo) [79.845074] Starting subtest: small-bo [79.845895] Subtest small-bo: SUCCESS (0.000s) [79.910332] [084/170] (922s left) kms_addfb_basic (tile-pitch-mismatch) [79.953814] Starting subtest: tile-pitch-mismatch [79.954568] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [80.067442] [085/170] (922s left) kms_addfb_basic (too-high) [80.156901] Starting subtest: too-high [80.157602] Subtest too-high: SUCCESS (0.000s) [80.227448] [086/170] (922s left) kms_addfb_basic (too-wide) [80.304873] Starting subtest: too-wide [80.305620] Subtest too-wide: SUCCESS (0.000s) [80.378493] [087/170] (922s left) kms_addfb_basic (unused-handle) [80.433224] Starting subtest: unused-handle [80.433903] Subtest unused-handle: SUCCESS (0.000s) [80.550574] [088/170] (921s left) kms_addfb_basic (unused-modifier) [80.594329] Starting subtest: unused-modifier [80.595017] Subtest unused-modifier: SUCCESS (0.000s) [80.725194] [089/170] (921s left) kms_addfb_basic (unused-offsets) [80.771758] Starting subtest: unused-offsets [80.772520] Subtest unused-offsets: SUCCESS (0.000s) [80.859390] [090/170] (921s left) kms_addfb_basic (unused-pitches) [80.900211] Starting subtest: unused-pitches [80.900880] Subtest unused-pitches: SUCCESS (0.000s) [80.993252] [091/170] (921s left) kms_busy (basic) [81.076027] Starting subtest: basic [81.076811] Starting dynamic subtest: flip [81.638538] Dynamic subtest flip: SUCCESS (0.562s) [81.642487] Starting dynamic subtest: modeset [82.285523] Dynamic subtest modeset: SUCCESS (0.647s) [82.286266] Subtest basic: SUCCESS (1.210s) [82.407345] [092/170] (920s left) kms_chamelium_hpd (dp-hpd-fast) [82.551632] Subtest dp-hpd-fast: SKIP (0.000s) [82.612941] [093/170] (919s left) kms_chamelium_edid (dp-edid-read) [82.761668] Subtest dp-edid-read: SKIP (0.000s) [82.821870] [094/170] (919s left) kms_chamelium_frames (dp-crc-fast) [82.971005] Subtest dp-crc-fast: SKIP (0.000s) [83.031230] [095/170] (919s left) kms_chamelium_hpd (hdmi-hpd-fast) [83.179747] Subtest hdmi-hpd-fast: SKIP (0.000s) [83.241137] [096/170] (919s left) kms_chamelium_edid (hdmi-edid-read) [83.389816] Subtest hdmi-edid-read: SKIP (0.000s) [83.450198] [097/170] (919s left) kms_chamelium_frames (hdmi-crc-fast) [83.599677] Subtest hdmi-crc-fast: SKIP (0.000s) [83.661237] [098/170] (918s left) kms_chamelium_hpd (vga-hpd-fast) [83.809930] Subtest vga-hpd-fast: SKIP (0.000s) [83.870279] [099/170] (918s left) kms_chamelium_edid (vga-edid-read) [84.018470] Subtest vga-edid-read: SKIP (0.000s) [84.079752] [100/170] (918s left) kms_prop_blob (basic) [84.118975] Starting subtest: basic [84.119669] Subtest basic: SUCCESS (0.000s) [84.166556] [101/170] (918s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [84.238868] Starting subtest: basic-busy-flip-before-cursor-atomic [84.240161] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [84.283306] [102/170] (918s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [84.356061] Starting subtest: basic-busy-flip-before-cursor-legacy [84.357538] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [84.400053] [103/170] (918s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [84.472445] Starting subtest: basic-flip-after-cursor-atomic [85.446675] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.974s) [85.562601] [104/170] (917s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [85.633856] Starting subtest: basic-flip-after-cursor-legacy [86.605634] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.972s) [86.723385] [105/170] (915s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [86.796028] Starting subtest: basic-flip-after-cursor-varying-size [87.784848] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.989s) [87.904829] [106/170] (914s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [87.975666] Starting subtest: basic-flip-before-cursor-atomic [88.948758] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.973s) [89.074543] [107/170] (913s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [89.148180] Starting subtest: basic-flip-before-cursor-legacy [90.117779] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.971s) [90.225313] [108/170] (912s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [90.297239] Starting subtest: basic-flip-before-cursor-varying-size [91.268551] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.972s) [91.385268] [109/170] (911s left) kms_flip (basic-flip-vs-dpms) [91.454311] Starting subtest: basic-flip-vs-dpms [91.455157] Starting dynamic subtest: A-HDMI-A1 [92.385235] Dynamic subtest A-HDMI-A1: SUCCESS (0.931s) [92.389396] Starting dynamic subtest: B-HDMI-A1 [93.282315] Dynamic subtest B-HDMI-A1: SUCCESS (0.898s) [93.286236] Starting dynamic subtest: C-HDMI-A1 [94.156989] Dynamic subtest C-HDMI-A1: SUCCESS (0.874s) [94.157738] Subtest basic-flip-vs-dpms: SUCCESS (2.704s) [94.269612] [110/170] (908s left) kms_flip (basic-flip-vs-modeset) [94.337867] Starting subtest: basic-flip-vs-modeset [94.338599] Starting dynamic subtest: A-HDMI-A1 [95.291980] Dynamic subtest A-HDMI-A1: SUCCESS (0.955s) [95.295817] Starting dynamic subtest: B-HDMI-A1 [96.075367] Dynamic subtest B-HDMI-A1: SUCCESS (0.783s) [96.079223] Starting dynamic subtest: C-HDMI-A1 [96.966759] Dynamic subtest C-HDMI-A1: SUCCESS (0.890s) [96.967438] Subtest basic-flip-vs-modeset: SUCCESS (2.630s) [97.090844] [111/170] (905s left) kms_flip (basic-flip-vs-wf_vblank) [97.160444] Starting subtest: basic-flip-vs-wf_vblank [97.161150] Starting dynamic subtest: A-HDMI-A1 [98.347067] Dynamic subtest A-HDMI-A1: SUCCESS (1.186s) [98.351790] Starting dynamic subtest: B-HDMI-A1 [99.492868] Dynamic subtest B-HDMI-A1: SUCCESS (1.145s) [99.498651] Starting dynamic subtest: C-HDMI-A1 [100.617873] Dynamic subtest C-HDMI-A1: SUCCESS (1.125s) [100.618829] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.460s) [100.799038] [112/170] (901s left) kms_flip (basic-plain-flip) [100.874025] Starting subtest: basic-plain-flip [100.874777] Starting dynamic subtest: A-HDMI-A1 [101.768602] Dynamic subtest A-HDMI-A1: SUCCESS (0.894s) [101.776559] Starting dynamic subtest: B-HDMI-A1 [102.630863] Dynamic subtest B-HDMI-A1: SUCCESS (0.861s) [102.637396] Starting dynamic subtest: C-HDMI-A1 [103.491950] Dynamic subtest C-HDMI-A1: SUCCESS (0.860s) [103.493112] Subtest basic-plain-flip: SUCCESS (2.620s) [103.637731] [113/170] (899s left) kms_force_connector_basic (force-connector-state) [103.761523] Starting subtest: force-connector-state [104.698101] Subtest force-connector-state: SUCCESS (0.938s) [104.945441] [114/170] (897s left) kms_force_connector_basic (force-edid) [105.066884] Starting subtest: force-edid [105.205391] Subtest force-edid: SUCCESS (0.137s) [105.362104] [115/170] (897s left) kms_force_connector_basic (force-load-detect) [105.482928] Starting subtest: force-load-detect [105.484319] Subtest force-load-detect: SKIP (0.000s) [105.645366] [116/170] (897s left) kms_force_connector_basic (prune-stale-modes) [105.768262] Starting subtest: prune-stale-modes [105.903830] Subtest prune-stale-modes: SUCCESS (0.136s) [106.062148] [117/170] (896s left) kms_frontbuffer_tracking (basic) [106.138748] Starting subtest: basic [108.937220] Subtest basic: SUCCESS (2.799s) [109.051385] [118/170] (893s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [109.136846] Starting subtest: compare-crc-sanitycheck [109.139042] Starting dynamic subtest: pipe-A-HDMI-A-1 [109.573166] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.435s) [109.578928] Starting dynamic subtest: pipe-B-HDMI-A-1 [110.154444] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.579s) [110.161106] Starting dynamic subtest: pipe-C-HDMI-A-1 [110.939493] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.782s) [110.940245] Subtest compare-crc-sanitycheck: SUCCESS (1.803s) [111.053203] [119/170] (891s left) kms_pipe_crc_basic (hang-read-crc) [111.126338] Starting subtest: hang-read-crc [111.127110] Starting dynamic subtest: pipe-A-HDMI-A-1 [112.152528] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.026s) [112.158489] Starting dynamic subtest: pipe-B-HDMI-A-1 [113.524513] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.369s) [113.530748] Starting dynamic subtest: pipe-C-HDMI-A-1 [115.324615] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.797s) [115.325418] Subtest hang-read-crc: SUCCESS (4.199s) [115.418974] [120/170] (887s left) kms_pipe_crc_basic (nonblocking-crc) [115.493595] Starting subtest: nonblocking-crc [115.494496] Starting dynamic subtest: pipe-A-HDMI-A-1 [116.079461] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.586s) [116.085228] Starting dynamic subtest: pipe-B-HDMI-A-1 [116.823505] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.741s) [116.829228] Starting dynamic subtest: pipe-C-HDMI-A-1 [117.784094] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.957s) [117.784811] Subtest nonblocking-crc: SUCCESS (2.292s) [117.878853] [121/170] (884s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [117.950796] Starting subtest: nonblocking-crc-frame-sequence [117.952950] Starting dynamic subtest: pipe-A-HDMI-A-1 [118.555994] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.604s) [118.561335] Starting dynamic subtest: pipe-B-HDMI-A-1 [119.304324] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.744s) [119.310107] Starting dynamic subtest: pipe-C-HDMI-A-1 [120.257479] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.952s) [120.258149] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.307s) [120.352182] [122/170] (882s left) kms_pipe_crc_basic (read-crc) [120.426629] Starting subtest: read-crc [120.427399] Starting dynamic subtest: pipe-A-HDMI-A-1 [120.964034] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.537s) [120.969577] Starting dynamic subtest: pipe-B-HDMI-A-1 [121.642434] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.676s) [121.648500] Starting dynamic subtest: pipe-C-HDMI-A-1 [122.538521] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.893s) [122.539251] Subtest read-crc: SUCCESS (2.113s) [122.632673] [123/170] (880s left) kms_pipe_crc_basic (read-crc-frame-sequence) [122.705952] Starting subtest: read-crc-frame-sequence [122.708201] Starting dynamic subtest: pipe-A-HDMI-A-1 [123.226364] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.519s) [123.233738] Starting dynamic subtest: pipe-B-HDMI-A-1 [123.908395] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.678s) [123.913320] Starting dynamic subtest: pipe-C-HDMI-A-1 [124.789609] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.879s) [124.790359] Subtest read-crc-frame-sequence: SUCCESS (2.084s) [124.884127] [124/170] (877s left) kms_psr (primary_page_flip) [124.924538] Subtest primary_page_flip: SKIP (0.000s) [124.945674] [125/170] (877s left) kms_psr (cursor_plane_move) [124.984712] Subtest cursor_plane_move: SKIP (0.000s) [124.995749] [126/170] (877s left) kms_psr (sprite_plane_onoff) [125.034863] Subtest sprite_plane_onoff: SKIP (0.000s) [125.045512] [127/170] (877s left) kms_psr (primary_mmap_gtt) [125.093312] Subtest primary_mmap_gtt: SKIP (0.000s) [125.112368] [128/170] (877s left) kms_setmode (basic-clone-single-crtc) [125.159953] Starting subtest: basic-clone-single-crtc [125.162130] Subtest basic-clone-single-crtc: SKIP (0.000s) [125.233968] [129/170] (877s left) i915_pm_backlight (basic-brightness) [125.322667] Subtest basic-brightness: SKIP (0.000s) [125.351050] [130/170] (877s left) i915_pm_rpm (basic-pci-d3-state) [126.847991] Starting subtest: basic-pci-d3-state [127.208920] Subtest basic-pci-d3-state: SUCCESS (0.362s) [127.501548] [131/170] (875s left) i915_pm_rpm (basic-rte) [127.545151] Starting subtest: basic-rte [129.327745] Subtest basic-rte: SUCCESS (1.783s) [130.006454] [132/170] (872s left) i915_pm_rps (basic-api) [130.046903] Starting subtest: basic-api [130.048590] Subtest basic-api: SUCCESS (0.001s) [130.125121] [133/170] (872s left) prime_self_import (basic-llseek-bad) [130.174073] Starting subtest: basic-llseek-bad [130.174890] Subtest basic-llseek-bad: SUCCESS (0.001s) [130.257599] [134/170] (872s left) prime_self_import (basic-llseek-size) [130.301512] Starting subtest: basic-llseek-size [130.303219] Subtest basic-llseek-size: SUCCESS (0.001s) [130.393408] [135/170] (872s left) prime_self_import (basic-with_fd_dup) [130.437824] Starting subtest: basic-with_fd_dup [130.439866] Subtest basic-with_fd_dup: SUCCESS (0.002s) [130.519397] [136/170] (872s left) prime_self_import (basic-with_one_bo) [130.558149] Starting subtest: basic-with_one_bo [130.559886] Subtest basic-with_one_bo: SUCCESS (0.001s) [130.613568] [137/170] (872s left) prime_self_import (basic-with_one_bo_two_files) [130.656159] Starting subtest: basic-with_one_bo_two_files [130.656912] Subtest basic-with_one_bo_two_files: SUCCESS (0.001s) [130.755403] [138/170] (872s left) prime_self_import (basic-with_two_bos) [130.814084] Starting subtest: basic-with_two_bos [130.816068] Subtest basic-with_two_bos: SUCCESS (0.002s) [130.903468] [139/170] (872s left) prime_vgem (basic-fence-flip) [130.948359] Starting subtest: basic-fence-flip [131.347815] Subtest basic-fence-flip: SUCCESS (0.398s) [131.457831] [140/170] (871s left) prime_vgem (basic-fence-mmap) [131.515319] Starting subtest: basic-fence-mmap [131.529841] Subtest basic-fence-mmap: SUCCESS (0.015s) [131.628266] [141/170] (871s left) prime_vgem (basic-fence-read) [131.699372] Starting subtest: basic-fence-read [131.703149] Subtest basic-fence-read: SKIP (0.003s) [131.811058] [142/170] (871s left) prime_vgem (basic-gtt) [131.888546] Starting subtest: basic-gtt [131.896182] Subtest basic-gtt: SUCCESS (0.008s) [131.994561] [143/170] (871s left) prime_vgem (basic-read) [132.059023] Starting subtest: basic-read [132.063342] Subtest basic-read: SKIP (0.004s) [132.145080] [144/170] (871s left) prime_vgem (basic-write) [132.198351] Starting subtest: basic-write [132.201879] Subtest basic-write: SKIP (0.003s) [132.289268] [145/170] (870s left) vgem_basic (setversion) [132.346030] Starting subtest: setversion [132.347414] Subtest setversion: SUCCESS (0.000s) [132.402712] [146/170] (870s left) vgem_basic (create) [132.462942] Starting subtest: create [132.463698] Subtest create: SUCCESS (0.000s) [132.520586] [147/170] (870s left) vgem_basic (debugfs) [132.579357] Starting subtest: debugfs [132.580044] Subtest debugfs: SUCCESS (0.000s) [132.636880] [148/170] (870s left) vgem_basic (dmabuf-export) [132.696193] Starting subtest: dmabuf-export [132.697937] Subtest dmabuf-export: SUCCESS (0.001s) [132.795128] [149/170] (870s left) vgem_basic (dmabuf-fence) [132.846163] Starting subtest: dmabuf-fence [132.846905] Subtest dmabuf-fence: SUCCESS (0.000s) [132.906112] [150/170] (870s left) vgem_basic (dmabuf-fence-before) [132.962828] Starting subtest: dmabuf-fence-before [132.963552] Subtest dmabuf-fence-before: SUCCESS (0.000s) [133.017647] [151/170] (870s left) vgem_basic (dmabuf-mmap) [133.062846] Starting subtest: dmabuf-mmap [133.069979] Subtest dmabuf-mmap: SUCCESS (0.007s) [133.111854] [152/170] (870s left) vgem_basic (mmap) [133.179766] Starting subtest: mmap [133.184764] Subtest mmap: SUCCESS (0.005s) [133.236804] [153/170] (870s left) vgem_basic (second-client) [133.296220] Starting subtest: second-client [133.348109] Subtest second-client: SUCCESS (0.051s) [133.408770] [154/170] (870s left) vgem_basic (sysfs) [133.463164] Starting subtest: sysfs [133.463982] Subtest sysfs: SUCCESS (0.000s) [133.528041] [155/170] (869s left) core_hotunplug (unbind-rebind) [133.596169] Starting subtest: unbind-rebind [134.520113] Subtest unbind-rebind: SUCCESS (0.924s) [134.531419] [156/170] (868s left) vgem_basic (unload) [134.573467] Starting subtest: unload [163.472317] Subtest unload: SUCCESS (28.898s) [163.584118] [157/170] (839s left) i915_module_load (reload) [163.636385] Starting subtest: reload [165.186692] Subtest reload: SUCCESS (1.551s) [165.196249] [158/170] (838s left) gem_lmem_swapping (basic) [165.255767] Subtest basic: SKIP (0.000s) [165.275293] [159/170] (838s left) gem_lmem_swapping (parallel-random-engines) [165.315720] Subtest parallel-random-engines: SKIP (0.000s) [165.341866] [160/170] (838s left) gem_lmem_swapping (random-engines) [165.382805] Subtest random-engines: SKIP (0.000s) [165.415805] [161/170] (838s left) gem_lmem_swapping (verify-random) [165.472627] Subtest verify-random: SKIP (0.000s) [165.492587] [162/170] (838s left) i915_pm_rpm (module-reload) [167.286760] Starting subtest: module-reload [174.539158] Subtest module-reload: SUCCESS (7.251s) [174.563806] [163/170] (828s left) i915_selftest (live) [174.614040] Starting subtest: live [174.614820] Starting dynamic subtest: sanitycheck [175.060437] Dynamic subtest sanitycheck: SUCCESS (0.445s) [175.069616] Starting dynamic subtest: uncore [175.519039] Dynamic subtest uncore: SUCCESS (0.457s) [175.526517] Starting dynamic subtest: workarounds [176.139449] Dynamic subtest workarounds: SUCCESS (0.619s) [176.148922] Starting dynamic subtest: gt_engines [177.073376] Dynamic subtest gt_engines: SUCCESS (0.933s) [177.082173] Starting dynamic subtest: gt_timelines [182.247584] Dynamic subtest gt_timelines: SUCCESS (5.173s) [182.256693] Starting dynamic subtest: gt_contexts [182.761074] Dynamic subtest gt_contexts: SUCCESS (0.512s) [182.770053] Starting dynamic subtest: gt_lrc [185.508984] Dynamic subtest gt_lrc: SUCCESS (2.746s) [185.519485] Starting dynamic subtest: gt_mocs [186.031508] Dynamic subtest gt_mocs: SUCCESS (0.521s) [186.040221] Starting dynamic subtest: gt_pm [197.602130] Dynamic subtest gt_pm: SUCCESS (11.569s) [197.609197] Starting dynamic subtest: gt_heartbeat [198.164782] Dynamic subtest gt_heartbeat: SUCCESS (0.561s) [198.174834] Starting dynamic subtest: gt_tlb [199.810466] Dynamic subtest gt_tlb: SUCCESS (1.644s) [199.819030] Starting dynamic subtest: requests [206.493545] Dynamic subtest requests: SUCCESS (6.682s) [206.502326] Starting dynamic subtest: migrate [209.699852] Dynamic subtest migrate: SUCCESS (3.200s) [209.706567] Starting dynamic subtest: active [210.110059] Dynamic subtest active: SUCCESS (0.415s) [210.117161] Starting dynamic subtest: objects [211.059482] Dynamic subtest objects: SUCCESS (0.948s) [211.066562] Starting dynamic subtest: mman [214.846552] Dynamic subtest mman: SUCCESS (3.787s) [214.853901] Starting dynamic subtest: dmabuf [215.350283] Dynamic subtest dmabuf: SUCCESS (0.497s) [215.356627] Starting dynamic subtest: vma [215.788972] Dynamic subtest vma: SUCCESS (0.436s) [215.798171] Starting dynamic subtest: coherency [216.848828] Dynamic subtest coherency: SUCCESS (1.062s) [216.855476] Starting dynamic subtest: gtt [224.424893] Dynamic subtest gtt: SUCCESS (7.578s) [224.435130] Starting dynamic subtest: gem [224.990986] Dynamic subtest gem: SUCCESS (0.565s) [224.998981] Starting dynamic subtest: evict [225.954344] Dynamic subtest evict: SUCCESS (0.962s) [225.961549] Starting dynamic subtest: hugepages [232.053294] Dynamic subtest hugepages: SUCCESS (6.098s) [232.062348] Starting dynamic subtest: gem_contexts [243.393454] Dynamic subtest gem_contexts: SUCCESS (11.339s) [243.400831] Starting dynamic subtest: client [243.870907] Dynamic subtest client: SUCCESS (0.476s) [243.881656] Starting dynamic subtest: gem_migrate [244.320984] Dynamic subtest gem_migrate: SUCCESS (0.449s) [244.329597] Starting dynamic subtest: reset [247.991073] Dynamic subtest reset: SUCCESS (3.669s) [248.000696] Starting dynamic subtest: memory_region [248.417353] Dynamic subtest memory_region: SUCCESS (0.425s) [248.424698] Starting dynamic subtest: hangcheck [271.932353] Dynamic subtest hangcheck: SUCCESS (23.514s) [271.939812] Starting dynamic subtest: execlists [279.667395] Dynamic subtest execlists: SUCCESS (7.734s) [279.676838] Starting dynamic subtest: ring_submission [280.083902] Dynamic subtest ring_submission: SUCCESS (0.416s) [280.092286] Starting dynamic subtest: perf [280.577357] Dynamic subtest perf: SUCCESS (0.492s) [280.586156] Starting dynamic subtest: slpc [280.995572] Dynamic subtest slpc: SUCCESS (0.417s) [281.004853] Starting dynamic subtest: guc [281.423703] Dynamic subtest guc: SUCCESS (0.427s) [281.432901] Starting dynamic subtest: guc_multi_lrc [281.840708] Dynamic subtest guc_multi_lrc: SUCCESS (0.416s) [281.849936] Starting dynamic subtest: guc_hang [282.267065] Dynamic subtest guc_hang: SUCCESS (0.426s) [282.275359] Starting dynamic subtest: late_gt_pm [282.741504] Dynamic subtest late_gt_pm: SUCCESS (0.473s) [282.742993] Subtest live: SUCCESS (108.126s) [282.930860] [164/170] (720s left) dmabuf (all-tests) [282.978977] Starting subtest: all-tests [282.979881] Starting dynamic subtest: sanitycheck [283.041394] Dynamic subtest sanitycheck: SUCCESS (0.061s) [283.049603] Starting dynamic subtest: dma_fence [283.216149] Dynamic subtest dma_fence: SUCCESS (0.174s) [283.231828] Starting dynamic subtest: dma_fence_chain [293.113793] Dynamic subtest dma_fence_chain: SUCCESS (9.887s) [293.119705] Starting dynamic subtest: dma_fence_unwrap [293.182180] Dynamic subtest dma_fence_unwrap: SUCCESS (0.077s) [293.198338] Starting dynamic subtest: dma_resv [293.267078] Dynamic subtest dma_resv: SUCCESS (0.083s) [293.269150] Subtest all-tests: SUCCESS (10.287s) [293.291619] [165/170] (710s left) i915_suspend (basic-s2idle-without-i915) [293.338566] Starting subtest: basic-s2idle-without-i915 [309.566946] Subtest basic-s2idle-without-i915: SUCCESS (3.408s) [314.682346] [166/170] (706s left) i915_suspend (basic-s3-without-i915) [314.727712] Starting subtest: basic-s3-without-i915 [349.701375] Subtest basic-s3-without-i915: SUCCESS (17.415s) [354.818480] [167/170] (689s left) gem_exec_suspend (basic-s0) [354.888191] Starting subtest: basic-S0 [354.888938] Starting dynamic subtest: smem [372.814045] Dynamic subtest smem: SUCCESS (4.421s) [372.814704] Subtest basic-S0: SUCCESS (4.421s) [377.968526] [168/170] (684s left) gem_exec_suspend (basic-s3) [378.017725] Starting subtest: basic-S3 [378.018499] Starting dynamic subtest: smem [400.822876] Dynamic subtest smem: SUCCESS (17.376s) [400.823549] Subtest basic-S3: SUCCESS (17.377s) [405.220841] [169/170] (667s left) kms_chamelium_hpd (common-hpd-after-suspend) [405.368091] Subtest common-hpd-after-suspend: SKIP (0.000s) [405.429814] [170/170] (666s left) kms_pipe_crc_basic (suspend-read-crc) [405.496228] Starting subtest: suspend-read-crc [405.496913] Starting dynamic subtest: pipe-A-HDMI-A-1 [432.995332] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.355s) [433.001980] Starting dynamic subtest: pipe-B-HDMI-A-1 [468.181796] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (6.587s) [468.188594] Starting dynamic subtest: pipe-C-HDMI-A-1 [490.372304] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.591s) [490.373043] Subtest suspend-read-crc: SUCCESS (19.541s) [497.442873] Closing watchdogs Done.