[41.552953] Initializing watchdogs [41.553295] /dev/watchdog0 [41.557961] [001/171] (960s left) i915_module_load (load) [41.766647] Starting subtest: load [42.702458] Subtest load: SUCCESS (0.930s) [42.725322] [002/171] (959s left) core_auth (basic-auth) [42.844802] Starting subtest: basic-auth [42.845924] Subtest basic-auth: SUCCESS (0.001s) [42.907510] [003/171] (959s left) debugfs_test (read_all_entries) [42.972477] Starting subtest: read_all_entries [43.012864] Subtest read_all_entries: SUCCESS (0.040s) [43.091236] [004/171] (959s left) debugfs_test (basic-hwmon) [43.154376] Starting subtest: basic-hwmon [43.156517] Subtest basic-hwmon: SKIP (0.000s) [43.224370] [005/171] (958s left) fbdev (eof) [43.296154] Starting subtest: eof [43.297282] Subtest eof: SUCCESS (0.000s) [43.341338] [006/171] (958s left) fbdev (info) [43.401656] Starting subtest: info [43.402949] Subtest info: SUCCESS (0.000s) [43.461415] [007/171] (958s left) fbdev (nullptr) [43.529322] Starting subtest: nullptr [43.530455] Subtest nullptr: SUCCESS (0.000s) [43.575988] [008/171] (958s left) fbdev (read) [43.645832] Starting subtest: read [43.726779] Subtest read: SUCCESS (0.081s) [43.758115] [009/171] (958s left) fbdev (write) [43.829162] Starting subtest: write [43.875819] Subtest write: SUCCESS (0.047s) [43.909371] [010/171] (958s left) gem_basic (bad-close) [43.972835] Starting subtest: bad-close [43.974927] Subtest bad-close: SUCCESS (0.000s) [44.025501] [011/171] (958s left) gem_basic (create-close) [44.088262] Starting subtest: create-close [44.090263] Subtest create-close: SUCCESS (0.000s) [44.158437] [012/171] (958s left) gem_basic (create-fd-close) [44.221156] Starting subtest: create-fd-close [44.223182] Subtest create-fd-close: SUCCESS (0.000s) [44.290402] [013/171] (957s left) gem_busy (busy@all-engines) [44.361532] Starting subtest: busy [44.362606] Starting dynamic subtest: all-engines [44.385895] Dynamic subtest all-engines: SUCCESS (0.024s) [44.387170] Subtest busy: SUCCESS (0.024s) [44.692349] [014/171] (957s left) gem_close_race (basic-process) [44.766417] Starting subtest: basic-process [44.798608] Subtest basic-process: SUCCESS (0.032s) [44.957919] [015/171] (957s left) gem_close_race (basic-threads) [45.030966] Starting subtest: basic-threads [46.183907] Subtest basic-threads: SUCCESS (1.153s) [46.343021] [016/171] (955s left) gem_ctx_create (basic) [46.417944] Starting subtest: basic [46.419069] Subtest basic: SUCCESS (0.000s) [46.578229] [017/171] (955s left) gem_ctx_create (basic-files) [46.647149] Starting subtest: basic-files [48.660376] Subtest basic-files: SUCCESS (2.013s) [48.873362] [018/171] (953s left) gem_ctx_exec (basic) [48.938877] Starting subtest: basic [48.940649] Subtest basic: SUCCESS (0.003s) [49.020947] [019/171] (953s left) gem_exec_basic (basic) [49.088766] Starting subtest: basic [49.089929] Starting dynamic subtest: rcs0-smem [49.090980] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [49.096274] Starting dynamic subtest: bcs0-smem [49.097381] Dynamic subtest bcs0-smem: SUCCESS (0.000s) [49.103418] Starting dynamic subtest: vcs0-smem [49.104555] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [49.110846] Starting dynamic subtest: vecs0-smem [49.111990] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [49.113338] Subtest basic: SUCCESS (0.002s) [49.260039] [020/171] (953s left) gem_exec_create (basic) [49.328902] Starting subtest: basic [49.329983] Starting dynamic subtest: smem [51.365418] Dynamic subtest smem: SUCCESS (2.036s) [51.366598] Subtest basic: SUCCESS (2.037s) [51.523521] [021/171] (950s left) gem_exec_fence (basic-busy) [51.708546] Starting subtest: basic-busy [51.709679] Starting dynamic subtest: rcs0 [51.737424] Dynamic subtest rcs0: SUCCESS (0.029s) [51.744387] Starting dynamic subtest: bcs0 [51.763196] Dynamic subtest bcs0: SUCCESS (0.026s) [51.769502] Starting dynamic subtest: vcs0 [51.788486] Dynamic subtest vcs0: SUCCESS (0.025s) [51.795376] Starting dynamic subtest: vecs0 [51.809252] Dynamic subtest vecs0: SUCCESS (0.021s) [51.810347] Subtest basic-busy: SUCCESS (0.101s) [52.258431] [022/171] (950s left) gem_exec_fence (basic-wait) [52.445977] Starting subtest: basic-wait [52.447177] Starting dynamic subtest: rcs0 [52.472339] Dynamic subtest rcs0: SUCCESS (0.027s) [52.478676] Starting dynamic subtest: bcs0 [52.506870] Dynamic subtest bcs0: SUCCESS (0.034s) [52.513335] Starting dynamic subtest: vcs0 [52.534819] Dynamic subtest vcs0: SUCCESS (0.028s) [52.540811] Starting dynamic subtest: vecs0 [52.569952] Dynamic subtest vecs0: SUCCESS (0.035s) [52.571424] Subtest basic-wait: SUCCESS (0.124s) [52.991473] [023/171] (949s left) gem_exec_fence (basic-await) [53.174581] Starting subtest: basic-await [53.175798] Starting dynamic subtest: rcs0 [53.245502] Dynamic subtest rcs0: SUCCESS (0.070s) [53.250978] Starting dynamic subtest: bcs0 [53.316499] Dynamic subtest bcs0: SUCCESS (0.071s) [53.322049] Starting dynamic subtest: vcs0 [53.386448] Dynamic subtest vcs0: SUCCESS (0.070s) [53.392074] Starting dynamic subtest: vecs0 [53.455396] Dynamic subtest vecs0: SUCCESS (0.069s) [53.456529] Subtest basic-await: SUCCESS (0.281s) [53.894230] [024/171] (948s left) gem_exec_fence (nb-await) [54.079534] Starting subtest: nb-await [54.080697] Starting dynamic subtest: rcs0 [54.134310] Dynamic subtest rcs0: SUCCESS (0.054s) [54.140388] Starting dynamic subtest: bcs0 [54.189677] Dynamic subtest bcs0: SUCCESS (0.055s) [54.195306] Starting dynamic subtest: vcs0 [54.242992] Dynamic subtest vcs0: SUCCESS (0.053s) [54.249534] Starting dynamic subtest: vecs0 [54.297813] Dynamic subtest vecs0: SUCCESS (0.054s) [54.299066] Subtest nb-await: SUCCESS (0.218s) [54.724985] [025/171] (947s left) gem_exec_gttfill (basic) [54.792916] Starting subtest: basic [57.954969] Subtest basic: SUCCESS (3.162s) [58.121314] [026/171] (944s left) gem_exec_parallel (engines) [58.250149] Starting subtest: engines [58.251243] Starting dynamic subtest: basic [59.653477] Dynamic subtest basic: SUCCESS (1.404s) [59.660700] Starting dynamic subtest: contexts [61.234227] Dynamic subtest contexts: SUCCESS (1.581s) [61.240348] Starting dynamic subtest: fds [62.966417] Dynamic subtest fds: SUCCESS (1.732s) [62.972828] Starting dynamic subtest: userptr [64.395692] Dynamic subtest userptr: SUCCESS (1.429s) [64.396889] Subtest engines: SUCCESS (6.147s) [64.556055] [027/171] (937s left) gem_exec_store (basic) [64.627764] Starting subtest: basic [64.674660] Subtest basic: SUCCESS (0.046s) [64.837411] [028/171] (937s left) gem_flink_basic (bad-flink) [64.898929] Starting subtest: bad-flink [64.900022] Subtest bad-flink: SUCCESS (0.000s) [64.955363] [029/171] (937s left) gem_flink_basic (bad-open) [65.016441] Starting subtest: bad-open [65.017449] Subtest bad-open: SUCCESS (0.000s) [65.074404] [030/171] (937s left) gem_flink_basic (basic) [65.137255] Starting subtest: basic [65.138321] Subtest basic: SUCCESS (0.000s) [65.203699] [031/171] (937s left) gem_flink_basic (double-flink) [65.265652] Starting subtest: double-flink [65.266689] Subtest double-flink: SUCCESS (0.000s) [65.337804] [032/171] (937s left) gem_flink_basic (flink-lifetime) [65.399336] Starting subtest: flink-lifetime [65.400341] Subtest flink-lifetime: SUCCESS (0.001s) [65.472545] [033/171] (937s left) gem_huc_copy (huc-copy) [65.537826] Subtest huc-copy: SKIP (0.000s) [65.564681] [034/171] (936s left) gem_linear_blits (basic) [65.703271] Starting subtest: basic [65.709249] Subtest basic: SUCCESS (0.006s) [65.812631] [035/171] (936s left) gem_mmap (basic) [65.945570] Starting subtest: basic [65.951116] Subtest basic: SUCCESS (0.000s) [66.021930] [036/171] (936s left) gem_mmap_gtt (basic) [66.084249] Starting subtest: basic [66.085405] Subtest basic: SUCCESS (0.000s) [66.134547] [037/171] (936s left) gem_render_linear_blits (basic) [66.198777] Starting subtest: basic [66.214545] Subtest basic: SUCCESS (0.016s) [66.249750] [038/171] (936s left) gem_render_tiled_blits (basic) [66.313827] Starting subtest: basic [66.333500] Subtest basic: SUCCESS (0.020s) [66.367606] [039/171] (936s left) gem_ringfill (basic-all) [66.549055] Starting subtest: basic-all [67.655188] Subtest basic-all: SUCCESS (1.107s) [67.739682] [040/171] (934s left) gem_softpin (allocator-basic) [67.805253] Subtest allocator-basic: SKIP (0.000s) [67.852730] [041/171] (934s left) gem_softpin (allocator-basic-reserve) [67.917220] Subtest allocator-basic-reserve: SKIP (0.000s) [67.970673] [042/171] (934s left) gem_softpin (safe-alignment) [68.034244] Starting subtest: safe-alignment [68.041882] Subtest safe-alignment: SUCCESS (0.009s) [68.121218] [043/171] (934s left) gem_sync (basic-all) [68.194091] Starting subtest: basic-all [70.217336] Subtest basic-all: SUCCESS (2.022s) [70.388252] [044/171] (932s left) gem_sync (basic-each) [70.457329] Starting subtest: basic-each [72.532656] Subtest basic-each: SUCCESS (2.075s) [72.704447] [045/171] (929s left) gem_tiled_blits (basic) [72.837963] Starting subtest: basic [72.848687] Subtest basic: SUCCESS (0.011s) [72.943397] [046/171] (929s left) gem_tiled_fence_blits (basic) [73.141439] Starting subtest: basic [73.147127] Subtest basic: SUCCESS (0.006s) [73.258255] [047/171] (929s left) gem_tiled_pread_basic [73.555963] [048/171] (929s left) gem_wait (busy@all-engines) [73.629754] Starting subtest: busy [73.630915] Starting dynamic subtest: all-engines [74.158210] Dynamic subtest all-engines: SUCCESS (0.528s) [74.159356] Subtest busy: SUCCESS (0.528s) [74.336312] [049/171] (928s left) gem_wait (wait@all-engines) [74.409121] Starting subtest: wait [74.410276] Starting dynamic subtest: all-engines [75.427200] Dynamic subtest all-engines: SUCCESS (1.018s) [75.428334] Subtest wait: SUCCESS (1.018s) [75.602457] [050/171] (927s left) i915_getparams_basic (basic-eu-total) [75.665461] Starting subtest: basic-eu-total [75.667586] Subtest basic-eu-total: SUCCESS (0.000s) [75.717900] [051/171] (927s left) i915_getparams_basic (basic-subslice-total) [75.779501] Starting subtest: basic-subslice-total [75.781584] Subtest basic-subslice-total: SUCCESS (0.000s) [75.836136] [052/171] (926s left) i915_hangman (error-state-basic) [75.901040] Starting subtest: error-state-basic [75.905755] Subtest error-state-basic: SUCCESS (0.004s) [75.967514] [053/171] (926s left) i915_pciid [76.085551] [054/171] (926s left) kms_addfb_basic (addfb25-bad-modifier) [76.149806] Starting subtest: addfb25-bad-modifier [76.151118] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [76.233616] [055/171] (926s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [76.295339] Starting subtest: addfb25-framebuffer-vs-set-tiling [76.296369] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [76.383804] [056/171] (926s left) kms_addfb_basic (addfb25-modifier-no-flag) [76.446365] Starting subtest: addfb25-modifier-no-flag [76.447581] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [76.550726] [057/171] (926s left) kms_addfb_basic (addfb25-x-tiled-legacy) [76.613788] Starting subtest: addfb25-x-tiled-legacy [76.614823] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [76.701123] [058/171] (926s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [76.765341] Starting subtest: addfb25-x-tiled-mismatch-legacy [76.766438] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [76.850284] [059/171] (926s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [76.934432] Starting subtest: addfb25-yf-tiled-legacy [76.935470] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [77.002538] [060/171] (925s left) kms_addfb_basic (addfb25-y-tiled-legacy) [77.087992] Starting subtest: addfb25-y-tiled-legacy [77.089191] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [77.154286] [061/171] (925s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [77.247050] Starting subtest: addfb25-y-tiled-small-legacy [77.249129] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s) [77.317375] [062/171] (925s left) kms_addfb_basic (bad-pitch-0) [77.378078] Starting subtest: bad-pitch-0 [77.379195] Subtest bad-pitch-0: SUCCESS (0.000s) [77.467171] [063/171] (925s left) kms_addfb_basic (bad-pitch-1024) [77.528354] Starting subtest: bad-pitch-1024 [77.529476] Subtest bad-pitch-1024: SUCCESS (0.000s) [77.617940] [064/171] (925s left) kms_addfb_basic (bad-pitch-128) [77.678019] Starting subtest: bad-pitch-128 [77.679068] Subtest bad-pitch-128: SUCCESS (0.000s) [77.766875] [065/171] (925s left) kms_addfb_basic (bad-pitch-256) [77.829899] Starting subtest: bad-pitch-256 [77.830927] Subtest bad-pitch-256: SUCCESS (0.000s) [77.917097] [066/171] (925s left) kms_addfb_basic (bad-pitch-32) [77.979297] Starting subtest: bad-pitch-32 [77.980433] Subtest bad-pitch-32: SUCCESS (0.000s) [78.066794] [067/171] (924s left) kms_addfb_basic (bad-pitch-63) [78.128605] Starting subtest: bad-pitch-63 [78.129752] Subtest bad-pitch-63: SUCCESS (0.000s) [78.216629] [068/171] (924s left) kms_addfb_basic (bad-pitch-65536) [78.277230] Starting subtest: bad-pitch-65536 [78.278335] Subtest bad-pitch-65536: SUCCESS (0.000s) [78.367348] [069/171] (924s left) kms_addfb_basic (bad-pitch-999) [78.428495] Starting subtest: bad-pitch-999 [78.429690] Subtest bad-pitch-999: SUCCESS (0.000s) [78.516855] [070/171] (924s left) kms_addfb_basic (basic) [78.579886] Starting subtest: basic [78.580898] Subtest basic: SUCCESS (0.000s) [78.667088] [071/171] (924s left) kms_addfb_basic (basic-x-tiled-legacy) [78.730749] Starting subtest: basic-x-tiled-legacy [78.731788] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [78.817187] [072/171] (924s left) kms_addfb_basic (basic-y-tiled-legacy) [78.879509] Starting subtest: basic-y-tiled-legacy [78.880539] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [78.966605] [073/171] (924s left) kms_addfb_basic (bo-too-small) [79.048409] Starting subtest: bo-too-small [79.049442] Subtest bo-too-small: SUCCESS (0.000s) [79.116362] [074/171] (923s left) kms_addfb_basic (bo-too-small-due-to-tiling) [79.198606] Starting subtest: bo-too-small-due-to-tiling [79.199760] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [79.267190] [075/171] (923s left) kms_addfb_basic (clobberred-modifier) [79.344582] Starting subtest: clobberred-modifier [79.345635] Subtest clobberred-modifier: SUCCESS (0.000s) [79.433514] [076/171] (923s left) kms_addfb_basic (framebuffer-vs-set-tiling) [79.494703] Starting subtest: framebuffer-vs-set-tiling [79.495837] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [79.583352] [077/171] (923s left) kms_addfb_basic (invalid-get-prop) [79.647298] Starting subtest: invalid-get-prop [79.648270] Subtest invalid-get-prop: SUCCESS (0.000s) [79.734406] [078/171] (923s left) kms_addfb_basic (invalid-get-prop-any) [79.798843] Starting subtest: invalid-get-prop-any [79.799925] Subtest invalid-get-prop-any: SUCCESS (0.000s) [79.883290] [079/171] (923s left) kms_addfb_basic (invalid-set-prop) [79.945392] Starting subtest: invalid-set-prop [79.946449] Subtest invalid-set-prop: SUCCESS (0.000s) [80.033381] [080/171] (923s left) kms_addfb_basic (invalid-set-prop-any) [80.094614] Starting subtest: invalid-set-prop-any [80.095746] Subtest invalid-set-prop-any: SUCCESS (0.000s) [80.183332] [081/171] (922s left) kms_addfb_basic (no-handle) [80.244144] Starting subtest: no-handle [80.245290] Subtest no-handle: SUCCESS (0.000s) [80.333244] [082/171] (922s left) kms_addfb_basic (size-max) [80.416959] Starting subtest: size-max [80.418098] Subtest size-max: SUCCESS (0.000s) [80.483113] [083/171] (922s left) kms_addfb_basic (small-bo) [80.567526] Starting subtest: small-bo [80.568713] Subtest small-bo: SUCCESS (0.000s) [80.633305] [084/171] (922s left) kms_addfb_basic (tile-pitch-mismatch) [80.696883] Starting subtest: tile-pitch-mismatch [80.697948] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [80.783145] [085/171] (922s left) kms_addfb_basic (too-high) [80.866286] Starting subtest: too-high [80.867352] Subtest too-high: SUCCESS (0.001s) [80.932757] [086/171] (922s left) kms_addfb_basic (too-wide) [81.015618] Starting subtest: too-wide [81.016761] Subtest too-wide: SUCCESS (0.000s) [81.083030] [087/171] (922s left) kms_addfb_basic (unused-handle) [81.144784] Starting subtest: unused-handle [81.145887] Subtest unused-handle: SUCCESS (0.000s) [81.232877] [088/171] (922s left) kms_addfb_basic (unused-modifier) [81.295501] Starting subtest: unused-modifier [81.296664] Subtest unused-modifier: SUCCESS (0.000s) [81.385825] [089/171] (921s left) kms_addfb_basic (unused-offsets) [81.446761] Starting subtest: unused-offsets [81.447919] Subtest unused-offsets: SUCCESS (0.000s) [81.550911] [090/171] (921s left) kms_addfb_basic (unused-pitches) [81.616298] Starting subtest: unused-pitches [81.617329] Subtest unused-pitches: SUCCESS (0.000s) [81.716495] [091/171] (921s left) kms_busy (basic) [81.804649] Starting subtest: basic [81.805740] Starting dynamic subtest: flip [82.258532] Dynamic subtest flip: SUCCESS (0.453s) [82.265187] Starting dynamic subtest: modeset [82.764620] Dynamic subtest modeset: SUCCESS (0.506s) [82.765760] Subtest basic: SUCCESS (0.960s) [82.896854] [092/171] (920s left) kms_chamelium_hpd (dp-hpd-fast) [83.021860] Subtest dp-hpd-fast: SKIP (0.000s) [83.104469] [093/171] (920s left) kms_chamelium_edid (dp-edid-read) [83.234741] Subtest dp-edid-read: SKIP (0.000s) [83.316789] [094/171] (920s left) kms_chamelium_frames (dp-crc-fast) [83.445939] Subtest dp-crc-fast: SKIP (0.000s) [83.528649] [095/171] (919s left) kms_chamelium_hpd (hdmi-hpd-fast) [83.658068] Subtest hdmi-hpd-fast: SKIP (0.000s) [83.740711] [096/171] (919s left) kms_chamelium_edid (hdmi-edid-read) [83.870278] Subtest hdmi-edid-read: SKIP (0.000s) [83.952464] [097/171] (919s left) kms_chamelium_frames (hdmi-crc-fast) [84.083719] Subtest hdmi-crc-fast: SKIP (0.000s) [84.165722] [098/171] (919s left) kms_chamelium_hpd (vga-hpd-fast) [84.295984] Subtest vga-hpd-fast: SKIP (0.000s) [84.378948] [099/171] (919s left) kms_chamelium_edid (vga-edid-read) [84.506836] Subtest vga-edid-read: SKIP (0.000s) [84.588296] [100/171] (918s left) kms_prop_blob (basic) [84.656651] Starting subtest: basic [84.657770] Subtest basic: SUCCESS (0.000s) [84.709020] [101/171] (918s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [84.793062] Starting subtest: basic-busy-flip-before-cursor-atomic [85.717672] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.925s) [85.843923] [102/171] (917s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [85.927171] Starting subtest: basic-busy-flip-before-cursor-legacy [86.850637] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.924s) [86.982784] [103/171] (916s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [87.070110] Starting subtest: basic-flip-after-cursor-atomic [88.020870] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.951s) [88.154288] [104/171] (915s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [88.238560] Starting subtest: basic-flip-after-cursor-legacy [89.161085] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.923s) [89.297026] [105/171] (914s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [89.380207] Starting subtest: basic-flip-after-cursor-varying-size [90.322135] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.942s) [90.448957] [106/171] (913s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [90.533167] Starting subtest: basic-flip-before-cursor-atomic [91.455774] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.922s) [91.585706] [107/171] (911s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [91.669639] Starting subtest: basic-flip-before-cursor-legacy [92.592606] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.924s) [92.721747] [108/171] (910s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [92.804800] Starting subtest: basic-flip-before-cursor-varying-size [93.727747] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.923s) [93.861179] [109/171] (909s left) kms_flip (basic-flip-vs-dpms) [93.934378] Starting subtest: basic-flip-vs-dpms [93.936541] Starting dynamic subtest: A-VGA1 [94.862044] Dynamic subtest A-VGA1: SUCCESS (0.926s) [94.870056] Starting dynamic subtest: B-VGA1 [95.742780] Dynamic subtest B-VGA1: SUCCESS (0.879s) [95.749736] Starting dynamic subtest: C-VGA1 [96.621210] Dynamic subtest C-VGA1: SUCCESS (0.877s) [96.622268] Subtest basic-flip-vs-dpms: SUCCESS (2.687s) [96.751717] [110/171] (906s left) kms_flip (basic-flip-vs-modeset) [96.828725] Starting subtest: basic-flip-vs-modeset [96.829856] Starting dynamic subtest: A-VGA1 [97.680366] Dynamic subtest A-VGA1: SUCCESS (0.850s) [97.686884] Starting dynamic subtest: B-VGA1 [98.470712] Dynamic subtest B-VGA1: SUCCESS (0.791s) [98.477900] Starting dynamic subtest: C-VGA1 [99.258859] Dynamic subtest C-VGA1: SUCCESS (0.787s) [99.261092] Subtest basic-flip-vs-modeset: SUCCESS (2.433s) [99.393945] [111/171] (904s left) kms_flip (basic-flip-vs-wf_vblank) [99.472352] Starting subtest: basic-flip-vs-wf_vblank [99.473325] Starting dynamic subtest: A-VGA1 [100.616882] Dynamic subtest A-VGA1: SUCCESS (1.144s) [100.625391] Starting dynamic subtest: B-VGA1 [101.738733] Dynamic subtest B-VGA1: SUCCESS (1.120s) [101.746930] Starting dynamic subtest: C-VGA1 [102.860965] Dynamic subtest C-VGA1: SUCCESS (1.121s) [102.863497] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.393s) [103.030867] [112/171] (900s left) kms_flip (basic-plain-flip) [103.104971] Starting subtest: basic-plain-flip [103.108299] Starting dynamic subtest: A-VGA1 [103.986060] Dynamic subtest A-VGA1: SUCCESS (0.877s) [103.993671] Starting dynamic subtest: B-VGA1 [104.822964] Dynamic subtest B-VGA1: SUCCESS (0.836s) [104.829792] Starting dynamic subtest: C-VGA1 [105.661361] Dynamic subtest C-VGA1: SUCCESS (0.838s) [105.663576] Subtest basic-plain-flip: SUCCESS (2.558s) [105.815702] [113/171] (897s left) kms_force_connector_basic (force-connector-state) [105.890827] Starting subtest: force-connector-state [106.025494] Subtest force-connector-state: SUCCESS (0.136s) [106.206338] [114/171] (897s left) kms_force_connector_basic (force-edid) [106.279380] Starting subtest: force-edid [106.301794] Subtest force-edid: SUCCESS (0.022s) [106.406459] [115/171] (897s left) kms_force_connector_basic (force-load-detect) [106.478780] Starting subtest: force-load-detect [106.623165] Subtest force-load-detect: SUCCESS (0.143s) [106.805522] [116/171] (896s left) kms_force_connector_basic (prune-stale-modes) [106.879877] Starting subtest: prune-stale-modes [106.895859] Subtest prune-stale-modes: SUCCESS (0.017s) [106.986675] [117/171] (896s left) kms_frontbuffer_tracking (basic) [107.076444] Starting subtest: basic [108.888672] Subtest basic: SUCCESS (1.816s) [108.954560] [118/171] (894s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [109.038509] Starting subtest: compare-crc-sanitycheck-xr24 [109.039526] Starting dynamic subtest: pipe-A-VGA-1 [109.308743] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.269s) [109.316364] Starting dynamic subtest: pipe-B-VGA-1 [109.569803] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.259s) [109.577634] Starting dynamic subtest: pipe-C-VGA-1 [109.840876] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.270s) [109.841969] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.803s) [109.974635] [119/171] (893s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [110.058901] Starting subtest: compare-crc-sanitycheck-nv12 [110.059936] Starting dynamic subtest: pipe-A-VGA-1 [110.062108] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s) [110.068992] Starting dynamic subtest: pipe-B-VGA-1 [110.070964] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s) [110.077523] Starting dynamic subtest: pipe-C-VGA-1 [110.079761] Dynamic subtest pipe-C-VGA-1: SKIP (0.000s) [110.081862] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s) [110.126241] [120/171] (893s left) kms_pipe_crc_basic (hang-read-crc) [110.210228] Starting subtest: hang-read-crc [110.211326] Starting dynamic subtest: pipe-A-VGA-1 [110.940254] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.729s) [110.946698] Starting dynamic subtest: pipe-B-VGA-1 [111.666650] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.726s) [111.673257] Starting dynamic subtest: pipe-C-VGA-1 [112.412097] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.743s) [112.413266] Subtest hang-read-crc: SUCCESS (2.202s) [112.526580] [121/171] (891s left) kms_pipe_crc_basic (nonblocking-crc) [112.613402] Starting subtest: nonblocking-crc [112.614524] Starting dynamic subtest: pipe-A-VGA-1 [113.051393] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.438s) [113.059695] Starting dynamic subtest: pipe-B-VGA-1 [113.477221] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.424s) [113.484032] Starting dynamic subtest: pipe-C-VGA-1 [113.914355] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.435s) [113.915472] Subtest nonblocking-crc: SUCCESS (1.301s) [114.030154] [122/171] (889s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [114.113725] Starting subtest: nonblocking-crc-frame-sequence [114.114858] Starting dynamic subtest: pipe-A-VGA-1 [114.552923] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.438s) [114.559762] Starting dynamic subtest: pipe-B-VGA-1 [114.978989] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.425s) [114.986861] Starting dynamic subtest: pipe-C-VGA-1 [115.419393] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.438s) [115.420550] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.305s) [115.535257] [123/171] (888s left) kms_pipe_crc_basic (read-crc) [115.624295] Starting subtest: read-crc [115.625412] Starting dynamic subtest: pipe-A-VGA-1 [115.991865] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.367s) [115.999710] Starting dynamic subtest: pipe-B-VGA-1 [116.352271] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.358s) [116.359061] Starting dynamic subtest: pipe-C-VGA-1 [116.723293] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.370s) [116.724383] Subtest read-crc: SUCCESS (1.100s) [116.839984] [124/171] (886s left) kms_pipe_crc_basic (read-crc-frame-sequence) [116.924100] Starting subtest: read-crc-frame-sequence [116.925239] Starting dynamic subtest: pipe-A-VGA-1 [117.296909] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.372s) [117.303766] Starting dynamic subtest: pipe-B-VGA-1 [117.656147] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.358s) [117.662719] Starting dynamic subtest: pipe-C-VGA-1 [118.028093] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.371s) [118.029341] Subtest read-crc-frame-sequence: SUCCESS (1.105s) [118.144348] [125/171] (885s left) kms_psr (primary_page_flip) [118.208242] Subtest primary_page_flip: SKIP (0.000s) [118.240251] [126/171] (885s left) kms_psr (cursor_plane_move) [118.305911] Subtest cursor_plane_move: SKIP (0.000s) [118.338680] [127/171] (885s left) kms_psr (sprite_plane_onoff) [118.400932] Subtest sprite_plane_onoff: SKIP (0.000s) [118.424498] [128/171] (885s left) kms_psr (primary_mmap_gtt) [118.488565] Subtest primary_mmap_gtt: SKIP (0.000s) [118.507448] [129/171] (885s left) kms_setmode (basic-clone-single-crtc) [118.569831] Starting subtest: basic-clone-single-crtc [118.572981] Subtest basic-clone-single-crtc: SKIP (0.000s) [118.627721] [130/171] (885s left) i915_pm_backlight (basic-brightness) [118.714855] Subtest basic-brightness: SKIP (0.000s) [118.748742] [131/171] (885s left) i915_pm_rpm (basic-pci-d3-state) [120.121242] Starting subtest: basic-pci-d3-state [120.332969] Subtest basic-pci-d3-state: SUCCESS (0.209s) [120.580801] [132/171] (883s left) i915_pm_rpm (basic-rte) [120.642906] Starting subtest: basic-rte [122.143780] Subtest basic-rte: SUCCESS (1.498s) [122.588621] [133/171] (881s left) i915_pm_rps (basic-api) [122.654475] Starting subtest: basic-api [122.656805] Subtest basic-api: SUCCESS (0.002s) [122.722383] [134/171] (881s left) prime_self_import (basic-llseek-bad) [122.783136] Starting subtest: basic-llseek-bad [122.785243] Subtest basic-llseek-bad: SUCCESS (0.001s) [122.854610] [135/171] (881s left) prime_self_import (basic-llseek-size) [122.914031] Starting subtest: basic-llseek-size [122.916295] Subtest basic-llseek-size: SUCCESS (0.002s) [122.988721] [136/171] (881s left) prime_self_import (basic-with_fd_dup) [123.050461] Starting subtest: basic-with_fd_dup [123.052694] Subtest basic-with_fd_dup: SUCCESS (0.002s) [123.122304] [137/171] (880s left) prime_self_import (basic-with_one_bo) [123.182399] Starting subtest: basic-with_one_bo [123.186159] Subtest basic-with_one_bo: SUCCESS (0.003s) [123.234867] [138/171] (880s left) prime_self_import (basic-with_one_bo_two_files) [123.294721] Starting subtest: basic-with_one_bo_two_files [123.296975] Subtest basic-with_one_bo_two_files: SUCCESS (0.001s) [123.373175] [139/171] (880s left) prime_self_import (basic-with_two_bos) [123.434843] Starting subtest: basic-with_two_bos [123.438403] Subtest basic-with_two_bos: SUCCESS (0.002s) [123.506063] [140/171] (880s left) prime_vgem (basic-fence-flip) [123.577620] Starting subtest: basic-fence-flip [123.907167] Subtest basic-fence-flip: SUCCESS (0.329s) [123.989244] [141/171] (880s left) prime_vgem (basic-fence-mmap) [124.061239] Starting subtest: basic-fence-mmap [124.084731] Subtest basic-fence-mmap: SUCCESS (0.023s) [124.155750] [142/171] (879s left) prime_vgem (basic-fence-read) [124.228510] Starting subtest: basic-fence-read [124.260835] Subtest basic-fence-read: SUCCESS (0.032s) [124.339868] [143/171] (879s left) prime_vgem (basic-gtt) [124.410970] Starting subtest: basic-gtt [124.422220] Subtest basic-gtt: SUCCESS (0.011s) [124.505688] [144/171] (879s left) prime_vgem (basic-read) [124.577911] Starting subtest: basic-read [124.593595] Subtest basic-read: SUCCESS (0.016s) [124.670663] [145/171] (879s left) prime_vgem (basic-write) [124.744247] Starting subtest: basic-write [124.761588] Subtest basic-write: SUCCESS (0.017s) [124.838875] [146/171] (879s left) vgem_basic (setversion) [124.908116] Starting subtest: setversion [124.910376] Subtest setversion: SUCCESS (0.000s) [124.955196] [147/171] (879s left) vgem_basic (create) [125.024645] Starting subtest: create [125.025924] Subtest create: SUCCESS (0.000s) [125.071816] [148/171] (879s left) vgem_basic (debugfs) [125.141187] Starting subtest: debugfs [125.142384] Subtest debugfs: SUCCESS (0.000s) [125.189157] [149/171] (879s left) vgem_basic (dmabuf-export) [125.257885] Starting subtest: dmabuf-export [125.260054] Subtest dmabuf-export: SUCCESS (0.002s) [125.321654] [150/171] (878s left) vgem_basic (dmabuf-fence) [125.391740] Starting subtest: dmabuf-fence [125.392853] Subtest dmabuf-fence: SUCCESS (0.001s) [125.437144] [151/171] (878s left) vgem_basic (dmabuf-fence-before) [125.509064] Starting subtest: dmabuf-fence-before [125.510225] Subtest dmabuf-fence-before: SUCCESS (0.001s) [125.555299] [152/171] (878s left) vgem_basic (dmabuf-mmap) [125.624610] Starting subtest: dmabuf-mmap [125.639202] Subtest dmabuf-mmap: SUCCESS (0.015s) [125.689162] [153/171] (878s left) vgem_basic (mmap) [125.757751] Starting subtest: mmap [125.767452] Subtest mmap: SUCCESS (0.010s) [125.804813] [154/171] (878s left) vgem_basic (second-client) [125.874455] Starting subtest: second-client [125.908995] Subtest second-client: SUCCESS (0.034s) [125.956693] [155/171] (878s left) vgem_basic (sysfs) [126.024508] Starting subtest: sysfs [126.025671] Subtest sysfs: SUCCESS (0.000s) [126.071635] [156/171] (878s left) core_hotunplug (unbind-rebind) [126.174199] Starting subtest: unbind-rebind [127.048568] Subtest unbind-rebind: SUCCESS (0.874s) [127.066655] [157/171] (877s left) vgem_basic (unload) [127.134837] Starting subtest: unload [156.045498] Subtest unload: SUCCESS (28.910s) [156.123894] [158/171] (848s left) i915_module_load (reload) [156.186242] Starting subtest: reload [158.174068] Subtest reload: SUCCESS (1.989s) [158.193177] [159/171] (846s left) gem_lmem_swapping (basic) [158.270137] Subtest basic: SKIP (0.000s) [158.299575] [160/171] (846s left) gem_lmem_swapping (parallel-random-engines) [158.364837] Subtest parallel-random-engines: SKIP (0.000s) [158.382685] [161/171] (846s left) gem_lmem_swapping (random-engines) [158.446386] Subtest random-engines: SKIP (0.000s) [158.466140] [162/171] (845s left) gem_lmem_swapping (verify-random) [158.530482] Subtest verify-random: SKIP (0.000s) [158.549805] [163/171] (845s left) i915_pm_rpm (module-reload) [160.160623] Starting subtest: module-reload [166.986514] Subtest module-reload: SUCCESS (6.826s) [167.000933] [164/171] (837s left) i915_selftest (live) [167.070987] Starting subtest: live [167.072239] Starting dynamic subtest: sanitycheck [167.711930] Dynamic subtest sanitycheck: SUCCESS (0.639s) [167.718334] Starting dynamic subtest: uncore [168.382704] Dynamic subtest uncore: SUCCESS (0.668s) [168.388825] Starting dynamic subtest: workarounds [169.177046] Dynamic subtest workarounds: SUCCESS (0.792s) [169.183865] Starting dynamic subtest: gt_engines [169.799824] Dynamic subtest gt_engines: SUCCESS (0.620s) [169.807145] Starting dynamic subtest: gt_timelines [175.483214] Dynamic subtest gt_timelines: SUCCESS (5.681s) [175.490178] Starting dynamic subtest: gt_contexts [176.188398] Dynamic subtest gt_contexts: SUCCESS (0.703s) [176.194101] Starting dynamic subtest: gt_lrc [176.835048] Dynamic subtest gt_lrc: SUCCESS (0.645s) [176.840841] Starting dynamic subtest: gt_mocs [177.464824] Dynamic subtest gt_mocs: SUCCESS (0.628s) [177.471289] Starting dynamic subtest: gt_pm [188.341942] Dynamic subtest gt_pm: SUCCESS (10.876s) [188.347365] Starting dynamic subtest: gt_heartbeat [189.052097] Dynamic subtest gt_heartbeat: SUCCESS (0.709s) [189.057599] Starting dynamic subtest: gt_tlb [189.669746] Dynamic subtest gt_tlb: SUCCESS (0.616s) [189.675689] Starting dynamic subtest: requests [195.838663] Dynamic subtest requests: SUCCESS (6.167s) [195.844791] Starting dynamic subtest: migrate [196.477104] Dynamic subtest migrate: SUCCESS (0.637s) [196.483085] Starting dynamic subtest: active [197.161658] Dynamic subtest active: SUCCESS (0.682s) [197.170035] Starting dynamic subtest: objects [198.119437] Dynamic subtest objects: SUCCESS (0.955s) [198.125331] Starting dynamic subtest: mman [202.084953] Dynamic subtest mman: SUCCESS (3.964s) [202.090461] Starting dynamic subtest: dmabuf [202.647969] Dynamic subtest dmabuf: SUCCESS (0.562s) [202.653303] Starting dynamic subtest: vma [203.369992] Dynamic subtest vma: SUCCESS (0.720s) [203.376861] Starting dynamic subtest: coherency [204.580185] Dynamic subtest coherency: SUCCESS (1.207s) [204.587185] Starting dynamic subtest: gtt [208.525854] Dynamic subtest gtt: SUCCESS (3.944s) [208.531631] Starting dynamic subtest: gem [209.344133] Dynamic subtest gem: SUCCESS (0.817s) [209.350342] Starting dynamic subtest: evict [209.942606] Dynamic subtest evict: SUCCESS (0.597s) [209.948203] Starting dynamic subtest: hugepages [211.151738] Dynamic subtest hugepages: SUCCESS (1.207s) [211.159374] Starting dynamic subtest: gem_contexts [215.943330] Dynamic subtest gem_contexts: SUCCESS (4.789s) [215.949677] Starting dynamic subtest: client [216.479881] Dynamic subtest client: SUCCESS (0.536s) [216.485355] Starting dynamic subtest: gem_migrate [217.039142] Dynamic subtest gem_migrate: SUCCESS (0.557s) [217.045457] Starting dynamic subtest: reset [218.767520] Dynamic subtest reset: SUCCESS (1.727s) [218.773672] Starting dynamic subtest: memory_region [219.328911] Dynamic subtest memory_region: SUCCESS (0.560s) [219.334418] Starting dynamic subtest: hangcheck [236.634054] Dynamic subtest hangcheck: SUCCESS (17.304s) [236.639875] Starting dynamic subtest: execlists [237.143936] Dynamic subtest execlists: SUCCESS (0.509s) [237.149486] Starting dynamic subtest: ring_submission [237.805665] Dynamic subtest ring_submission: SUCCESS (0.660s) [237.811354] Starting dynamic subtest: perf [238.501303] Dynamic subtest perf: SUCCESS (0.694s) [238.508071] Starting dynamic subtest: slpc [239.027766] Dynamic subtest slpc: SUCCESS (0.525s) [239.036258] Starting dynamic subtest: guc [239.668219] Dynamic subtest guc: SUCCESS (0.638s) [239.676416] Starting dynamic subtest: guc_multi_lrc [240.366730] Dynamic subtest guc_multi_lrc: SUCCESS (0.696s) [240.372540] Starting dynamic subtest: guc_hang [241.054255] Dynamic subtest guc_hang: SUCCESS (0.685s) [241.061481] Starting dynamic subtest: late_gt_pm [241.569884] Dynamic subtest late_gt_pm: SUCCESS (0.514s) [241.571063] Subtest live: SUCCESS (74.499s) [241.721135] [165/171] (762s left) dmabuf (all-tests) [241.788529] Starting subtest: all-tests [241.792102] Starting dynamic subtest: sanitycheck [241.850640] Dynamic subtest sanitycheck: SUCCESS (0.061s) [241.856772] Starting dynamic subtest: dma_fence [242.025422] Dynamic subtest dma_fence: SUCCESS (0.173s) [242.031610] Starting dynamic subtest: dma_fence_chain [253.505584] Dynamic subtest dma_fence_chain: SUCCESS (11.479s) [253.512318] Starting dynamic subtest: dma_fence_unwrap [253.572434] Dynamic subtest dma_fence_unwrap: SUCCESS (0.065s) [253.581208] Starting dynamic subtest: dma_resv [253.641624] Dynamic subtest dma_resv: SUCCESS (0.068s) [253.642823] Subtest all-tests: SUCCESS (11.853s) [253.654873] [166/171] (750s left) i915_suspend (basic-s2idle-without-i915) [253.717736] Starting subtest: basic-s2idle-without-i915 [270.739716] Subtest basic-s2idle-without-i915: SUCCESS (2.208s) [276.867477] [167/171] (748s left) i915_suspend (basic-s3-without-i915) [276.932099] Starting subtest: basic-s3-without-i915 [294.790913] Subtest basic-s3-without-i915: SUCCESS (1.978s) [299.907284] [168/171] (746s left) gem_exec_suspend (basic-s0) [299.984336] Starting subtest: basic-S0 [299.985516] Starting dynamic subtest: smem [316.640661] Dynamic subtest smem: SUCCESS (1.235s) [316.641699] Subtest basic-S0: SUCCESS (1.236s) [323.786042] [169/171] (744s left) gem_exec_suspend (basic-s3) [323.855863] Starting subtest: basic-S3 [323.856855] Starting dynamic subtest: smem [341.667542] Dynamic subtest smem: SUCCESS (1.304s) [341.668655] Subtest basic-S3: SUCCESS (1.306s) [354.813037] [170/171] (743s left) kms_chamelium_hpd (common-hpd-after-suspend) [354.951562] Subtest common-hpd-after-suspend: SKIP (0.000s) [355.036642] [171/171] (743s left) kms_pipe_crc_basic (suspend-read-crc) [355.121554] Starting subtest: suspend-read-crc [355.122689] Starting dynamic subtest: pipe-A-VGA-1 [372.818315] Dynamic subtest pipe-A-VGA-1: SUCCESS (1.442s) [372.827315] Starting dynamic subtest: pipe-B-VGA-1 [390.837876] Dynamic subtest pipe-B-VGA-1: SUCCESS (1.451s) [390.846108] Starting dynamic subtest: pipe-C-VGA-1 [408.843980] Dynamic subtest pipe-C-VGA-1: SUCCESS (1.476s) [408.845029] Subtest suspend-read-crc: SUCCESS (4.374s) [412.934340] Closing watchdogs Done.