[49.319297] Initializing watchdogs [49.319468] /dev/watchdog0 [49.329600] [001/171] (960s left) i915_module_load (load) [49.475627] Starting subtest: load [52.100678] Subtest load: SUCCESS (2.625s) [52.130263] [002/171] (957s left) core_auth (basic-auth) [52.280287] Starting subtest: basic-auth [52.281441] Subtest basic-auth: SUCCESS (0.002s) [52.367305] [003/171] (957s left) debugfs_test (read_all_entries) [52.529443] Starting subtest: read_all_entries [52.619720] Subtest read_all_entries: SUCCESS (0.090s) [52.732403] [004/171] (957s left) debugfs_test (basic-hwmon) [52.895910] Starting subtest: basic-hwmon [52.898339] Subtest basic-hwmon: SKIP (0.000s) [53.005660] [005/171] (956s left) fbdev (eof) [53.187704] Starting subtest: eof [53.188915] Subtest eof: SUCCESS (0.000s) [53.248036] [006/171] (956s left) fbdev (info) [53.384085] Starting subtest: info [53.385563] Subtest info: SUCCESS (0.000s) [53.469982] [007/171] (956s left) fbdev (nullptr) [53.618689] Starting subtest: nullptr [53.620054] Subtest nullptr: SUCCESS (0.000s) [53.684827] [008/171] (956s left) fbdev (read) [53.836950] Starting subtest: read [60.947397] Subtest read: SUCCESS (7.077s) [60.984618] [009/171] (949s left) fbdev (write) [61.135860] Starting subtest: write [64.924213] Subtest write: SUCCESS (3.754s) [64.967086] [010/171] (945s left) gem_basic (bad-close) [65.104063] Starting subtest: bad-close [65.106453] Subtest bad-close: SUCCESS (0.000s) [65.183326] [011/171] (944s left) gem_basic (create-close) [65.320217] Starting subtest: create-close [65.322349] Subtest create-close: SUCCESS (0.000s) [65.399623] [012/171] (944s left) gem_basic (create-fd-close) [65.537400] Starting subtest: create-fd-close [65.539405] Subtest create-fd-close: SUCCESS (0.000s) [65.632965] [013/171] (944s left) gem_busy (busy@all-engines) [65.782322] Starting subtest: busy [65.783534] Starting dynamic subtest: all-engines [65.849506] Dynamic subtest all-engines: SUCCESS (0.067s) [65.850631] Subtest busy: SUCCESS (0.068s) [66.251340] [014/171] (943s left) gem_close_race (basic-process) [66.401558] Starting subtest: basic-process [66.465104] Subtest basic-process: SUCCESS (0.063s) [66.667915] [015/171] (943s left) gem_close_race (basic-threads) [66.826336] Starting subtest: basic-threads [68.018717] Subtest basic-threads: SUCCESS (1.192s) [68.238692] [016/171] (941s left) gem_ctx_create (basic) [68.396823] Starting subtest: basic [68.398027] Subtest basic: SUCCESS (0.000s) [68.601811] [017/171] (941s left) gem_ctx_create (basic-files) [68.755578] Starting subtest: basic-files [70.779449] Subtest basic-files: SUCCESS (2.024s) [70.999490] [018/171] (939s left) gem_ctx_exec (basic) [71.139056] Starting subtest: basic [71.143169] Subtest basic: SUCCESS (0.004s) [71.249534] [019/171] (939s left) gem_exec_basic (basic) [71.400109] Starting subtest: basic [71.401233] Starting dynamic subtest: rcs0-smem [71.402262] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [71.414216] Starting dynamic subtest: bcs0-smem [71.415371] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [71.425599] Starting dynamic subtest: vcs0-smem [71.426752] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [71.436881] Starting dynamic subtest: vecs0-smem [71.438311] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [71.439705] Subtest basic: SUCCESS (0.005s) [71.600714] [020/171] (938s left) gem_exec_create (basic) [71.754242] Starting subtest: basic [71.755452] Starting dynamic subtest: smem [73.793127] Dynamic subtest smem: SUCCESS (2.039s) [73.794214] Subtest basic: SUCCESS (2.039s) [74.012421] [021/171] (936s left) gem_exec_fence (basic-busy) [74.315387] Starting subtest: basic-busy [74.316771] Starting dynamic subtest: rcs0 [74.340976] Dynamic subtest rcs0: SUCCESS (0.033s) [74.354075] Starting dynamic subtest: bcs0 [74.367481] Dynamic subtest bcs0: SUCCESS (0.027s) [74.378055] Starting dynamic subtest: vcs0 [74.390403] Dynamic subtest vcs0: SUCCESS (0.023s) [74.401614] Starting dynamic subtest: vecs0 [74.424097] Dynamic subtest vecs0: SUCCESS (0.033s) [74.425398] Subtest basic-busy: SUCCESS (0.117s) [75.018318] [022/171] (935s left) gem_exec_fence (basic-wait) [75.320499] Starting subtest: basic-wait [75.321717] Starting dynamic subtest: rcs0 [75.347547] Dynamic subtest rcs0: SUCCESS (0.035s) [75.362486] Starting dynamic subtest: bcs0 [75.365416] Dynamic subtest bcs0: SUCCESS (0.018s) [75.377485] Starting dynamic subtest: vcs0 [75.383250] Dynamic subtest vcs0: SUCCESS (0.018s) [75.394311] Starting dynamic subtest: vecs0 [75.409610] Dynamic subtest vecs0: SUCCESS (0.026s) [75.410746] Subtest basic-wait: SUCCESS (0.098s) [75.968970] [023/171] (934s left) gem_exec_fence (basic-await) [76.266329] Starting subtest: basic-await [76.267458] Starting dynamic subtest: rcs0 [76.357194] Dynamic subtest rcs0: SUCCESS (0.098s) [76.383874] Starting dynamic subtest: bcs0 [76.483170] Dynamic subtest bcs0: SUCCESS (0.125s) [76.509863] Starting dynamic subtest: vcs0 [76.610741] Dynamic subtest vcs0: SUCCESS (0.127s) [76.637163] Starting dynamic subtest: vecs0 [76.741303] Dynamic subtest vecs0: SUCCESS (0.130s) [76.743277] Subtest basic-await: SUCCESS (0.483s) [77.318969] [024/171] (933s left) gem_exec_fence (nb-await) [77.623676] Starting subtest: nb-await [77.624854] Starting dynamic subtest: rcs0 [77.682385] Dynamic subtest rcs0: SUCCESS (0.067s) [77.702040] Starting dynamic subtest: bcs0 [77.752625] Dynamic subtest bcs0: SUCCESS (0.070s) [77.779199] Starting dynamic subtest: vcs0 [77.827176] Dynamic subtest vcs0: SUCCESS (0.074s) [77.853371] Starting dynamic subtest: vecs0 [77.901542] Dynamic subtest vecs0: SUCCESS (0.073s) [77.903677] Subtest nb-await: SUCCESS (0.287s) [78.485636] [025/171] (931s left) gem_exec_gttfill (basic) [78.631188] Starting subtest: basic [85.806328] Subtest basic: SUCCESS (7.167s) [85.990032] [026/171] (924s left) gem_exec_parallel (engines) [86.207480] Starting subtest: engines [86.208678] Starting dynamic subtest: basic [88.706762] Dynamic subtest basic: SUCCESS (2.476s) [88.719389] Starting dynamic subtest: contexts [92.164720] Dynamic subtest contexts: SUCCESS (3.471s) [92.177700] Starting dynamic subtest: fds [95.902864] Dynamic subtest fds: SUCCESS (3.746s) [95.926630] Starting dynamic subtest: userptr [98.486504] Dynamic subtest userptr: SUCCESS (2.559s) [98.488145] Subtest engines: SUCCESS (12.253s) [98.673821] [027/171] (911s left) gem_exec_store (basic) [98.821385] Starting subtest: basic [98.876735] Subtest basic: SUCCESS (0.055s) [99.087705] [028/171] (911s left) gem_flink_basic (bad-flink) [99.222328] Starting subtest: bad-flink [99.223447] Subtest bad-flink: SUCCESS (0.000s) [99.303219] [029/171] (911s left) gem_flink_basic (bad-open) [99.439196] Starting subtest: bad-open [99.440326] Subtest bad-open: SUCCESS (0.000s) [99.519735] [030/171] (911s left) gem_flink_basic (basic) [99.655368] Starting subtest: basic [99.656562] Subtest basic: SUCCESS (0.000s) [99.752805] [031/171] (910s left) gem_flink_basic (double-flink) [99.889461] Starting subtest: double-flink [99.890538] Subtest double-flink: SUCCESS (0.000s) [99.986488] [032/171] (910s left) gem_flink_basic (flink-lifetime) [100.124453] Starting subtest: flink-lifetime [100.126874] Subtest flink-lifetime: SUCCESS (0.002s) [100.219438] [033/171] (910s left) gem_huc_copy (huc-copy) [100.361473] Starting subtest: huc-copy [100.363700] Subtest huc-copy: SKIP (0.000s) [100.469794] [034/171] (910s left) gem_linear_blits (basic) [100.758756] Starting subtest: basic [100.770764] Subtest basic: SUCCESS (0.012s) [100.886912] [035/171] (909s left) gem_mmap (basic) [101.085571] Starting subtest: basic [101.090537] Subtest basic: SUCCESS (0.001s) [101.192554] [036/171] (909s left) gem_mmap_gtt (basic) [101.321373] Starting subtest: basic [101.322572] Subtest basic: SUCCESS (0.001s) [101.402046] [037/171] (909s left) gem_render_linear_blits (basic) [101.542841] Starting subtest: basic [101.594985] Subtest basic: SUCCESS (0.051s) [101.652827] [038/171] (909s left) gem_render_tiled_blits (basic) [101.799842] Starting subtest: basic [101.859822] Subtest basic: SUCCESS (0.060s) [101.937766] [039/171] (908s left) gem_ringfill (basic-all) [102.207578] Starting subtest: basic-all [103.426822] Subtest basic-all: SUCCESS (1.211s) [103.505327] [040/171] (907s left) gem_softpin (allocator-basic) [103.646772] Starting subtest: allocator-basic [103.808564] Subtest allocator-basic: SUCCESS (0.160s) [103.906326] [041/171] (906s left) gem_softpin (allocator-basic-reserve) [104.059756] Starting subtest: allocator-basic-reserve [104.226092] Subtest allocator-basic-reserve: SUCCESS (0.158s) [104.339188] [042/171] (906s left) gem_softpin (safe-alignment) [104.496228] Starting subtest: safe-alignment [104.514607] Subtest safe-alignment: SUCCESS (0.018s) [104.619782] [043/171] (906s left) gem_sync (basic-all) [104.768582] Starting subtest: basic-all [106.954736] Subtest basic-all: SUCCESS (2.162s) [107.137335] [044/171] (903s left) gem_sync (basic-each) [107.289363] Starting subtest: basic-each [109.537652] Subtest basic-each: SUCCESS (2.248s) [109.765027] [045/171] (901s left) gem_tiled_blits (basic) [110.060885] Starting subtest: basic [110.091179] Subtest basic: SUCCESS (0.031s) [110.220765] [046/171] (900s left) gem_tiled_fence_blits (basic) [110.580886] Starting subtest: basic [110.610035] Subtest basic: SUCCESS (0.029s) [110.734562] [047/171] (900s left) gem_tiled_pread_basic [111.270611] [048/171] (899s left) gem_wait (busy@all-engines) [111.425662] Starting subtest: busy [111.426835] Starting dynamic subtest: all-engines [111.964420] Dynamic subtest all-engines: SUCCESS (0.539s) [111.965560] Subtest busy: SUCCESS (0.539s) [112.221464] [049/171] (898s left) gem_wait (wait@all-engines) [112.382961] Starting subtest: wait [112.384156] Starting dynamic subtest: all-engines [113.426699] Dynamic subtest all-engines: SUCCESS (1.039s) [113.428385] Subtest wait: SUCCESS (1.039s) [113.671210] [050/171] (897s left) i915_getparams_basic (basic-eu-total) [113.812173] Starting subtest: basic-eu-total [113.814202] Subtest basic-eu-total: SUCCESS (0.000s) [113.887095] [051/171] (897s left) i915_getparams_basic (basic-subslice-total) [114.024610] Starting subtest: basic-subslice-total [114.026827] Subtest basic-subslice-total: SUCCESS (0.000s) [114.103258] [052/171] (897s left) i915_hangman (error-state-basic) [114.246342] Starting subtest: error-state-basic [114.261663] Subtest error-state-basic: SUCCESS (0.014s) [114.353901] [053/171] (896s left) i915_pciid [114.570454] [054/171] (896s left) kms_addfb_basic (addfb25-bad-modifier) [114.701472] Starting subtest: addfb25-bad-modifier [114.702520] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [114.836289] [055/171] (896s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [114.975519] Starting subtest: addfb25-framebuffer-vs-set-tiling [114.976662] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [115.103756] [056/171] (896s left) kms_addfb_basic (addfb25-modifier-no-flag) [115.242816] Starting subtest: addfb25-modifier-no-flag [115.243909] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [115.369827] [057/171] (895s left) kms_addfb_basic (addfb25-x-tiled-legacy) [115.507571] Starting subtest: addfb25-x-tiled-legacy [115.508623] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [115.636636] [058/171] (895s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [115.775023] Starting subtest: addfb25-x-tiled-mismatch-legacy [115.776113] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [115.904032] [059/171] (895s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [116.071465] Starting subtest: addfb25-yf-tiled-legacy [116.072543] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [116.169557] [060/171] (895s left) kms_addfb_basic (addfb25-y-tiled-legacy) [116.335851] Starting subtest: addfb25-y-tiled-legacy [116.336990] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [116.419397] [061/171] (894s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [116.586693] Starting subtest: addfb25-y-tiled-small-legacy [116.587910] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [116.686628] [062/171] (894s left) kms_addfb_basic (bad-pitch-0) [116.825494] Starting subtest: bad-pitch-0 [116.826565] Subtest bad-pitch-0: SUCCESS (0.000s) [116.953492] [063/171] (894s left) kms_addfb_basic (bad-pitch-1024) [117.091520] Starting subtest: bad-pitch-1024 [117.092546] Subtest bad-pitch-1024: SUCCESS (0.000s) [117.203269] [064/171] (894s left) kms_addfb_basic (bad-pitch-128) [117.341993] Starting subtest: bad-pitch-128 [117.343109] Subtest bad-pitch-128: SUCCESS (0.000s) [117.469943] [065/171] (894s left) kms_addfb_basic (bad-pitch-256) [117.607337] Starting subtest: bad-pitch-256 [117.608367] Subtest bad-pitch-256: SUCCESS (0.000s) [117.736598] [066/171] (893s left) kms_addfb_basic (bad-pitch-32) [117.875231] Starting subtest: bad-pitch-32 [117.876269] Subtest bad-pitch-32: SUCCESS (0.000s) [118.003743] [067/171] (893s left) kms_addfb_basic (bad-pitch-63) [118.142473] Starting subtest: bad-pitch-63 [118.143517] Subtest bad-pitch-63: SUCCESS (0.000s) [118.269973] [068/171] (893s left) kms_addfb_basic (bad-pitch-65536) [118.416172] Starting subtest: bad-pitch-65536 [118.417232] Subtest bad-pitch-65536: SUCCESS (0.001s) [118.536879] [069/171] (893s left) kms_addfb_basic (bad-pitch-999) [118.674389] Starting subtest: bad-pitch-999 [118.675420] Subtest bad-pitch-999: SUCCESS (0.000s) [118.803727] [070/171] (892s left) kms_addfb_basic (basic) [118.942480] Starting subtest: basic [118.943511] Subtest basic: SUCCESS (0.000s) [119.093466] [071/171] (892s left) kms_addfb_basic (basic-x-tiled-legacy) [119.232354] Starting subtest: basic-x-tiled-legacy [119.233393] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [119.353589] [072/171] (892s left) kms_addfb_basic (basic-y-tiled-legacy) [119.494508] Starting subtest: basic-y-tiled-legacy [119.495622] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [119.620380] [073/171] (892s left) kms_addfb_basic (bo-too-small) [119.787491] Starting subtest: bo-too-small [119.788604] Subtest bo-too-small: SUCCESS (0.000s) [119.886428] [074/171] (891s left) kms_addfb_basic (bo-too-small-due-to-tiling) [120.053350] Starting subtest: bo-too-small-due-to-tiling [120.054452] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [120.154036] [075/171] (891s left) kms_addfb_basic (clobberred-modifier) [120.292348] Starting subtest: clobberred-modifier [120.293405] Subtest clobberred-modifier: SUCCESS (0.000s) [120.419875] [076/171] (891s left) kms_addfb_basic (framebuffer-vs-set-tiling) [120.559229] Starting subtest: framebuffer-vs-set-tiling [120.560292] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [120.708875] [077/171] (891s left) kms_addfb_basic (invalid-get-prop) [120.846649] Starting subtest: invalid-get-prop [120.847690] Subtest invalid-get-prop: SUCCESS (0.000s) [120.969904] [078/171] (890s left) kms_addfb_basic (invalid-get-prop-any) [121.107531] Starting subtest: invalid-get-prop-any [121.108591] Subtest invalid-get-prop-any: SUCCESS (0.000s) [121.237230] [079/171] (890s left) kms_addfb_basic (invalid-set-prop) [121.376418] Starting subtest: invalid-set-prop [121.377452] Subtest invalid-set-prop: SUCCESS (0.000s) [121.503340] [080/171] (890s left) kms_addfb_basic (invalid-set-prop-any) [121.641266] Starting subtest: invalid-set-prop-any [121.642397] Subtest invalid-set-prop-any: SUCCESS (0.000s) [121.770158] [081/171] (890s left) kms_addfb_basic (no-handle) [121.908431] Starting subtest: no-handle [121.909519] Subtest no-handle: SUCCESS (0.000s) [122.036834] [082/171] (889s left) kms_addfb_basic (size-max) [122.203691] Starting subtest: size-max [122.204798] Subtest size-max: SUCCESS (0.000s) [122.303618] [083/171] (889s left) kms_addfb_basic (small-bo) [122.471769] Starting subtest: small-bo [122.472964] Subtest small-bo: SUCCESS (0.000s) [122.570178] [084/171] (889s left) kms_addfb_basic (tile-pitch-mismatch) [122.709915] Starting subtest: tile-pitch-mismatch [122.711148] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [122.836847] [085/171] (889s left) kms_addfb_basic (too-high) [123.007921] Starting subtest: too-high [123.009166] Subtest too-high: SUCCESS (0.001s) [123.103302] [086/171] (888s left) kms_addfb_basic (too-wide) [123.270534] Starting subtest: too-wide [123.271700] Subtest too-wide: SUCCESS (0.000s) [123.369927] [087/171] (888s left) kms_addfb_basic (unused-handle) [123.508562] Starting subtest: unused-handle [123.509618] Subtest unused-handle: SUCCESS (0.000s) [123.636812] [088/171] (888s left) kms_addfb_basic (unused-modifier) [123.775374] Starting subtest: unused-modifier [123.776543] Subtest unused-modifier: SUCCESS (0.000s) [123.903120] [089/171] (888s left) kms_addfb_basic (unused-offsets) [124.031711] Starting subtest: unused-offsets [124.033109] Subtest unused-offsets: SUCCESS (0.000s) [124.170787] [090/171] (887s left) kms_addfb_basic (unused-pitches) [124.308269] Starting subtest: unused-pitches [124.309316] Subtest unused-pitches: SUCCESS (0.000s) [124.436911] [091/171] (887s left) kms_busy (basic) [124.601821] Starting subtest: basic [124.604236] Starting dynamic subtest: flip [125.591344] Dynamic subtest flip: SUCCESS (0.979s) [125.606086] Starting dynamic subtest: modeset [129.452538] Dynamic subtest modeset: SUCCESS (3.870s) [129.453935] Subtest basic: SUCCESS (4.852s) [130.426578] [092/171] (881s left) kms_chamelium_hpd (dp-hpd-fast) [130.909418] Subtest dp-hpd-fast: SKIP (0.000s) [131.786396] [093/171] (880s left) kms_chamelium_edid (dp-edid-read) [132.250519] Subtest dp-edid-read: SKIP (0.000s) [133.130555] [094/171] (879s left) kms_chamelium_frames (dp-crc-fast) [133.610389] Subtest dp-crc-fast: SKIP (0.000s) [134.471544] [095/171] (877s left) kms_chamelium_hpd (hdmi-hpd-fast) [134.953082] Subtest hdmi-hpd-fast: SKIP (0.000s) [135.817059] [096/171] (876s left) kms_chamelium_edid (hdmi-edid-read) [136.302602] Subtest hdmi-edid-read: SKIP (0.000s) [137.163411] [097/171] (875s left) kms_chamelium_frames (hdmi-crc-fast) [137.638214] Subtest hdmi-crc-fast: SKIP (0.000s) [138.503039] [098/171] (873s left) kms_chamelium_hpd (vga-hpd-fast) [138.983385] Subtest vga-hpd-fast: SKIP (0.000s) [139.847328] [099/171] (872s left) kms_chamelium_edid (vga-edid-read) [140.331619] Subtest vga-edid-read: SKIP (0.000s) [141.191374] [100/171] (871s left) kms_prop_blob (basic) [141.334062] Starting subtest: basic [141.335231] Subtest basic: SUCCESS (0.000s) [141.408393] [101/171] (871s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [141.567229] Starting subtest: basic-busy-flip-before-cursor-atomic [141.569254] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [141.639435] [102/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [141.805595] Starting subtest: basic-busy-flip-before-cursor-legacy [141.812707] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [141.889845] [103/171] (870s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [142.056288] Starting subtest: basic-flip-after-cursor-atomic [143.339811] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.283s) [144.314659] [104/171] (868s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [144.481968] Starting subtest: basic-flip-after-cursor-legacy [145.781137] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.293s) [146.745394] [105/171] (865s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [146.912682] Starting subtest: basic-flip-after-cursor-varying-size [148.203187] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.291s) [149.176858] [106/171] (863s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [149.344941] Starting subtest: basic-flip-before-cursor-atomic [150.637079] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.291s) [151.610748] [107/171] (861s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [151.775202] Starting subtest: basic-flip-before-cursor-legacy [153.070385] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.294s) [154.040760] [108/171] (858s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [154.207098] Starting subtest: basic-flip-before-cursor-varying-size [155.490966] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.283s) [156.408451] [109/171] (856s left) kms_flip (basic-flip-vs-dpms) [156.546823] Starting subtest: basic-flip-vs-dpms [156.550048] Starting dynamic subtest: A-eDP1 [159.416839] Dynamic subtest A-eDP1: SUCCESS (2.865s) [159.442316] Starting dynamic subtest: B-eDP1 [161.781258] Dynamic subtest B-eDP1: SUCCESS (2.365s) [161.797763] Starting dynamic subtest: C-eDP1 [164.154971] Dynamic subtest C-eDP1: SUCCESS (2.372s) [164.161250] Subtest basic-flip-vs-dpms: SUCCESS (7.611s) [165.116571] [110/171] (847s left) kms_flip (basic-flip-vs-modeset) [165.261906] Starting subtest: basic-flip-vs-modeset [165.265307] Starting dynamic subtest: A-eDP1 [168.108230] Dynamic subtest A-eDP1: SUCCESS (2.843s) [168.124951] Starting dynamic subtest: B-eDP1 [170.477236] Dynamic subtest B-eDP1: SUCCESS (2.368s) [170.497073] Starting dynamic subtest: C-eDP1 [172.832413] Dynamic subtest C-eDP1: SUCCESS (2.355s) [172.835233] Subtest basic-flip-vs-modeset: SUCCESS (7.574s) [173.755156] [111/171] (839s left) kms_flip (basic-flip-vs-wf_vblank) [173.902385] Starting subtest: basic-flip-vs-wf_vblank [173.905294] Starting dynamic subtest: A-eDP1 [176.219464] Dynamic subtest A-eDP1: SUCCESS (2.314s) [176.235877] Starting dynamic subtest: B-eDP1 [178.506351] Dynamic subtest B-eDP1: SUCCESS (2.286s) [178.523056] Starting dynamic subtest: C-eDP1 [180.810155] Dynamic subtest C-eDP1: SUCCESS (2.302s) [180.816078] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.912s) [182.075355] [112/171] (830s left) kms_flip (basic-plain-flip) [182.220680] Starting subtest: basic-plain-flip [182.223690] Starting dynamic subtest: A-eDP1 [184.254880] Dynamic subtest A-eDP1: SUCCESS (2.031s) [184.271316] Starting dynamic subtest: B-eDP1 [186.222772] Dynamic subtest B-eDP1: SUCCESS (1.967s) [186.239474] Starting dynamic subtest: C-eDP1 [188.206242] Dynamic subtest C-eDP1: SUCCESS (1.982s) [188.209244] Subtest basic-plain-flip: SUCCESS (5.990s) [189.501345] [113/171] (823s left) kms_force_connector_basic (force-connector-state) [189.637383] Starting subtest: force-connector-state [191.341270] Subtest force-connector-state: SUCCESS (1.702s) [191.961804] [114/171] (820s left) kms_force_connector_basic (force-edid) [192.113232] Starting subtest: force-edid [192.363053] Subtest force-edid: SUCCESS (0.251s) [192.494746] [115/171] (820s left) kms_force_connector_basic (force-load-detect) [192.643685] Starting subtest: force-load-detect [192.645854] Subtest force-load-detect: SKIP (0.000s) [192.778370] [116/171] (820s left) kms_force_connector_basic (prune-stale-modes) [192.929509] Starting subtest: prune-stale-modes [193.235772] Subtest prune-stale-modes: SUCCESS (0.305s) [193.361431] [117/171] (819s left) kms_frontbuffer_tracking (basic) [193.541053] Starting subtest: basic [196.860252] Subtest basic: SUCCESS (3.318s) [196.979759] [118/171] (815s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [197.143029] Starting subtest: compare-crc-sanitycheck-xr24 [197.144853] Starting dynamic subtest: pipe-A-eDP-1 [197.824628] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.680s) [197.845530] Starting dynamic subtest: pipe-B-eDP-1 [199.205614] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.374s) [199.225241] Starting dynamic subtest: pipe-C-eDP-1 [200.755536] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.541s) [200.756763] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.609s) [201.716644] [119/171] (811s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [201.884095] Starting subtest: compare-crc-sanitycheck-nv12 [201.886921] Starting dynamic subtest: pipe-A-eDP-1 [202.800166] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.905s) [202.816543] Starting dynamic subtest: pipe-B-eDP-1 [204.241039] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.445s) [204.262939] Starting dynamic subtest: pipe-C-eDP-1 [206.020873] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.772s) [206.029194] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.138s) [206.966567] [120/171] (806s left) kms_pipe_crc_basic (hang-read-crc) [207.136214] Starting subtest: hang-read-crc [207.138057] Starting dynamic subtest: pipe-A-eDP-1 [209.453154] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.315s) [209.473364] Starting dynamic subtest: pipe-B-eDP-1 [212.429414] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.969s) [212.447837] Starting dynamic subtest: pipe-C-eDP-1 [215.722876] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.289s) [215.724143] Subtest hang-read-crc: SUCCESS (8.588s) [216.691987] [121/171] (796s left) kms_pipe_crc_basic (nonblocking-crc) [216.862387] Starting subtest: nonblocking-crc [216.865575] Starting dynamic subtest: pipe-A-eDP-1 [217.716936] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.851s) [217.737414] Starting dynamic subtest: pipe-B-eDP-1 [219.273466] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.551s) [219.293556] Starting dynamic subtest: pipe-C-eDP-1 [220.980084] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.701s) [220.981198] Subtest nonblocking-crc: SUCCESS (4.118s) [221.940628] [122/171] (791s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [222.107399] Starting subtest: nonblocking-crc-frame-sequence [222.110459] Starting dynamic subtest: pipe-A-eDP-1 [222.966641] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.855s) [222.985527] Starting dynamic subtest: pipe-B-eDP-1 [224.535791] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.564s) [224.555346] Starting dynamic subtest: pipe-C-eDP-1 [226.249834] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.708s) [226.251186] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.142s) [227.188900] [123/171] (785s left) kms_pipe_crc_basic (read-crc) [227.357380] Starting subtest: read-crc [227.360498] Starting dynamic subtest: pipe-A-eDP-1 [228.148017] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.787s) [228.168339] Starting dynamic subtest: pipe-B-eDP-1 [229.636575] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.482s) [229.656036] Starting dynamic subtest: pipe-C-eDP-1 [231.279408] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.638s) [231.280537] Subtest read-crc: SUCCESS (3.922s) [232.244895] [124/171] (780s left) kms_pipe_crc_basic (read-crc-frame-sequence) [232.412328] Starting subtest: read-crc-frame-sequence [232.415447] Starting dynamic subtest: pipe-A-eDP-1 [233.205498] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.789s) [233.224383] Starting dynamic subtest: pipe-B-eDP-1 [234.691311] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.480s) [234.709980] Starting dynamic subtest: pipe-C-eDP-1 [236.330448] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.635s) [236.331592] Subtest read-crc-frame-sequence: SUCCESS (3.919s) [237.300661] [125/171] (775s left) kms_psr (primary_page_flip) [237.463925] Starting subtest: primary_page_flip [241.722888] Subtest primary_page_flip: SUCCESS (4.258s) [242.677027] [126/171] (770s left) kms_psr (cursor_plane_move) [242.847503] Starting subtest: cursor_plane_move [247.039840] Subtest cursor_plane_move: SUCCESS (4.191s) [247.989787] [127/171] (765s left) kms_psr (sprite_plane_onoff) [248.159454] Starting subtest: sprite_plane_onoff [252.449524] Subtest sprite_plane_onoff: SUCCESS (4.281s) [253.364251] [128/171] (759s left) kms_psr (primary_mmap_gtt) [253.535415] Starting subtest: primary_mmap_gtt [257.780027] Subtest primary_mmap_gtt: SUCCESS (4.232s) [258.675916] [129/171] (754s left) kms_setmode (basic-clone-single-crtc) [258.807397] Starting subtest: basic-clone-single-crtc [258.810519] Subtest basic-clone-single-crtc: SKIP (0.000s) [258.892483] [130/171] (754s left) i915_pm_backlight (basic-brightness) [259.063252] Starting subtest: basic-brightness [259.184323] Starting dynamic subtest: eDP-1 [259.736372] Dynamic subtest eDP-1: SUCCESS (0.561s) [259.737510] Subtest basic-brightness: SUCCESS (0.674s) [260.662062] [131/171] (752s left) i915_pm_rpm (basic-pci-d3-state) [262.549969] Starting subtest: basic-pci-d3-state [266.963968] Subtest basic-pci-d3-state: SUCCESS (4.409s) [267.162804] [132/171] (746s left) i915_pm_rpm (basic-rte) [267.300645] Starting subtest: basic-rte [273.422757] Subtest basic-rte: SUCCESS (6.120s) [274.891225] [133/171] (738s left) i915_pm_rps (basic-api) [275.028909] Starting subtest: basic-api [275.033143] Subtest basic-api: SUCCESS (0.004s) [275.140096] [134/171] (738s left) prime_self_import (basic-llseek-bad) [275.267525] Starting subtest: basic-llseek-bad [275.272225] Subtest basic-llseek-bad: SUCCESS (0.003s) [275.373420] [135/171] (738s left) prime_self_import (basic-llseek-size) [275.507763] Starting subtest: basic-llseek-size [275.512424] Subtest basic-llseek-size: SUCCESS (0.004s) [275.608171] [136/171] (737s left) prime_self_import (basic-with_fd_dup) [275.744419] Starting subtest: basic-with_fd_dup [275.749149] Subtest basic-with_fd_dup: SUCCESS (0.004s) [275.839672] [137/171] (737s left) prime_self_import (basic-with_one_bo) [275.975992] Starting subtest: basic-with_one_bo [275.981196] Subtest basic-with_one_bo: SUCCESS (0.005s) [276.071536] [138/171] (737s left) prime_self_import (basic-with_one_bo_two_files) [276.208959] Starting subtest: basic-with_one_bo_two_files [276.213390] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [276.306487] [139/171] (737s left) prime_self_import (basic-with_two_bos) [276.442480] Starting subtest: basic-with_two_bos [276.447225] Subtest basic-with_two_bos: SUCCESS (0.004s) [276.556690] [140/171] (737s left) prime_vgem (basic-fence-flip) [276.710732] Starting subtest: basic-fence-flip [277.184908] Subtest basic-fence-flip: SUCCESS (0.474s) [277.309670] [141/171] (736s left) prime_vgem (basic-fence-mmap) [277.460841] Starting subtest: basic-fence-mmap [277.508426] Subtest basic-fence-mmap: SUCCESS (0.047s) [277.607487] [142/171] (736s left) prime_vgem (basic-fence-read) [277.760625] Starting subtest: basic-fence-read [277.833999] Subtest basic-fence-read: SUCCESS (0.073s) [277.940890] [143/171] (735s left) prime_vgem (basic-gtt) [278.093615] Starting subtest: basic-gtt [278.116932] Subtest basic-gtt: SUCCESS (0.023s) [278.206977] [144/171] (735s left) prime_vgem (basic-read) [278.360395] Starting subtest: basic-read [278.395748] Subtest basic-read: SUCCESS (0.035s) [278.490040] [145/171] (735s left) prime_vgem (basic-write) [278.643868] Starting subtest: basic-write [278.685546] Subtest basic-write: SUCCESS (0.042s) [278.773351] [146/171] (735s left) vgem_basic (setversion) [278.919554] Starting subtest: setversion [278.921965] Subtest setversion: SUCCESS (0.000s) [278.990419] [147/171] (734s left) vgem_basic (create) [279.136323] Starting subtest: create [279.137456] Subtest create: SUCCESS (0.000s) [279.206855] [148/171] (734s left) vgem_basic (debugfs) [279.352940] Starting subtest: debugfs [279.354174] Subtest debugfs: SUCCESS (0.001s) [279.422571] [149/171] (734s left) vgem_basic (dmabuf-export) [279.586931] Starting subtest: dmabuf-export [279.610139] Subtest dmabuf-export: SUCCESS (0.021s) [279.711866] [150/171] (734s left) vgem_basic (dmabuf-fence) [279.852907] Starting subtest: dmabuf-fence [279.854216] Subtest dmabuf-fence: SUCCESS (0.001s) [279.928062] [151/171] (734s left) vgem_basic (dmabuf-fence-before) [280.088790] Starting subtest: dmabuf-fence-before [280.089981] Subtest dmabuf-fence-before: SUCCESS (0.001s) [280.157452] [152/171] (733s left) vgem_basic (dmabuf-mmap) [280.305193] Starting subtest: dmabuf-mmap [280.329889] Subtest dmabuf-mmap: SUCCESS (0.026s) [280.407461] [153/171] (733s left) vgem_basic (mmap) [280.555430] Starting subtest: mmap [280.570274] Subtest mmap: SUCCESS (0.017s) [280.640786] [154/171] (733s left) vgem_basic (second-client) [280.788827] Starting subtest: second-client [280.822413] Subtest second-client: SUCCESS (0.033s) [280.891562] [155/171] (733s left) vgem_basic (sysfs) [281.037518] Starting subtest: sysfs [281.038765] Subtest sysfs: SUCCESS (0.001s) [281.107485] [156/171] (732s left) core_hotunplug (unbind-rebind) [281.287387] Starting subtest: unbind-rebind [283.734556] Subtest unbind-rebind: SUCCESS (2.447s) [283.793330] [157/171] (730s left) vgem_basic (unload) [283.930276] Starting subtest: unload [312.940027] Subtest unload: SUCCESS (28.974s) [313.042124] [158/171] (701s left) i915_module_load (reload) [313.179880] Starting subtest: reload [316.699475] Subtest reload: SUCCESS (3.519s) [316.730907] [159/171] (697s left) gem_lmem_swapping (basic) [316.885510] Subtest basic: SKIP (0.000s) [316.934543] [160/171] (697s left) gem_lmem_swapping (parallel-random-engines) [317.079075] Subtest parallel-random-engines: SKIP (0.000s) [317.134347] [161/171] (697s left) gem_lmem_swapping (random-engines) [317.276230] Subtest random-engines: SKIP (0.000s) [317.317376] [162/171] (696s left) gem_lmem_swapping (verify-random) [317.461635] Subtest verify-random: SKIP (0.000s) [317.517912] [163/171] (696s left) i915_pm_rpm (module-reload) [330.041770] Starting subtest: module-reload [364.906699] Subtest module-reload: SUCCESS (34.857s) [364.932135] [164/171] (649s left) i915_selftest (live) [365.077461] Starting subtest: live [365.078845] Starting dynamic subtest: sanitycheck [366.870368] Dynamic subtest sanitycheck: SUCCESS (1.783s) [366.884621] Starting dynamic subtest: uncore [368.666434] Dynamic subtest uncore: SUCCESS (1.792s) [368.680714] Starting dynamic subtest: workarounds [370.660481] Dynamic subtest workarounds: SUCCESS (1.990s) [370.674985] Starting dynamic subtest: gt_engines [372.968548] Dynamic subtest gt_engines: SUCCESS (2.304s) [372.982745] Starting dynamic subtest: gt_timelines [385.467685] Dynamic subtest gt_timelines: SUCCESS (12.495s) [385.482142] Starting dynamic subtest: gt_contexts [387.358442] Dynamic subtest gt_contexts: SUCCESS (1.887s) [387.372978] Starting dynamic subtest: gt_lrc [391.778684] Dynamic subtest gt_lrc: SUCCESS (4.416s) [391.793168] Starting dynamic subtest: gt_mocs [393.694346] Dynamic subtest gt_mocs: SUCCESS (1.912s) [393.708807] Starting dynamic subtest: gt_pm [398.709427] Dynamic subtest gt_pm: SUCCESS (5.012s) [398.724048] Starting dynamic subtest: gt_heartbeat [400.595687] Dynamic subtest gt_heartbeat: SUCCESS (1.883s) [400.610277] Starting dynamic subtest: gt_tlb [403.669587] Dynamic subtest gt_tlb: SUCCESS (3.070s) [403.684144] Starting dynamic subtest: requests [411.279530] Dynamic subtest requests: SUCCESS (7.606s) [411.293903] Starting dynamic subtest: migrate [415.806662] Dynamic subtest migrate: SUCCESS (4.523s) [415.821269] Starting dynamic subtest: active [417.630217] Dynamic subtest active: SUCCESS (1.820s) [417.644932] Starting dynamic subtest: objects [420.958305] Dynamic subtest objects: SUCCESS (3.325s) [420.972919] Starting dynamic subtest: mman [427.849506] Dynamic subtest mman: SUCCESS (6.888s) [427.864087] Starting dynamic subtest: dmabuf [429.671535] Dynamic subtest dmabuf: SUCCESS (1.818s) [429.686440] Starting dynamic subtest: vma [431.518438] Dynamic subtest vma: SUCCESS (1.844s) [431.533023] Starting dynamic subtest: coherency [435.997299] Dynamic subtest coherency: SUCCESS (4.475s) [436.012231] Starting dynamic subtest: gtt [445.296302] Dynamic subtest gtt: SUCCESS (9.296s) [445.310837] Starting dynamic subtest: gem [447.216361] Dynamic subtest gem: SUCCESS (1.916s) [447.231160] Starting dynamic subtest: evict [450.974709] Dynamic subtest evict: SUCCESS (3.755s) [450.989522] Starting dynamic subtest: hugepages [457.858405] Dynamic subtest hugepages: SUCCESS (6.880s) [457.873475] Starting dynamic subtest: gem_contexts [473.029463] Dynamic subtest gem_contexts: SUCCESS (15.168s) [473.044267] Starting dynamic subtest: client [474.903455] Dynamic subtest client: SUCCESS (1.870s) [474.918404] Starting dynamic subtest: gem_migrate [476.695532] Dynamic subtest gem_migrate: SUCCESS (1.789s) [476.710787] Starting dynamic subtest: reset [482.109379] Dynamic subtest reset: SUCCESS (5.411s) [482.124483] Starting dynamic subtest: memory_region [483.927389] Dynamic subtest memory_region: SUCCESS (1.815s) [483.942740] Starting dynamic subtest: hangcheck [508.286246] Dynamic subtest hangcheck: SUCCESS (24.356s) [508.301410] Starting dynamic subtest: execlists [518.397754] Dynamic subtest execlists: SUCCESS (10.108s) [518.412019] Starting dynamic subtest: ring_submission [520.204064] Dynamic subtest ring_submission: SUCCESS (1.803s) [520.219247] Starting dynamic subtest: perf [522.137394] Dynamic subtest perf: SUCCESS (1.930s) [522.152661] Starting dynamic subtest: slpc [523.986844] Dynamic subtest slpc: SUCCESS (1.846s) [524.002329] Starting dynamic subtest: guc [525.845571] Dynamic subtest guc: SUCCESS (1.856s) [525.860561] Starting dynamic subtest: guc_multi_lrc [527.631214] Dynamic subtest guc_multi_lrc: SUCCESS (1.782s) [527.646404] Starting dynamic subtest: guc_hang [529.414332] Dynamic subtest guc_hang: SUCCESS (1.780s) [529.429603] Starting dynamic subtest: late_gt_pm [531.231575] Dynamic subtest late_gt_pm: SUCCESS (1.814s) [531.232958] Subtest live: SUCCESS (166.147s) [531.434110] [165/171] (482s left) dmabuf (all-tests) [531.569886] Starting subtest: all-tests [531.571311] Starting dynamic subtest: sanitycheck [531.641822] Dynamic subtest sanitycheck: SUCCESS (0.070s) [531.661787] Starting dynamic subtest: dma_fence [531.824735] Dynamic subtest dma_fence: SUCCESS (0.179s) [531.841849] Starting dynamic subtest: dma_fence_chain [549.563964] Dynamic subtest dma_fence_chain: SUCCESS (17.727s) [549.576373] Starting dynamic subtest: dma_fence_unwrap [549.619682] Dynamic subtest dma_fence_unwrap: SUCCESS (0.060s) [549.637449] Starting dynamic subtest: dma_resv [549.691587] Dynamic subtest dma_resv: SUCCESS (0.068s) [549.693651] Subtest all-tests: SUCCESS (18.123s) [549.724714] [166/171] (464s left) i915_suspend (basic-s2idle-without-i915) [549.853087] Starting subtest: basic-s2idle-without-i915 [573.888652] Subtest basic-s2idle-without-i915: SUCCESS (8.443s) [574.180029] [167/171] (455s left) i915_suspend (basic-s3-without-i915) [574.317506] Starting subtest: basic-s3-without-i915 [598.051507] Subtest basic-s3-without-i915: SUCCESS (7.212s) [598.345310] [168/171] (448s left) gem_exec_suspend (basic-s0) [598.502041] Starting subtest: basic-S0 [598.503272] Starting dynamic subtest: smem [617.996099] Dynamic subtest smem: SUCCESS (3.825s) [617.997287] Subtest basic-S0: SUCCESS (3.827s) [618.147556] [169/171] (444s left) gem_exec_suspend (basic-s3) [618.291945] Starting subtest: basic-S3 [618.293124] Starting dynamic subtest: smem [637.004327] Dynamic subtest smem: SUCCESS (3.659s) [637.005462] Subtest basic-S3: SUCCESS (3.661s) [637.156717] [170/171] (440s left) kms_chamelium_hpd (common-hpd-after-suspend) [637.651453] Subtest common-hpd-after-suspend: SKIP (0.000s) [638.530572] [171/171] (438s left) kms_pipe_crc_basic (suspend-read-crc) [638.691947] Starting subtest: suspend-read-crc [638.696421] Starting dynamic subtest: pipe-A-eDP-1 [655.960946] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.428s) [655.983284] Starting dynamic subtest: pipe-B-eDP-1 [673.951834] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.109s) [673.974437] Starting dynamic subtest: pipe-C-eDP-1 [692.921710] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.234s) [692.922848] Subtest suspend-read-crc: SUCCESS (8.789s) [693.828078] Closing watchdogs Done.