[40.621530] Initializing watchdogs [40.621641] /dev/watchdog0 [40.628075] [001/170] (960s left) i915_module_load (load) [40.694124] Starting subtest: load [41.605699] Subtest load: SUCCESS (0.910s) [41.624021] [002/170] (959s left) core_auth (basic-auth) [41.734937] Starting subtest: basic-auth [41.736106] Subtest basic-auth: SUCCESS (0.001s) [41.799875] [003/170] (959s left) debugfs_test (read_all_entries) [41.866811] Starting subtest: read_all_entries [41.905022] Subtest read_all_entries: SUCCESS (0.038s) [41.982371] [004/170] (959s left) debugfs_test (basic-hwmon) [42.046831] Starting subtest: basic-hwmon [42.048854] Subtest basic-hwmon: SKIP (0.000s) [42.115152] [005/170] (959s left) fbdev (eof) [42.187728] Starting subtest: eof [42.188818] Subtest eof: SUCCESS (0.000s) [42.234430] [006/170] (958s left) fbdev (info) [42.295697] Starting subtest: info [42.296954] Subtest info: SUCCESS (0.000s) [42.350596] [007/170] (958s left) fbdev (nullptr) [42.421044] Starting subtest: nullptr [42.422237] Subtest nullptr: SUCCESS (0.000s) [42.467743] [008/170] (958s left) fbdev (read) [42.537728] Starting subtest: read [42.617884] Subtest read: SUCCESS (0.080s) [42.651094] [009/170] (958s left) fbdev (write) [42.720730] Starting subtest: write [42.760068] Subtest write: SUCCESS (0.039s) [42.799849] [010/170] (958s left) gem_basic (bad-close) [42.863271] Starting subtest: bad-close [42.865302] Subtest bad-close: SUCCESS (0.000s) [42.917057] [011/170] (958s left) gem_basic (create-close) [42.982898] Starting subtest: create-close [42.984972] Subtest create-close: SUCCESS (0.000s) [43.049917] [012/170] (958s left) gem_basic (create-fd-close) [43.113198] Starting subtest: create-fd-close [43.115267] Subtest create-fd-close: SUCCESS (0.000s) [43.182524] [013/170] (958s left) gem_busy (busy@all-engines) [43.254416] Starting subtest: busy [43.255490] Starting dynamic subtest: all-engines [43.279246] Dynamic subtest all-engines: SUCCESS (0.025s) [43.280418] Subtest busy: SUCCESS (0.025s) [43.587718] [014/170] (957s left) gem_close_race (basic-process) [43.662263] Starting subtest: basic-process [43.697300] Subtest basic-process: SUCCESS (0.034s) [43.868983] [015/170] (957s left) gem_close_race (basic-threads) [43.939564] Starting subtest: basic-threads [45.098576] Subtest basic-threads: SUCCESS (1.158s) [45.268129] [016/170] (956s left) gem_ctx_create (basic) [45.336869] Starting subtest: basic [45.337917] Subtest basic: SUCCESS (0.000s) [45.499365] [017/170] (955s left) gem_ctx_create (basic-files) [45.567608] Starting subtest: basic-files [47.579671] Subtest basic-files: SUCCESS (2.012s) [47.869924] [018/170] (953s left) gem_ctx_exec (basic) [47.936274] Starting subtest: basic [47.938454] Subtest basic: SUCCESS (0.002s) [48.016721] [019/170] (953s left) gem_exec_basic (basic) [48.082817] Starting subtest: basic [48.083942] Starting dynamic subtest: rcs0-smem [48.084966] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [48.090450] Starting dynamic subtest: bcs0-smem [48.091563] Dynamic subtest bcs0-smem: SUCCESS (0.000s) [48.097462] Starting dynamic subtest: vcs0-smem [48.098548] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [48.104619] Starting dynamic subtest: vecs0-smem [48.105615] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [48.106788] Subtest basic: SUCCESS (0.002s) [48.250385] [020/170] (953s left) gem_exec_create (basic) [48.321203] Starting subtest: basic [48.322215] Starting dynamic subtest: smem [50.371732] Dynamic subtest smem: SUCCESS (2.049s) [50.373269] Subtest basic: SUCCESS (2.050s) [50.538332] [021/170] (950s left) gem_exec_fence (basic-busy) [50.724449] Starting subtest: basic-busy [50.725430] Starting dynamic subtest: rcs0 [50.756243] Dynamic subtest rcs0: SUCCESS (0.032s) [50.762117] Starting dynamic subtest: bcs0 [50.789109] Dynamic subtest bcs0: SUCCESS (0.032s) [50.794800] Starting dynamic subtest: vcs0 [50.819245] Dynamic subtest vcs0: SUCCESS (0.031s) [50.824790] Starting dynamic subtest: vecs0 [50.848717] Dynamic subtest vecs0: SUCCESS (0.029s) [50.850027] Subtest basic-busy: SUCCESS (0.124s) [51.300516] [022/170] (950s left) gem_exec_fence (basic-wait) [51.480513] Starting subtest: basic-wait [51.481770] Starting dynamic subtest: rcs0 [51.505367] Dynamic subtest rcs0: SUCCESS (0.025s) [51.512247] Starting dynamic subtest: bcs0 [51.524958] Dynamic subtest bcs0: SUCCESS (0.019s) [51.531159] Starting dynamic subtest: vcs0 [51.555064] Dynamic subtest vcs0: SUCCESS (0.030s) [51.561708] Starting dynamic subtest: vecs0 [51.583384] Dynamic subtest vecs0: SUCCESS (0.029s) [51.584631] Subtest basic-wait: SUCCESS (0.103s) [52.014900] [023/170] (949s left) gem_exec_fence (basic-await) [52.197121] Starting subtest: basic-await [52.198201] Starting dynamic subtest: rcs0 [52.267906] Dynamic subtest rcs0: SUCCESS (0.071s) [52.273297] Starting dynamic subtest: bcs0 [52.338526] Dynamic subtest bcs0: SUCCESS (0.070s) [52.343865] Starting dynamic subtest: vcs0 [52.409459] Dynamic subtest vcs0: SUCCESS (0.071s) [52.415297] Starting dynamic subtest: vecs0 [52.480995] Dynamic subtest vecs0: SUCCESS (0.071s) [52.482121] Subtest basic-await: SUCCESS (0.283s) [52.916294] [024/170] (948s left) gem_exec_fence (nb-await) [53.095114] Starting subtest: nb-await [53.096279] Starting dynamic subtest: rcs0 [53.149122] Dynamic subtest rcs0: SUCCESS (0.054s) [53.154516] Starting dynamic subtest: bcs0 [53.202635] Dynamic subtest bcs0: SUCCESS (0.054s) [53.208388] Starting dynamic subtest: vcs0 [53.256111] Dynamic subtest vcs0: SUCCESS (0.053s) [53.261667] Starting dynamic subtest: vecs0 [53.310793] Dynamic subtest vecs0: SUCCESS (0.055s) [53.311829] Subtest nb-await: SUCCESS (0.216s) [53.732091] [025/170] (947s left) gem_exec_gttfill (basic) [53.803992] Starting subtest: basic [56.792949] Subtest basic: SUCCESS (2.988s) [56.946972] [026/170] (944s left) gem_exec_parallel (engines) [57.081527] Starting subtest: engines [57.082584] Starting dynamic subtest: basic [58.496343] Dynamic subtest basic: SUCCESS (1.415s) [58.503233] Starting dynamic subtest: contexts [60.025306] Dynamic subtest contexts: SUCCESS (1.529s) [60.031993] Starting dynamic subtest: fds [61.794549] Dynamic subtest fds: SUCCESS (1.769s) [61.801226] Starting dynamic subtest: userptr [63.243620] Dynamic subtest userptr: SUCCESS (1.449s) [63.244697] Subtest engines: SUCCESS (6.162s) [63.413985] [027/170] (938s left) gem_exec_store (basic) [63.484772] Starting subtest: basic [63.515933] Subtest basic: SUCCESS (0.031s) [63.680057] [028/170] (937s left) gem_flink_basic (bad-flink) [63.741920] Starting subtest: bad-flink [63.743085] Subtest bad-flink: SUCCESS (0.000s) [63.796544] [029/170] (937s left) gem_flink_basic (bad-open) [63.857849] Starting subtest: bad-open [63.858886] Subtest bad-open: SUCCESS (0.000s) [63.912567] [030/170] (937s left) gem_flink_basic (basic) [63.974135] Starting subtest: basic [63.975193] Subtest basic: SUCCESS (0.000s) [64.045600] [031/170] (937s left) gem_flink_basic (double-flink) [64.107020] Starting subtest: double-flink [64.108080] Subtest double-flink: SUCCESS (0.000s) [64.181298] [032/170] (937s left) gem_flink_basic (flink-lifetime) [64.243531] Starting subtest: flink-lifetime [64.244640] Subtest flink-lifetime: SUCCESS (0.001s) [64.312850] [033/170] (937s left) gem_huc_copy (huc-copy) [64.380270] Subtest huc-copy: SKIP (0.000s) [64.406893] [034/170] (937s left) gem_linear_blits (basic) [64.543187] Starting subtest: basic [64.549513] Subtest basic: SUCCESS (0.007s) [64.652825] [035/170] (936s left) gem_mmap (basic) [64.788402] Starting subtest: basic [64.794082] Subtest basic: SUCCESS (0.000s) [64.864788] [036/170] (936s left) gem_mmap_gtt (basic) [64.926302] Starting subtest: basic [64.927462] Subtest basic: SUCCESS (0.000s) [64.974727] [037/170] (936s left) gem_render_linear_blits (basic) [65.039755] Starting subtest: basic [65.056227] Subtest basic: SUCCESS (0.017s) [65.090807] [038/170] (936s left) gem_render_tiled_blits (basic) [65.154790] Starting subtest: basic [65.174317] Subtest basic: SUCCESS (0.020s) [65.207691] [039/170] (936s left) gem_ringfill (basic-all) [65.393841] Starting subtest: basic-all [66.505313] Subtest basic-all: SUCCESS (1.113s) [66.565809] [040/170] (935s left) gem_softpin (allocator-basic) [66.629822] Subtest allocator-basic: SKIP (0.000s) [66.677990] [041/170] (935s left) gem_softpin (allocator-basic-reserve) [66.741928] Subtest allocator-basic-reserve: SKIP (0.000s) [66.795282] [042/170] (934s left) gem_softpin (safe-alignment) [66.859431] Starting subtest: safe-alignment [66.867591] Subtest safe-alignment: SUCCESS (0.009s) [66.963120] [043/170] (934s left) gem_sync (basic-all) [67.033651] Starting subtest: basic-all [69.208905] Subtest basic-all: SUCCESS (2.174s) [69.379702] [044/170] (932s left) gem_sync (basic-each) [69.447855] Starting subtest: basic-each [71.509354] Subtest basic-each: SUCCESS (2.061s) [71.680050] [045/170] (930s left) gem_tiled_blits (basic) [71.807366] Starting subtest: basic [71.817903] Subtest basic: SUCCESS (0.011s) [71.902482] [046/170] (929s left) gem_tiled_fence_blits (basic) [72.099199] Starting subtest: basic [72.105069] Subtest basic: SUCCESS (0.006s) [72.203655] [047/170] (929s left) gem_tiled_pread_basic [72.495285] [048/170] (929s left) gem_wait (busy@all-engines) [72.568759] Starting subtest: busy [72.569901] Starting dynamic subtest: all-engines [73.085096] Dynamic subtest all-engines: SUCCESS (0.516s) [73.086335] Subtest busy: SUCCESS (0.516s) [73.262699] [049/170] (928s left) gem_wait (wait@all-engines) [73.337196] Starting subtest: wait [73.338491] Starting dynamic subtest: all-engines [74.354201] Dynamic subtest all-engines: SUCCESS (1.016s) [74.355205] Subtest wait: SUCCESS (1.016s) [74.531566] [050/170] (927s left) i915_getparams_basic (basic-eu-total) [74.592391] Starting subtest: basic-eu-total [74.594392] Subtest basic-eu-total: SUCCESS (0.000s) [74.643517] [051/170] (927s left) i915_getparams_basic (basic-subslice-total) [74.704962] Starting subtest: basic-subslice-total [74.707202] Subtest basic-subslice-total: SUCCESS (0.000s) [74.759808] [052/170] (927s left) i915_hangman (error-state-basic) [74.830570] Starting subtest: error-state-basic [74.835647] Subtest error-state-basic: SUCCESS (0.004s) [74.909799] [053/170] (926s left) i915_pciid [75.026698] [054/170] (926s left) kms_addfb_basic (addfb25-bad-modifier) [75.088628] Starting subtest: addfb25-bad-modifier [75.090561] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [75.194047] [055/170] (926s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [75.255721] Starting subtest: addfb25-framebuffer-vs-set-tiling [75.256791] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [75.344920] [056/170] (926s left) kms_addfb_basic (addfb25-modifier-no-flag) [75.406875] Starting subtest: addfb25-modifier-no-flag [75.407969] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [75.493060] [057/170] (926s left) kms_addfb_basic (addfb25-x-tiled-legacy) [75.554974] Starting subtest: addfb25-x-tiled-legacy [75.556028] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [75.643379] [058/170] (926s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [75.705587] Starting subtest: addfb25-x-tiled-mismatch-legacy [75.706734] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [75.792996] [059/170] (926s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [75.876476] Starting subtest: addfb25-yf-tiled-legacy [75.877565] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [75.942740] [060/170] (926s left) kms_addfb_basic (addfb25-y-tiled-legacy) [76.024530] Starting subtest: addfb25-y-tiled-legacy [76.025570] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [76.093344] [061/170] (925s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [76.175522] Starting subtest: addfb25-y-tiled-small-legacy [76.177787] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s) [76.243636] [062/170] (925s left) kms_addfb_basic (bad-pitch-0) [76.304729] Starting subtest: bad-pitch-0 [76.305830] Subtest bad-pitch-0: SUCCESS (0.000s) [76.392870] [063/170] (925s left) kms_addfb_basic (bad-pitch-1024) [76.454377] Starting subtest: bad-pitch-1024 [76.455462] Subtest bad-pitch-1024: SUCCESS (0.000s) [76.543454] [064/170] (925s left) kms_addfb_basic (bad-pitch-128) [76.606353] Starting subtest: bad-pitch-128 [76.607379] Subtest bad-pitch-128: SUCCESS (0.000s) [76.692647] [065/170] (925s left) kms_addfb_basic (bad-pitch-256) [76.753994] Starting subtest: bad-pitch-256 [76.755095] Subtest bad-pitch-256: SUCCESS (0.000s) [76.842867] [066/170] (925s left) kms_addfb_basic (bad-pitch-32) [76.905343] Starting subtest: bad-pitch-32 [76.906345] Subtest bad-pitch-32: SUCCESS (0.000s) [76.992790] [067/170] (925s left) kms_addfb_basic (bad-pitch-63) [77.054232] Starting subtest: bad-pitch-63 [77.055225] Subtest bad-pitch-63: SUCCESS (0.000s) [77.142529] [068/170] (924s left) kms_addfb_basic (bad-pitch-65536) [77.205177] Starting subtest: bad-pitch-65536 [77.206228] Subtest bad-pitch-65536: SUCCESS (0.000s) [77.292888] [069/170] (924s left) kms_addfb_basic (bad-pitch-999) [77.354414] Starting subtest: bad-pitch-999 [77.355417] Subtest bad-pitch-999: SUCCESS (0.000s) [77.443128] [070/170] (924s left) kms_addfb_basic (basic) [77.504919] Starting subtest: basic [77.506025] Subtest basic: SUCCESS (0.000s) [77.593447] [071/170] (924s left) kms_addfb_basic (basic-x-tiled-legacy) [77.656285] Starting subtest: basic-x-tiled-legacy [77.657306] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [77.742262] [072/170] (924s left) kms_addfb_basic (basic-y-tiled-legacy) [77.806191] Starting subtest: basic-y-tiled-legacy [77.807180] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [77.891862] [073/170] (924s left) kms_addfb_basic (bo-too-small) [77.973701] Starting subtest: bo-too-small [77.974844] Subtest bo-too-small: SUCCESS (0.000s) [78.042624] [074/170] (924s left) kms_addfb_basic (bo-too-small-due-to-tiling) [78.125309] Starting subtest: bo-too-small-due-to-tiling [78.126285] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [78.192019] [075/170] (923s left) kms_addfb_basic (clobberred-modifier) [78.255520] Starting subtest: clobberred-modifier [78.256504] Subtest clobberred-modifier: SUCCESS (0.000s) [78.342644] [076/170] (923s left) kms_addfb_basic (framebuffer-vs-set-tiling) [78.405599] Starting subtest: framebuffer-vs-set-tiling [78.406647] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [78.492664] [077/170] (923s left) kms_addfb_basic (invalid-get-prop) [78.555779] Starting subtest: invalid-get-prop [78.556816] Subtest invalid-get-prop: SUCCESS (0.000s) [78.642090] [078/170] (923s left) kms_addfb_basic (invalid-get-prop-any) [78.702940] Starting subtest: invalid-get-prop-any [78.704020] Subtest invalid-get-prop-any: SUCCESS (0.000s) [78.792615] [079/170] (923s left) kms_addfb_basic (invalid-set-prop) [78.855840] Starting subtest: invalid-set-prop [78.856929] Subtest invalid-set-prop: SUCCESS (0.000s) [78.944534] [080/170] (923s left) kms_addfb_basic (invalid-set-prop-any) [79.007272] Starting subtest: invalid-set-prop-any [79.008327] Subtest invalid-set-prop-any: SUCCESS (0.000s) [79.108921] [081/170] (923s left) kms_addfb_basic (no-handle) [79.169903] Starting subtest: no-handle [79.171014] Subtest no-handle: SUCCESS (0.000s) [79.259259] [082/170] (923s left) kms_addfb_basic (size-max) [79.341958] Starting subtest: size-max [79.343071] Subtest size-max: SUCCESS (0.000s) [79.408621] [083/170] (922s left) kms_addfb_basic (small-bo) [79.491939] Starting subtest: small-bo [79.492946] Subtest small-bo: SUCCESS (0.000s) [79.564518] [084/170] (922s left) kms_addfb_basic (tile-pitch-mismatch) [79.626635] Starting subtest: tile-pitch-mismatch [79.627652] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [79.725665] [085/170] (922s left) kms_addfb_basic (too-high) [79.808433] Starting subtest: too-high [79.809393] Subtest too-high: SUCCESS (0.000s) [79.874884] [086/170] (922s left) kms_addfb_basic (too-wide) [79.958280] Starting subtest: too-wide [79.959350] Subtest too-wide: SUCCESS (0.000s) [80.024838] [087/170] (922s left) kms_addfb_basic (unused-handle) [80.085768] Starting subtest: unused-handle [80.086866] Subtest unused-handle: SUCCESS (0.000s) [80.176408] [088/170] (922s left) kms_addfb_basic (unused-modifier) [80.237982] Starting subtest: unused-modifier [80.239029] Subtest unused-modifier: SUCCESS (0.000s) [80.325914] [089/170] (922s left) kms_addfb_basic (unused-offsets) [80.389616] Starting subtest: unused-offsets [80.390639] Subtest unused-offsets: SUCCESS (0.000s) [80.475453] [090/170] (921s left) kms_addfb_basic (unused-pitches) [80.537125] Starting subtest: unused-pitches [80.538151] Subtest unused-pitches: SUCCESS (0.000s) [80.624746] [091/170] (921s left) kms_busy (basic) [80.712251] Starting subtest: basic [80.713259] Starting dynamic subtest: flip [81.184318] Dynamic subtest flip: SUCCESS (0.471s) [81.191222] Starting dynamic subtest: modeset [81.686944] Dynamic subtest modeset: SUCCESS (0.502s) [81.687956] Subtest basic: SUCCESS (0.974s) [81.820236] [092/170] (920s left) kms_chamelium_hpd (dp-hpd-fast) [81.961780] Subtest dp-hpd-fast: SKIP (0.000s) [82.044037] [093/170] (920s left) kms_chamelium_edid (dp-edid-read) [82.174871] Subtest dp-edid-read: SKIP (0.000s) [82.257050] [094/170] (920s left) kms_chamelium_frames (dp-crc-fast) [82.387838] Subtest dp-crc-fast: SKIP (0.000s) [82.470776] [095/170] (919s left) kms_chamelium_hpd (hdmi-hpd-fast) [82.599232] Subtest hdmi-hpd-fast: SKIP (0.000s) [82.679143] [096/170] (919s left) kms_chamelium_edid (hdmi-edid-read) [82.792716] Subtest hdmi-edid-read: SKIP (0.000s) [82.874332] [097/170] (919s left) kms_chamelium_frames (hdmi-crc-fast) [83.003702] Subtest hdmi-crc-fast: SKIP (0.000s) [83.084745] [098/170] (919s left) kms_chamelium_hpd (vga-hpd-fast) [83.200121] Subtest vga-hpd-fast: SKIP (0.000s) [83.282200] [099/170] (919s left) kms_chamelium_edid (vga-edid-read) [83.411735] Subtest vga-edid-read: SKIP (0.000s) [83.494423] [100/170] (919s left) kms_prop_blob (basic) [83.556916] Starting subtest: basic [83.557937] Subtest basic: SUCCESS (0.000s) [83.613289] [101/170] (918s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [83.698895] Starting subtest: basic-busy-flip-before-cursor-atomic [84.620618] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.922s) [84.750066] [102/170] (917s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [84.832688] Starting subtest: basic-busy-flip-before-cursor-legacy [85.757910] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.925s) [85.883910] [103/170] (916s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [85.965506] Starting subtest: basic-flip-after-cursor-atomic [86.907676] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.941s) [87.037438] [104/170] (915s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [87.121769] Starting subtest: basic-flip-after-cursor-legacy [88.044477] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.924s) [88.166765] [105/170] (914s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [88.250853] Starting subtest: basic-flip-after-cursor-varying-size [89.189412] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.940s) [89.319824] [106/170] (913s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [89.404093] Starting subtest: basic-flip-before-cursor-atomic [90.327028] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.923s) [90.455621] [107/170] (912s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [90.537882] Starting subtest: basic-flip-before-cursor-legacy [91.463762] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.926s) [91.594833] [108/170] (911s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [91.677814] Starting subtest: basic-flip-before-cursor-varying-size [92.602355] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.924s) [92.734617] [109/170] (909s left) kms_flip (basic-flip-vs-dpms) [92.808905] Starting subtest: basic-flip-vs-dpms [92.809945] Starting dynamic subtest: A-VGA1 [93.732873] Dynamic subtest A-VGA1: SUCCESS (0.923s) [93.739239] Starting dynamic subtest: B-VGA1 [94.616793] Dynamic subtest B-VGA1: SUCCESS (0.884s) [94.623250] Starting dynamic subtest: C-VGA1 [95.504684] Dynamic subtest C-VGA1: SUCCESS (0.888s) [95.506831] Subtest basic-flip-vs-dpms: SUCCESS (2.699s) [95.646022] [110/170] (907s left) kms_flip (basic-flip-vs-modeset) [95.720412] Starting subtest: basic-flip-vs-modeset [95.722396] Starting dynamic subtest: A-VGA1 [96.563910] Dynamic subtest A-VGA1: SUCCESS (0.841s) [96.570379] Starting dynamic subtest: B-VGA1 [97.348469] Dynamic subtest B-VGA1: SUCCESS (0.785s) [97.355098] Starting dynamic subtest: C-VGA1 [98.137165] Dynamic subtest C-VGA1: SUCCESS (0.788s) [98.139465] Subtest basic-flip-vs-modeset: SUCCESS (2.418s) [98.272891] [111/170] (904s left) kms_flip (basic-flip-vs-wf_vblank) [98.348830] Starting subtest: basic-flip-vs-wf_vblank [98.351017] Starting dynamic subtest: A-VGA1 [99.497723] Dynamic subtest A-VGA1: SUCCESS (1.147s) [99.504332] Starting dynamic subtest: B-VGA1 [100.620244] Dynamic subtest B-VGA1: SUCCESS (1.122s) [100.626966] Starting dynamic subtest: C-VGA1 [101.742102] Dynamic subtest C-VGA1: SUCCESS (1.121s) [101.743742] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.396s) [101.895290] [112/170] (900s left) kms_flip (basic-plain-flip) [101.970344] Starting subtest: basic-plain-flip [101.971381] Starting dynamic subtest: A-VGA1 [102.835378] Dynamic subtest A-VGA1: SUCCESS (0.864s) [102.841800] Starting dynamic subtest: B-VGA1 [103.671864] Dynamic subtest B-VGA1: SUCCESS (0.836s) [103.678364] Starting dynamic subtest: C-VGA1 [104.517494] Dynamic subtest C-VGA1: SUCCESS (0.844s) [104.520408] Subtest basic-plain-flip: SUCCESS (2.552s) [104.702799] [113/170] (898s left) kms_force_connector_basic (force-connector-state) [104.778436] Starting subtest: force-connector-state [104.911377] Subtest force-connector-state: SUCCESS (0.134s) [105.083312] [114/170] (897s left) kms_force_connector_basic (force-edid) [105.157712] Starting subtest: force-edid [105.177373] Subtest force-edid: SUCCESS (0.020s) [105.266585] [115/170] (897s left) kms_force_connector_basic (force-load-detect) [105.339134] Starting subtest: force-load-detect [105.488392] Subtest force-load-detect: SUCCESS (0.148s) [105.660331] [116/170] (897s left) kms_force_connector_basic (prune-stale-modes) [105.734362] Starting subtest: prune-stale-modes [105.750506] Subtest prune-stale-modes: SUCCESS (0.017s) [105.847736] [117/170] (896s left) kms_frontbuffer_tracking (basic) [105.936120] Starting subtest: basic [107.728910] Subtest basic: SUCCESS (1.795s) [107.810854] [118/170] (894s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [107.896769] Starting subtest: compare-crc-sanitycheck [107.897774] Starting dynamic subtest: pipe-A-VGA-1 [108.165916] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.269s) [108.172483] Starting dynamic subtest: pipe-B-VGA-1 [108.430736] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.264s) [108.437577] Starting dynamic subtest: pipe-C-VGA-1 [108.693903] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.263s) [108.695076] Subtest compare-crc-sanitycheck: SUCCESS (0.798s) [108.827212] [119/170] (893s left) kms_pipe_crc_basic (hang-read-crc) [108.911986] Starting subtest: hang-read-crc [108.913100] Starting dynamic subtest: pipe-A-VGA-1 [109.641205] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.728s) [109.647920] Starting dynamic subtest: pipe-B-VGA-1 [110.358846] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.717s) [110.365343] Starting dynamic subtest: pipe-C-VGA-1 [111.076867] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.717s) [111.078038] Subtest hang-read-crc: SUCCESS (2.164s) [111.190709] [120/170] (891s left) kms_pipe_crc_basic (nonblocking-crc) [111.281278] Starting subtest: nonblocking-crc [111.282271] Starting dynamic subtest: pipe-A-VGA-1 [111.716491] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.433s) [111.724775] Starting dynamic subtest: pipe-B-VGA-1 [112.141111] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.423s) [112.149408] Starting dynamic subtest: pipe-C-VGA-1 [112.572785] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.431s) [112.573942] Subtest nonblocking-crc: SUCCESS (1.292s) [112.685569] [121/170] (890s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [112.770246] Starting subtest: nonblocking-crc-frame-sequence [112.772451] Starting dynamic subtest: pipe-A-VGA-1 [113.209789] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.438s) [113.216641] Starting dynamic subtest: pipe-B-VGA-1 [113.635251] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.424s) [113.641764] Starting dynamic subtest: pipe-C-VGA-1 [114.061772] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.426s) [114.062935] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.292s) [114.178108] [122/170] (888s left) kms_pipe_crc_basic (read-crc) [114.263331] Starting subtest: read-crc [114.264316] Starting dynamic subtest: pipe-A-VGA-1 [114.636873] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.371s) [114.643443] Starting dynamic subtest: pipe-B-VGA-1 [114.995035] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.357s) [115.001579] Starting dynamic subtest: pipe-C-VGA-1 [115.356455] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.360s) [115.357671] Subtest read-crc: SUCCESS (1.093s) [115.472069] [123/170] (887s left) kms_pipe_crc_basic (read-crc-frame-sequence) [115.557262] Starting subtest: read-crc-frame-sequence [115.558266] Starting dynamic subtest: pipe-A-VGA-1 [115.929959] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.372s) [115.936580] Starting dynamic subtest: pipe-B-VGA-1 [116.289382] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.357s) [116.296027] Starting dynamic subtest: pipe-C-VGA-1 [116.650125] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.359s) [116.651215] Subtest read-crc-frame-sequence: SUCCESS (1.092s) [116.763671] [124/170] (886s left) kms_psr (primary_page_flip) [116.827225] Subtest primary_page_flip: SKIP (0.000s) [116.859146] [125/170] (886s left) kms_psr (cursor_plane_move) [116.921597] Subtest cursor_plane_move: SKIP (0.000s) [116.943605] [126/170] (885s left) kms_psr (sprite_plane_onoff) [117.007200] Subtest sprite_plane_onoff: SKIP (0.000s) [117.025089] [127/170] (885s left) kms_psr (primary_mmap_gtt) [117.088508] Subtest primary_mmap_gtt: SKIP (0.000s) [117.108922] [128/170] (885s left) kms_setmode (basic-clone-single-crtc) [117.170826] Starting subtest: basic-clone-single-crtc [117.173987] Subtest basic-clone-single-crtc: SKIP (0.000s) [117.232584] [129/170] (885s left) i915_pm_backlight (basic-brightness) [117.319951] Subtest basic-brightness: SKIP (0.000s) [117.341477] [130/170] (885s left) i915_pm_rpm (basic-pci-d3-state) [129.044079] Subtest basic-pci-d3-state: SKIP (0.000s) [129.137850] [131/170] (873s left) i915_pm_rpm (basic-rte) [129.198770] Starting subtest: basic-rte [141.023343] Subtest basic-rte: FAIL (11.651s) [151.480897] [132/170] (851s left) i915_pm_rps (basic-api) [151.545318] Starting subtest: basic-api [151.547547] Subtest basic-api: SUCCESS (0.002s) [151.602841] [133/170] (851s left) prime_self_import (basic-llseek-bad) [151.665939] Starting subtest: basic-llseek-bad [151.668045] Subtest basic-llseek-bad: SUCCESS (0.001s) [151.754328] [134/170] (851s left) prime_self_import (basic-llseek-size) [151.815144] Starting subtest: basic-llseek-size [151.817340] Subtest basic-llseek-size: SUCCESS (0.002s) [151.887193] [135/170] (851s left) prime_self_import (basic-with_fd_dup) [151.948219] Starting subtest: basic-with_fd_dup [151.950303] Subtest basic-with_fd_dup: SUCCESS (0.002s) [152.018365] [136/170] (851s left) prime_self_import (basic-with_one_bo) [152.078359] Starting subtest: basic-with_one_bo [152.081783] Subtest basic-with_one_bo: SUCCESS (0.002s) [152.131367] [137/170] (850s left) prime_self_import (basic-with_one_bo_two_files) [152.192855] Starting subtest: basic-with_one_bo_two_files [152.195097] Subtest basic-with_one_bo_two_files: SUCCESS (0.001s) [152.269338] [138/170] (850s left) prime_self_import (basic-with_two_bos) [152.329858] Starting subtest: basic-with_two_bos [152.332072] Subtest basic-with_two_bos: SUCCESS (0.002s) [152.401196] [139/170] (850s left) prime_vgem (basic-fence-flip) [152.474794] Starting subtest: basic-fence-flip [152.804208] Subtest basic-fence-flip: SUCCESS (0.329s) [152.885184] [140/170] (850s left) prime_vgem (basic-fence-mmap) [152.958729] Starting subtest: basic-fence-mmap [152.981204] Subtest basic-fence-mmap: SUCCESS (0.022s) [153.055238] [141/170] (850s left) prime_vgem (basic-fence-read) [153.124041] Starting subtest: basic-fence-read [153.157246] Subtest basic-fence-read: SUCCESS (0.033s) [153.218462] [142/170] (849s left) prime_vgem (basic-gtt) [153.291477] Starting subtest: basic-gtt [153.302677] Subtest basic-gtt: SUCCESS (0.011s) [153.370254] [143/170] (849s left) prime_vgem (basic-read) [153.441768] Starting subtest: basic-read [153.458594] Subtest basic-read: SUCCESS (0.017s) [153.535016] [144/170] (849s left) prime_vgem (basic-write) [153.607032] Starting subtest: basic-write [153.624651] Subtest basic-write: SUCCESS (0.018s) [153.702571] [145/170] (849s left) vgem_basic (setversion) [153.771260] Starting subtest: setversion [153.773592] Subtest setversion: SUCCESS (0.000s) [153.818961] [146/170] (849s left) vgem_basic (create) [153.887821] Starting subtest: create [153.888890] Subtest create: SUCCESS (0.000s) [153.935470] [147/170] (849s left) vgem_basic (debugfs) [154.004672] Starting subtest: debugfs [154.005715] Subtest debugfs: SUCCESS (0.000s) [154.051942] [148/170] (849s left) vgem_basic (dmabuf-export) [154.121630] Starting subtest: dmabuf-export [154.123911] Subtest dmabuf-export: SUCCESS (0.002s) [154.202258] [149/170] (849s left) vgem_basic (dmabuf-fence) [154.271115] Starting subtest: dmabuf-fence [154.272276] Subtest dmabuf-fence: SUCCESS (0.001s) [154.318160] [150/170] (848s left) vgem_basic (dmabuf-fence-before) [154.387903] Starting subtest: dmabuf-fence-before [154.389267] Subtest dmabuf-fence-before: SUCCESS (0.000s) [154.435207] [151/170] (848s left) vgem_basic (dmabuf-mmap) [154.504678] Starting subtest: dmabuf-mmap [154.519418] Subtest dmabuf-mmap: SUCCESS (0.016s) [154.569262] [152/170] (848s left) vgem_basic (mmap) [154.637665] Starting subtest: mmap [154.646393] Subtest mmap: SUCCESS (0.010s) [154.689404] [153/170] (848s left) vgem_basic (second-client) [154.754545] Starting subtest: second-client [154.804446] Subtest second-client: SUCCESS (0.050s) [154.854635] [154/170] (848s left) vgem_basic (sysfs) [154.920937] Starting subtest: sysfs [154.922167] Subtest sysfs: SUCCESS (0.000s) [154.968591] [155/170] (848s left) core_hotunplug (unbind-rebind) [155.071393] Starting subtest: unbind-rebind [155.905131] Subtest unbind-rebind: SUCCESS (0.834s) [155.923113] [156/170] (847s left) vgem_basic (unload) [155.990692] Starting subtest: unload [184.900031] Subtest unload: SUCCESS (28.908s) [184.983332] [157/170] (818s left) i915_module_load (reload) [185.045192] Starting subtest: reload [186.946790] Subtest reload: SUCCESS (1.898s) [186.969347] [158/170] (816s left) gem_lmem_swapping (basic) [187.064995] Subtest basic: SKIP (0.000s) [187.084950] [159/170] (816s left) gem_lmem_swapping (parallel-random-engines) [187.150072] Subtest parallel-random-engines: SKIP (0.000s) [187.168156] [160/170] (816s left) gem_lmem_swapping (random-engines) [187.232834] Subtest random-engines: SKIP (0.000s) [187.253450] [161/170] (816s left) gem_lmem_swapping (verify-random) [187.318985] Subtest verify-random: SKIP (0.000s) [187.336775] [162/170] (816s left) i915_pm_rpm (module-reload) [188.947763] Starting subtest: module-reload [195.712942] Subtest module-reload: SUCCESS (6.765s) [195.727530] [163/170] (807s left) i915_selftest (live) [195.799126] Starting subtest: live [195.800387] Starting dynamic subtest: sanitycheck [196.457343] Dynamic subtest sanitycheck: SUCCESS (0.657s) [196.464904] Starting dynamic subtest: uncore [197.025136] Dynamic subtest uncore: SUCCESS (0.567s) [197.030953] Starting dynamic subtest: workarounds [197.710056] Dynamic subtest workarounds: SUCCESS (0.684s) [197.715596] Starting dynamic subtest: gt_engines [198.337833] Dynamic subtest gt_engines: SUCCESS (0.626s) [198.344600] Starting dynamic subtest: gt_timelines [204.003338] Dynamic subtest gt_timelines: SUCCESS (5.664s) [204.009131] Starting dynamic subtest: gt_contexts [204.728175] Dynamic subtest gt_contexts: SUCCESS (0.723s) [204.733610] Starting dynamic subtest: gt_lrc [205.411715] Dynamic subtest gt_lrc: SUCCESS (0.682s) [205.417454] Starting dynamic subtest: gt_mocs [206.111118] Dynamic subtest gt_mocs: SUCCESS (0.699s) [206.116748] Starting dynamic subtest: gt_pm [216.357634] Dynamic subtest gt_pm: SUCCESS (10.245s) [216.363314] Starting dynamic subtest: gt_heartbeat [216.995344] Dynamic subtest gt_heartbeat: SUCCESS (0.637s) [217.002229] Starting dynamic subtest: gt_tlb [217.708392] Dynamic subtest gt_tlb: SUCCESS (0.713s) [217.714112] Starting dynamic subtest: requests [223.918694] Dynamic subtest requests: SUCCESS (6.210s) [223.928297] Starting dynamic subtest: migrate [224.556677] Dynamic subtest migrate: SUCCESS (0.636s) [224.562284] Starting dynamic subtest: active [225.082894] Dynamic subtest active: SUCCESS (0.525s) [225.089968] Starting dynamic subtest: objects [226.020563] Dynamic subtest objects: SUCCESS (0.936s) [226.026151] Starting dynamic subtest: mman [230.107653] Dynamic subtest mman: SUCCESS (4.086s) [230.113214] Starting dynamic subtest: dmabuf [230.815496] Dynamic subtest dmabuf: SUCCESS (0.706s) [230.820958] Starting dynamic subtest: vma [231.371027] Dynamic subtest vma: SUCCESS (0.555s) [231.379418] Starting dynamic subtest: coherency [232.522335] Dynamic subtest coherency: SUCCESS (1.150s) [232.528224] Starting dynamic subtest: gtt [236.539995] Dynamic subtest gtt: SUCCESS (4.017s) [236.545360] Starting dynamic subtest: gem [237.253880] Dynamic subtest gem: SUCCESS (0.713s) [237.261237] Starting dynamic subtest: evict [237.770902] Dynamic subtest evict: SUCCESS (0.516s) [237.776457] Starting dynamic subtest: hugepages [238.904054] Dynamic subtest hugepages: SUCCESS (1.133s) [238.909564] Starting dynamic subtest: gem_contexts [243.716182] Dynamic subtest gem_contexts: SUCCESS (4.812s) [243.722269] Starting dynamic subtest: client [244.424425] Dynamic subtest client: SUCCESS (0.707s) [244.430212] Starting dynamic subtest: gem_migrate [244.953663] Dynamic subtest gem_migrate: SUCCESS (0.528s) [244.960440] Starting dynamic subtest: reset [246.622318] Dynamic subtest reset: SUCCESS (1.668s) [246.628453] Starting dynamic subtest: memory_region [247.291028] Dynamic subtest memory_region: SUCCESS (0.668s) [247.297957] Starting dynamic subtest: hangcheck [264.537489] Dynamic subtest hangcheck: SUCCESS (17.245s) [264.543232] Starting dynamic subtest: execlists [265.106615] Dynamic subtest execlists: SUCCESS (0.567s) [265.112251] Starting dynamic subtest: ring_submission [265.757054] Dynamic subtest ring_submission: SUCCESS (0.649s) [265.764193] Starting dynamic subtest: perf [266.457109] Dynamic subtest perf: SUCCESS (0.699s) [266.463304] Starting dynamic subtest: slpc [266.983829] Dynamic subtest slpc: SUCCESS (0.525s) [266.990279] Starting dynamic subtest: guc [267.671780] Dynamic subtest guc: SUCCESS (0.687s) [267.677985] Starting dynamic subtest: guc_multi_lrc [268.299453] Dynamic subtest guc_multi_lrc: SUCCESS (0.626s) [268.306242] Starting dynamic subtest: guc_hang [268.953510] Dynamic subtest guc_hang: SUCCESS (0.653s) [268.959256] Starting dynamic subtest: late_gt_pm [269.586733] Dynamic subtest late_gt_pm: SUCCESS (0.632s) [269.588333] Subtest live: SUCCESS (73.787s) [269.733704] [164/170] (733s left) dmabuf (all-tests) [269.803962] Starting subtest: all-tests [269.807365] Starting dynamic subtest: sanitycheck [269.865598] Dynamic subtest sanitycheck: SUCCESS (0.061s) [269.871376] Starting dynamic subtest: dma_fence [270.042487] Dynamic subtest dma_fence: SUCCESS (0.176s) [270.052757] Starting dynamic subtest: dma_fence_chain [281.560815] Dynamic subtest dma_fence_chain: SUCCESS (11.517s) [281.567317] Starting dynamic subtest: dma_fence_unwrap [281.622380] Dynamic subtest dma_fence_unwrap: SUCCESS (0.061s) [281.627959] Starting dynamic subtest: dma_resv [281.686424] Dynamic subtest dma_resv: SUCCESS (0.064s) [281.687600] Subtest all-tests: SUCCESS (11.882s) [281.699147] [165/170] (721s left) i915_suspend (basic-s2idle-without-i915) [281.761235] Starting subtest: basic-s2idle-without-i915 [298.730611] Subtest basic-s2idle-without-i915: SUCCESS (2.102s) [304.431507] [166/170] (719s left) i915_suspend (basic-s3-without-i915) [304.500514] Starting subtest: basic-s3-without-i915 [322.974365] Subtest basic-s3-without-i915: SUCCESS (2.080s) [328.845050] [167/170] (717s left) gem_exec_suspend (basic-s0) [328.922343] Starting subtest: basic-S0 [328.923418] Starting dynamic subtest: smem [345.619620] Dynamic subtest smem: SUCCESS (1.225s) [345.620677] Subtest basic-S0: SUCCESS (1.225s) [351.762122] [168/170] (715s left) gem_exec_suspend (basic-s3) [351.835733] Starting subtest: basic-S3 [351.836795] Starting dynamic subtest: smem [369.628727] Dynamic subtest smem: SUCCESS (1.310s) [369.629831] Subtest basic-S3: SUCCESS (1.311s) [376.764837] [169/170] (714s left) kms_chamelium_hpd (common-hpd-after-suspend) [376.894587] Subtest common-hpd-after-suspend: SKIP (0.000s) [376.982236] [170/170] (714s left) kms_pipe_crc_basic (suspend-read-crc) [377.077277] Starting subtest: suspend-read-crc [377.079507] Starting dynamic subtest: pipe-A-VGA-1 [394.808343] Dynamic subtest pipe-A-VGA-1: SUCCESS (1.464s) [394.816272] Starting dynamic subtest: pipe-B-VGA-1 [412.802456] Dynamic subtest pipe-B-VGA-1: SUCCESS (1.456s) [412.811568] Starting dynamic subtest: pipe-C-VGA-1 [430.814752] Dynamic subtest pipe-C-VGA-1: SUCCESS (1.465s) [430.815901] Subtest suspend-read-crc: SUCCESS (4.391s) [434.903689] Closing watchdogs Done.