[46.651964] Initializing watchdogs [46.652167] /dev/watchdog0 [46.662306] [001/171] (960s left) i915_module_load (load) [46.802397] Starting subtest: load [49.417838] Subtest load: SUCCESS (2.615s) [49.445887] [002/171] (957s left) core_auth (basic-auth) [49.596661] Starting subtest: basic-auth [49.597770] Subtest basic-auth: SUCCESS (0.002s) [49.693356] [003/171] (957s left) debugfs_test (read_all_entries) [49.842715] Starting subtest: read_all_entries [49.917799] Subtest read_all_entries: SUCCESS (0.075s) [50.031538] [004/171] (957s left) debugfs_test (basic-hwmon) [50.174628] Starting subtest: basic-hwmon [50.176761] Subtest basic-hwmon: SKIP (0.000s) [50.284772] [005/171] (956s left) fbdev (eof) [50.468219] Starting subtest: eof [50.469376] Subtest eof: SUCCESS (0.001s) [50.532853] [006/171] (956s left) fbdev (info) [50.690580] Starting subtest: info [50.691874] Subtest info: SUCCESS (0.000s) [50.768133] [007/171] (956s left) fbdev (nullptr) [50.917277] Starting subtest: nullptr [50.918510] Subtest nullptr: SUCCESS (0.000s) [50.985630] [008/171] (956s left) fbdev (read) [51.133456] Starting subtest: read [58.232371] Subtest read: SUCCESS (7.064s) [58.265772] [009/171] (949s left) fbdev (write) [58.418149] Starting subtest: write [62.198967] Subtest write: SUCCESS (3.746s) [62.232291] [010/171] (945s left) gem_basic (bad-close) [62.366639] Starting subtest: bad-close [62.368777] Subtest bad-close: SUCCESS (0.000s) [62.448933] [011/171] (944s left) gem_basic (create-close) [62.586428] Starting subtest: create-close [62.588485] Subtest create-close: SUCCESS (0.000s) [62.682582] [012/171] (944s left) gem_basic (create-fd-close) [62.819503] Starting subtest: create-fd-close [62.821505] Subtest create-fd-close: SUCCESS (0.000s) [62.915586] [013/171] (944s left) gem_busy (busy@all-engines) [63.072992] Starting subtest: busy [63.074157] Starting dynamic subtest: all-engines [63.136575] Dynamic subtest all-engines: SUCCESS (0.064s) [63.137647] Subtest busy: SUCCESS (0.064s) [63.533715] [014/171] (943s left) gem_close_race (basic-process) [63.694890] Starting subtest: basic-process [63.760285] Subtest basic-process: SUCCESS (0.065s) [63.967186] [015/171] (943s left) gem_close_race (basic-threads) [64.126338] Starting subtest: basic-threads [65.282220] Subtest basic-threads: SUCCESS (1.156s) [65.491051] [016/171] (942s left) gem_ctx_create (basic) [65.651467] Starting subtest: basic [65.652567] Subtest basic: SUCCESS (0.000s) [65.850947] [017/171] (941s left) gem_ctx_create (basic-files) [66.004610] Starting subtest: basic-files [68.028872] Subtest basic-files: SUCCESS (2.025s) [68.251255] [018/171] (939s left) gem_ctx_exec (basic) [68.392626] Starting subtest: basic [68.398100] Subtest basic: SUCCESS (0.005s) [68.499705] [019/171] (939s left) gem_exec_basic (basic) [68.646945] Starting subtest: basic [68.648131] Starting dynamic subtest: rcs0-smem [68.649162] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [68.660723] Starting dynamic subtest: bcs0-smem [68.661926] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [68.672381] Starting dynamic subtest: vcs0-smem [68.673748] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [68.684383] Starting dynamic subtest: vecs0-smem [68.685692] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [68.686990] Subtest basic: SUCCESS (0.006s) [68.852846] [020/171] (938s left) gem_exec_create (basic) [69.004420] Starting subtest: basic [69.005550] Starting dynamic subtest: smem [71.072841] Dynamic subtest smem: SUCCESS (2.045s) [71.074185] Subtest basic: SUCCESS (2.046s) [71.276856] [021/171] (936s left) gem_exec_fence (basic-busy) [71.582711] Starting subtest: basic-busy [71.584277] Starting dynamic subtest: rcs0 [71.608151] Dynamic subtest rcs0: SUCCESS (0.035s) [71.621460] Starting dynamic subtest: bcs0 [71.646328] Dynamic subtest bcs0: SUCCESS (0.037s) [71.661962] Starting dynamic subtest: vcs0 [71.664367] Dynamic subtest vcs0: SUCCESS (0.019s) [71.675598] Starting dynamic subtest: vecs0 [71.690439] Dynamic subtest vecs0: SUCCESS (0.026s) [71.691631] Subtest basic-busy: SUCCESS (0.118s) [72.301254] [022/171] (935s left) gem_exec_fence (basic-wait) [72.603155] Starting subtest: basic-wait [72.604480] Starting dynamic subtest: rcs0 [72.633353] Dynamic subtest rcs0: SUCCESS (0.037s) [72.648930] Starting dynamic subtest: bcs0 [72.653290] Dynamic subtest bcs0: SUCCESS (0.021s) [72.665263] Starting dynamic subtest: vcs0 [72.683675] Dynamic subtest vcs0: SUCCESS (0.030s) [72.695282] Starting dynamic subtest: vecs0 [72.708387] Dynamic subtest vecs0: SUCCESS (0.024s) [72.709581] Subtest basic-wait: SUCCESS (0.114s) [73.251110] [023/171] (934s left) gem_exec_fence (basic-await) [73.552787] Starting subtest: basic-await [73.553978] Starting dynamic subtest: rcs0 [73.647656] Dynamic subtest rcs0: SUCCESS (0.101s) [73.672423] Starting dynamic subtest: bcs0 [73.759487] Dynamic subtest bcs0: SUCCESS (0.113s) [73.787235] Starting dynamic subtest: vcs0 [73.898917] Dynamic subtest vcs0: SUCCESS (0.130s) [73.925467] Starting dynamic subtest: vecs0 [74.042691] Dynamic subtest vecs0: SUCCESS (0.143s) [74.044700] Subtest basic-await: SUCCESS (0.490s) [74.601133] [024/171] (933s left) gem_exec_fence (nb-await) [74.889499] Starting subtest: nb-await [74.890765] Starting dynamic subtest: rcs0 [74.948126] Dynamic subtest rcs0: SUCCESS (0.066s) [74.966233] Starting dynamic subtest: bcs0 [75.017450] Dynamic subtest bcs0: SUCCESS (0.069s) [75.044538] Starting dynamic subtest: vcs0 [75.097835] Dynamic subtest vcs0: SUCCESS (0.080s) [75.124865] Starting dynamic subtest: vecs0 [75.173770] Dynamic subtest vecs0: SUCCESS (0.075s) [75.176520] Subtest nb-await: SUCCESS (0.292s) [75.751131] [025/171] (932s left) gem_exec_gttfill (basic) [75.905234] Starting subtest: basic [82.007424] Subtest basic: SUCCESS (6.093s) [82.187975] [026/171] (925s left) gem_exec_parallel (engines) [82.401192] Starting subtest: engines [82.402376] Starting dynamic subtest: basic [84.968130] Dynamic subtest basic: SUCCESS (2.543s) [84.993480] Starting dynamic subtest: contexts [88.494957] Dynamic subtest contexts: SUCCESS (3.541s) [88.508416] Starting dynamic subtest: fds [92.549443] Dynamic subtest fds: SUCCESS (4.062s) [92.578401] Starting dynamic subtest: userptr [95.177273] Dynamic subtest userptr: SUCCESS (2.603s) [95.179311] Subtest engines: SUCCESS (12.751s) [95.368726] [027/171] (912s left) gem_exec_store (basic) [95.527628] Starting subtest: basic [95.591584] Subtest basic: SUCCESS (0.065s) [95.803301] [028/171] (912s left) gem_flink_basic (bad-flink) [95.938242] Starting subtest: bad-flink [95.939578] Subtest bad-flink: SUCCESS (0.000s) [96.020309] [029/171] (911s left) gem_flink_basic (bad-open) [96.158709] Starting subtest: bad-open [96.160030] Subtest bad-open: SUCCESS (0.000s) [96.235730] [030/171] (911s left) gem_flink_basic (basic) [96.373783] Starting subtest: basic [96.375078] Subtest basic: SUCCESS (0.000s) [96.469318] [031/171] (911s left) gem_flink_basic (double-flink) [96.606341] Starting subtest: double-flink [96.607490] Subtest double-flink: SUCCESS (0.000s) [96.703288] [032/171] (911s left) gem_flink_basic (flink-lifetime) [96.842568] Starting subtest: flink-lifetime [96.843955] Subtest flink-lifetime: SUCCESS (0.002s) [96.935755] [033/171] (911s left) gem_huc_copy (huc-copy) [97.079359] Starting subtest: huc-copy [97.081537] Subtest huc-copy: SKIP (0.000s) [97.202837] [034/171] (910s left) gem_linear_blits (basic) [97.479433] Starting subtest: basic [97.491225] Subtest basic: SUCCESS (0.012s) [97.637283] [035/171] (910s left) gem_mmap (basic) [97.837202] Starting subtest: basic [97.848303] Subtest basic: SUCCESS (0.001s) [97.935843] [036/171] (910s left) gem_mmap_gtt (basic) [98.075848] Starting subtest: basic [98.077177] Subtest basic: SUCCESS (0.001s) [98.152454] [037/171] (909s left) gem_render_linear_blits (basic) [98.293193] Starting subtest: basic [98.343277] Subtest basic: SUCCESS (0.050s) [98.418124] [038/171] (909s left) gem_render_tiled_blits (basic) [98.562218] Starting subtest: basic [98.622107] Subtest basic: SUCCESS (0.060s) [98.689241] [039/171] (909s left) gem_ringfill (basic-all) [98.993169] Starting subtest: basic-all [100.272156] Subtest basic-all: SUCCESS (1.270s) [100.370449] [040/171] (907s left) gem_softpin (allocator-basic) [100.519569] Starting subtest: allocator-basic [100.691837] Subtest allocator-basic: SUCCESS (0.163s) [100.805575] [041/171] (907s left) gem_softpin (allocator-basic-reserve) [100.956535] Starting subtest: allocator-basic-reserve [101.130311] Subtest allocator-basic-reserve: SUCCESS (0.165s) [101.237332] [042/171] (907s left) gem_softpin (safe-alignment) [101.389245] Starting subtest: safe-alignment [101.408135] Subtest safe-alignment: SUCCESS (0.019s) [101.536113] [043/171] (906s left) gem_sync (basic-all) [101.693737] Starting subtest: basic-all [103.999090] Subtest basic-all: SUCCESS (2.280s) [104.188128] [044/171] (904s left) gem_sync (basic-each) [104.340354] Starting subtest: basic-each [106.587261] Subtest basic-each: SUCCESS (2.247s) [106.818396] [045/171] (901s left) gem_tiled_blits (basic) [107.100608] Starting subtest: basic [107.130423] Subtest basic: SUCCESS (0.030s) [107.253261] [046/171] (901s left) gem_tiled_fence_blits (basic) [107.593498] Starting subtest: basic [107.621857] Subtest basic: SUCCESS (0.028s) [107.750169] [047/171] (900s left) gem_tiled_pread_basic [108.303326] [048/171] (900s left) gem_wait (busy@all-engines) [108.468704] Starting subtest: busy [108.470076] Starting dynamic subtest: all-engines [109.019815] Dynamic subtest all-engines: SUCCESS (0.551s) [109.021057] Subtest busy: SUCCESS (0.551s) [109.270338] [049/171] (899s left) gem_wait (wait@all-engines) [109.430965] Starting subtest: wait [109.432227] Starting dynamic subtest: all-engines [110.464092] Dynamic subtest all-engines: SUCCESS (1.029s) [110.466099] Subtest wait: SUCCESS (1.029s) [110.703644] [050/171] (897s left) i915_getparams_basic (basic-eu-total) [110.841601] Starting subtest: basic-eu-total [110.843788] Subtest basic-eu-total: SUCCESS (0.000s) [110.919512] [051/171] (897s left) i915_getparams_basic (basic-subslice-total) [111.055916] Starting subtest: basic-subslice-total [111.058340] Subtest basic-subslice-total: SUCCESS (0.000s) [111.136153] [052/171] (897s left) i915_hangman (error-state-basic) [111.289642] Starting subtest: error-state-basic [111.300913] Subtest error-state-basic: SUCCESS (0.011s) [111.386426] [053/171] (897s left) i915_pciid [111.603147] [054/171] (897s left) kms_addfb_basic (addfb25-bad-modifier) [111.743419] Starting subtest: addfb25-bad-modifier [111.744505] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [111.885770] [055/171] (896s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [112.023757] Starting subtest: addfb25-framebuffer-vs-set-tiling [112.025040] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [112.153709] [056/171] (896s left) kms_addfb_basic (addfb25-modifier-no-flag) [112.292510] Starting subtest: addfb25-modifier-no-flag [112.293623] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [112.419597] [057/171] (896s left) kms_addfb_basic (addfb25-x-tiled-legacy) [112.559126] Starting subtest: addfb25-x-tiled-legacy [112.560231] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [112.686327] [058/171] (896s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [112.817982] Starting subtest: addfb25-x-tiled-mismatch-legacy [112.819211] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [112.936100] [059/171] (895s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [113.103559] Starting subtest: addfb25-yf-tiled-legacy [113.104707] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [113.202520] [060/171] (895s left) kms_addfb_basic (addfb25-y-tiled-legacy) [113.370261] Starting subtest: addfb25-y-tiled-legacy [113.371379] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [113.469609] [061/171] (895s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [113.638145] Starting subtest: addfb25-y-tiled-small-legacy [113.639324] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [113.735619] [062/171] (895s left) kms_addfb_basic (bad-pitch-0) [113.868120] Starting subtest: bad-pitch-0 [113.869205] Subtest bad-pitch-0: SUCCESS (0.000s) [113.986154] [063/171] (894s left) kms_addfb_basic (bad-pitch-1024) [114.125777] Starting subtest: bad-pitch-1024 [114.127127] Subtest bad-pitch-1024: SUCCESS (0.000s) [114.252666] [064/171] (894s left) kms_addfb_basic (bad-pitch-128) [114.392413] Starting subtest: bad-pitch-128 [114.393512] Subtest bad-pitch-128: SUCCESS (0.000s) [114.519766] [065/171] (894s left) kms_addfb_basic (bad-pitch-256) [114.658689] Starting subtest: bad-pitch-256 [114.659869] Subtest bad-pitch-256: SUCCESS (0.000s) [114.786211] [066/171] (894s left) kms_addfb_basic (bad-pitch-32) [114.924897] Starting subtest: bad-pitch-32 [114.925984] Subtest bad-pitch-32: SUCCESS (0.000s) [115.052869] [067/171] (893s left) kms_addfb_basic (bad-pitch-63) [115.189885] Starting subtest: bad-pitch-63 [115.191338] Subtest bad-pitch-63: SUCCESS (0.000s) [115.319186] [068/171] (893s left) kms_addfb_basic (bad-pitch-65536) [115.458178] Starting subtest: bad-pitch-65536 [115.459268] Subtest bad-pitch-65536: SUCCESS (0.000s) [115.586329] [069/171] (893s left) kms_addfb_basic (bad-pitch-999) [115.725661] Starting subtest: bad-pitch-999 [115.726746] Subtest bad-pitch-999: SUCCESS (0.000s) [115.853100] [070/171] (893s left) kms_addfb_basic (basic) [115.990549] Starting subtest: basic [115.991728] Subtest basic: SUCCESS (0.000s) [116.119864] [071/171] (892s left) kms_addfb_basic (basic-x-tiled-legacy) [116.258387] Starting subtest: basic-x-tiled-legacy [116.259481] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [116.386433] [072/171] (892s left) kms_addfb_basic (basic-y-tiled-legacy) [116.526487] Starting subtest: basic-y-tiled-legacy [116.527586] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [116.652993] [073/171] (892s left) kms_addfb_basic (bo-too-small) [116.820185] Starting subtest: bo-too-small [116.821412] Subtest bo-too-small: SUCCESS (0.000s) [116.919192] [074/171] (892s left) kms_addfb_basic (bo-too-small-due-to-tiling) [117.085676] Starting subtest: bo-too-small-due-to-tiling [117.086995] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [117.185768] [075/171] (892s left) kms_addfb_basic (clobberred-modifier) [117.322281] Starting subtest: clobberred-modifier [117.323401] Subtest clobberred-modifier: SUCCESS (0.000s) [117.452685] [076/171] (891s left) kms_addfb_basic (framebuffer-vs-set-tiling) [117.592237] Starting subtest: framebuffer-vs-set-tiling [117.593345] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [117.741321] [077/171] (891s left) kms_addfb_basic (invalid-get-prop) [117.879706] Starting subtest: invalid-get-prop [117.880791] Subtest invalid-get-prop: SUCCESS (0.000s) [118.002464] [078/171] (891s left) kms_addfb_basic (invalid-get-prop-any) [118.141878] Starting subtest: invalid-get-prop-any [118.143063] Subtest invalid-get-prop-any: SUCCESS (0.000s) [118.269577] [079/171] (891s left) kms_addfb_basic (invalid-set-prop) [118.414950] Starting subtest: invalid-set-prop [118.416253] Subtest invalid-set-prop: SUCCESS (0.000s) [118.554063] [080/171] (890s left) kms_addfb_basic (invalid-set-prop-any) [118.692708] Starting subtest: invalid-set-prop-any [118.693916] Subtest invalid-set-prop-any: SUCCESS (0.000s) [118.819862] [081/171] (890s left) kms_addfb_basic (no-handle) [118.957148] Starting subtest: no-handle [118.958294] Subtest no-handle: SUCCESS (0.000s) [119.086231] [082/171] (890s left) kms_addfb_basic (size-max) [119.253143] Starting subtest: size-max [119.254487] Subtest size-max: SUCCESS (0.000s) [119.352726] [083/171] (890s left) kms_addfb_basic (small-bo) [119.520463] Starting subtest: small-bo [119.521779] Subtest small-bo: SUCCESS (0.000s) [119.619288] [084/171] (889s left) kms_addfb_basic (tile-pitch-mismatch) [119.757777] Starting subtest: tile-pitch-mismatch [119.758931] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [119.886278] [085/171] (889s left) kms_addfb_basic (too-high) [120.053450] Starting subtest: too-high [120.054648] Subtest too-high: SUCCESS (0.001s) [120.152285] [086/171] (889s left) kms_addfb_basic (too-wide) [120.318193] Starting subtest: too-wide [120.319373] Subtest too-wide: SUCCESS (0.000s) [120.419399] [087/171] (889s left) kms_addfb_basic (unused-handle) [120.556622] Starting subtest: unused-handle [120.557989] Subtest unused-handle: SUCCESS (0.000s) [120.686455] [088/171] (888s left) kms_addfb_basic (unused-modifier) [120.824212] Starting subtest: unused-modifier [120.825565] Subtest unused-modifier: SUCCESS (0.000s) [120.952431] [089/171] (888s left) kms_addfb_basic (unused-offsets) [121.090446] Starting subtest: unused-offsets [121.091618] Subtest unused-offsets: SUCCESS (0.000s) [121.219990] [090/171] (888s left) kms_addfb_basic (unused-pitches) [121.357362] Starting subtest: unused-pitches [121.358535] Subtest unused-pitches: SUCCESS (0.000s) [121.487221] [091/171] (888s left) kms_busy (basic) [121.661145] Starting subtest: basic [121.662407] Starting dynamic subtest: flip [122.753284] Dynamic subtest flip: SUCCESS (1.091s) [122.771961] Starting dynamic subtest: modeset [126.663091] Dynamic subtest modeset: SUCCESS (3.903s) [126.664349] Subtest basic: SUCCESS (4.997s) [127.633616] [092/171] (882s left) kms_chamelium_hpd (dp-hpd-fast) [128.115524] Subtest dp-hpd-fast: SKIP (0.000s) [128.991526] [093/171] (880s left) kms_chamelium_edid (dp-edid-read) [129.472786] Subtest dp-edid-read: SKIP (0.000s) [130.334256] [094/171] (879s left) kms_chamelium_frames (dp-crc-fast) [130.815170] Subtest dp-crc-fast: SKIP (0.000s) [131.684293] [095/171] (878s left) kms_chamelium_hpd (hdmi-hpd-fast) [132.178445] Subtest hdmi-hpd-fast: SKIP (0.000s) [133.087256] [096/171] (876s left) kms_chamelium_edid (hdmi-edid-read) [133.565557] Subtest hdmi-edid-read: SKIP (0.000s) [134.428901] [097/171] (875s left) kms_chamelium_frames (hdmi-crc-fast) [134.910443] Subtest hdmi-crc-fast: SKIP (0.000s) [135.772110] [098/171] (874s left) kms_chamelium_hpd (vga-hpd-fast) [136.250931] Subtest vga-hpd-fast: SKIP (0.000s) [137.116708] [099/171] (872s left) kms_chamelium_edid (vga-edid-read) [137.598825] Subtest vga-edid-read: SKIP (0.000s) [138.482565] [100/171] (871s left) kms_prop_blob (basic) [138.621435] Starting subtest: basic [138.622661] Subtest basic: SUCCESS (0.000s) [138.703302] [101/171] (871s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [138.879299] Starting subtest: basic-busy-flip-before-cursor-atomic [138.881486] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [138.947882] [102/171] (871s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [139.113235] Starting subtest: basic-busy-flip-before-cursor-legacy [139.115435] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [139.202949] [103/171] (870s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [139.368106] Starting subtest: basic-flip-after-cursor-atomic [140.647648] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.279s) [141.581458] [104/171] (868s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [141.748615] Starting subtest: basic-flip-after-cursor-legacy [143.022913] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.274s) [143.951324] [105/171] (866s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [144.117485] Starting subtest: basic-flip-after-cursor-varying-size [145.409961] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.293s) [146.383213] [106/171] (863s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [146.557902] Starting subtest: basic-flip-before-cursor-atomic [147.842810] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.285s) [148.815525] [107/171] (861s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [148.982453] Starting subtest: basic-flip-before-cursor-legacy [150.258382] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.275s) [151.185413] [108/171] (858s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [151.353876] Starting subtest: basic-flip-before-cursor-varying-size [152.633540] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.280s) [153.550944] [109/171] (856s left) kms_flip (basic-flip-vs-dpms) [153.697542] Starting subtest: basic-flip-vs-dpms [153.700718] Starting dynamic subtest: A-eDP1 [156.568210] Dynamic subtest A-eDP1: SUCCESS (2.867s) [156.594517] Starting dynamic subtest: B-eDP1 [158.993110] Dynamic subtest B-eDP1: SUCCESS (2.417s) [159.007948] Starting dynamic subtest: C-eDP1 [161.360053] Dynamic subtest C-eDP1: SUCCESS (2.368s) [161.361152] Subtest basic-flip-vs-dpms: SUCCESS (7.662s) [162.262860] [110/171] (847s left) kms_flip (basic-flip-vs-modeset) [162.409107] Starting subtest: basic-flip-vs-modeset [162.412164] Starting dynamic subtest: A-eDP1 [165.248983] Dynamic subtest A-eDP1: SUCCESS (2.837s) [165.267242] Starting dynamic subtest: B-eDP1 [167.623775] Dynamic subtest B-eDP1: SUCCESS (2.367s) [167.638841] Starting dynamic subtest: C-eDP1 [169.979653] Dynamic subtest C-eDP1: SUCCESS (2.354s) [169.980905] Subtest basic-flip-vs-modeset: SUCCESS (7.567s) [170.898251] [111/171] (839s left) kms_flip (basic-flip-vs-wf_vblank) [171.043491] Starting subtest: basic-flip-vs-wf_vblank [171.047731] Starting dynamic subtest: A-eDP1 [173.357502] Dynamic subtest A-eDP1: SUCCESS (2.310s) [173.375436] Starting dynamic subtest: B-eDP1 [175.581654] Dynamic subtest B-eDP1: SUCCESS (2.223s) [175.599695] Starting dynamic subtest: C-eDP1 [177.820663] Dynamic subtest C-eDP1: SUCCESS (2.238s) [177.825747] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.780s) [179.086854] [112/171] (831s left) kms_flip (basic-plain-flip) [179.234938] Starting subtest: basic-plain-flip [179.237674] Starting dynamic subtest: A-eDP1 [181.267551] Dynamic subtest A-eDP1: SUCCESS (2.030s) [181.285255] Starting dynamic subtest: B-eDP1 [183.235584] Dynamic subtest B-eDP1: SUCCESS (1.967s) [183.254716] Starting dynamic subtest: C-eDP1 [185.217968] Dynamic subtest C-eDP1: SUCCESS (1.981s) [185.221054] Subtest basic-plain-flip: SUCCESS (5.988s) [186.511554] [113/171] (823s left) kms_force_connector_basic (force-connector-state) [186.656191] Starting subtest: force-connector-state [188.378828] Subtest force-connector-state: SUCCESS (1.720s) [188.996421] [114/171] (821s left) kms_force_connector_basic (force-edid) [189.137987] Starting subtest: force-edid [189.396286] Subtest force-edid: SUCCESS (0.254s) [189.529279] [115/171] (820s left) kms_force_connector_basic (force-load-detect) [189.673668] Starting subtest: force-load-detect [189.676036] Subtest force-load-detect: SKIP (0.000s) [189.796172] [116/171] (820s left) kms_force_connector_basic (prune-stale-modes) [189.939171] Starting subtest: prune-stale-modes [190.243443] Subtest prune-stale-modes: SUCCESS (0.306s) [190.379606] [117/171] (820s left) kms_frontbuffer_tracking (basic) [190.557258] Starting subtest: basic [193.811229] Subtest basic: SUCCESS (3.254s) [193.931035] [118/171] (816s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [194.103933] Starting subtest: compare-crc-sanitycheck-xr24 [194.105300] Starting dynamic subtest: pipe-A-eDP-1 [194.777103] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.671s) [194.797382] Starting dynamic subtest: pipe-B-eDP-1 [196.147918] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.366s) [196.175713] Starting dynamic subtest: pipe-C-eDP-1 [197.703853] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.542s) [197.704957] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.590s) [198.609574] [119/171] (811s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [198.779151] Starting subtest: compare-crc-sanitycheck-nv12 [198.782527] Starting dynamic subtest: pipe-A-eDP-1 [199.709988] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.921s) [199.726018] Starting dynamic subtest: pipe-B-eDP-1 [201.153112] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.448s) [201.178908] Starting dynamic subtest: pipe-C-eDP-1 [202.951723] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.793s) [202.959971] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.173s) [203.915334] [120/171] (806s left) kms_pipe_crc_basic (hang-read-crc) [204.084219] Starting subtest: hang-read-crc [204.087463] Starting dynamic subtest: pipe-A-eDP-1 [206.393501] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.306s) [206.412347] Starting dynamic subtest: pipe-B-eDP-1 [209.391243] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.994s) [209.410513] Starting dynamic subtest: pipe-C-eDP-1 [212.718316] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.307s) [212.721486] Subtest hang-read-crc: SUCCESS (8.619s) [213.641354] [121/171] (796s left) kms_pipe_crc_basic (nonblocking-crc) [213.807804] Starting subtest: nonblocking-crc [213.818733] Starting dynamic subtest: pipe-A-eDP-1 [214.667958] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.857s) [214.687127] Starting dynamic subtest: pipe-B-eDP-1 [216.218865] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.548s) [216.237770] Starting dynamic subtest: pipe-C-eDP-1 [217.937538] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.714s) [217.938813] Subtest nonblocking-crc: SUCCESS (4.131s) [218.888910] [122/171] (791s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [219.057431] Starting subtest: nonblocking-crc-frame-sequence [219.060686] Starting dynamic subtest: pipe-A-eDP-1 [219.915603] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.854s) [219.934578] Starting dynamic subtest: pipe-B-eDP-1 [221.468394] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.549s) [221.488134] Starting dynamic subtest: pipe-C-eDP-1 [223.183335] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.713s) [223.184563] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.127s) [224.137582] [123/171] (786s left) kms_pipe_crc_basic (read-crc) [224.306654] Starting subtest: read-crc [224.309843] Starting dynamic subtest: pipe-A-eDP-1 [225.096932] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.787s) [225.116230] Starting dynamic subtest: pipe-B-eDP-1 [226.586498] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.482s) [226.605770] Starting dynamic subtest: pipe-C-eDP-1 [228.229467] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.643s) [228.230662] Subtest read-crc: SUCCESS (3.923s) [229.193473] [124/171] (781s left) kms_pipe_crc_basic (read-crc-frame-sequence) [229.364256] Starting subtest: read-crc-frame-sequence [229.367324] Starting dynamic subtest: pipe-A-eDP-1 [230.153609] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.785s) [230.172438] Starting dynamic subtest: pipe-B-eDP-1 [231.638576] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.482s) [231.659556] Starting dynamic subtest: pipe-C-eDP-1 [233.287473] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.644s) [233.289650] Subtest read-crc-frame-sequence: SUCCESS (3.923s) [234.249393] [125/171] (776s left) kms_psr (primary_page_flip) [234.414864] Starting subtest: primary_page_flip [238.623354] Subtest primary_page_flip: SUCCESS (4.196s) [239.561542] [126/171] (771s left) kms_psr (cursor_plane_move) [239.733472] Starting subtest: cursor_plane_move [243.834878] Subtest cursor_plane_move: SUCCESS (4.101s) [244.745512] [127/171] (765s left) kms_psr (sprite_plane_onoff) [244.910288] Starting subtest: sprite_plane_onoff [249.180920] Subtest sprite_plane_onoff: SUCCESS (4.263s) [250.120420] [128/171] (760s left) kms_psr (primary_mmap_gtt) [250.293706] Starting subtest: primary_mmap_gtt [254.481946] Subtest primary_mmap_gtt: SUCCESS (4.189s) [255.434041] [129/171] (755s left) kms_setmode (basic-clone-single-crtc) [255.576770] Starting subtest: basic-clone-single-crtc [255.580351] Subtest basic-clone-single-crtc: SKIP (0.000s) [255.666874] [130/171] (755s left) i915_pm_backlight (basic-brightness) [255.835965] Starting subtest: basic-brightness [255.931687] Starting dynamic subtest: eDP-1 [256.492419] Dynamic subtest eDP-1: SUCCESS (0.561s) [256.493769] Subtest basic-brightness: SUCCESS (0.657s) [257.425957] [131/171] (753s left) i915_pm_rpm (basic-pci-d3-state) [259.315799] Starting subtest: basic-pci-d3-state [263.717368] Subtest basic-pci-d3-state: SUCCESS (4.399s) [263.918394] [132/171] (746s left) i915_pm_rpm (basic-rte) [264.055258] Starting subtest: basic-rte [270.179823] Subtest basic-rte: SUCCESS (6.123s) [271.669866] [133/171] (739s left) i915_pm_rps (basic-api) [271.815566] Starting subtest: basic-api [271.819890] Subtest basic-api: SUCCESS (0.004s) [271.901919] [134/171] (739s left) prime_self_import (basic-llseek-bad) [272.041624] Starting subtest: basic-llseek-bad [272.044176] Subtest basic-llseek-bad: SUCCESS (0.003s) [272.151325] [135/171] (738s left) prime_self_import (basic-llseek-size) [272.286161] Starting subtest: basic-llseek-size [272.290797] Subtest basic-llseek-size: SUCCESS (0.004s) [272.401433] [136/171] (738s left) prime_self_import (basic-with_fd_dup) [272.536122] Starting subtest: basic-with_fd_dup [272.540984] Subtest basic-with_fd_dup: SUCCESS (0.005s) [272.634765] [137/171] (738s left) prime_self_import (basic-with_one_bo) [272.771819] Starting subtest: basic-with_one_bo [272.776477] Subtest basic-with_one_bo: SUCCESS (0.005s) [272.866412] [138/171] (738s left) prime_self_import (basic-with_one_bo_two_files) [273.002448] Starting subtest: basic-with_one_bo_two_files [273.007249] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [273.118068] [139/171] (737s left) prime_self_import (basic-with_two_bos) [273.254130] Starting subtest: basic-with_two_bos [273.258874] Subtest basic-with_two_bos: SUCCESS (0.005s) [273.368493] [140/171] (737s left) prime_vgem (basic-fence-flip) [273.531343] Starting subtest: basic-fence-flip [274.012666] Subtest basic-fence-flip: SUCCESS (0.481s) [274.136544] [141/171] (737s left) prime_vgem (basic-fence-mmap) [274.288451] Starting subtest: basic-fence-mmap [274.336348] Subtest basic-fence-mmap: SUCCESS (0.048s) [274.419052] [142/171] (736s left) prime_vgem (basic-fence-read) [274.571839] Starting subtest: basic-fence-read [274.651262] Subtest basic-fence-read: SUCCESS (0.079s) [274.735489] [143/171] (736s left) prime_vgem (basic-gtt) [274.896956] Starting subtest: basic-gtt [274.922021] Subtest basic-gtt: SUCCESS (0.023s) [275.034821] [144/171] (736s left) prime_vgem (basic-read) [275.200403] Starting subtest: basic-read [275.236268] Subtest basic-read: SUCCESS (0.036s) [275.351840] [145/171] (735s left) prime_vgem (basic-write) [275.504933] Starting subtest: basic-write [275.547500] Subtest basic-write: SUCCESS (0.042s) [275.635350] [146/171] (735s left) vgem_basic (setversion) [275.780643] Starting subtest: setversion [275.783023] Subtest setversion: SUCCESS (0.000s) [275.851711] [147/171] (735s left) vgem_basic (create) [275.997345] Starting subtest: create [275.998577] Subtest create: SUCCESS (0.000s) [276.068185] [148/171] (735s left) vgem_basic (debugfs) [276.214364] Starting subtest: debugfs [276.215647] Subtest debugfs: SUCCESS (0.001s) [276.285095] [149/171] (735s left) vgem_basic (dmabuf-export) [276.430514] Starting subtest: dmabuf-export [276.433332] Subtest dmabuf-export: SUCCESS (0.003s) [276.534884] [150/171] (734s left) vgem_basic (dmabuf-fence) [276.680545] Starting subtest: dmabuf-fence [276.681835] Subtest dmabuf-fence: SUCCESS (0.001s) [276.751725] [151/171] (734s left) vgem_basic (dmabuf-fence-before) [276.899444] Starting subtest: dmabuf-fence-before [276.900585] Subtest dmabuf-fence-before: SUCCESS (0.001s) [276.969632] [152/171] (734s left) vgem_basic (dmabuf-mmap) [277.115179] Starting subtest: dmabuf-mmap [277.141134] Subtest dmabuf-mmap: SUCCESS (0.026s) [277.220260] [153/171] (734s left) vgem_basic (mmap) [277.365658] Starting subtest: mmap [277.381441] Subtest mmap: SUCCESS (0.017s) [277.452717] [154/171] (734s left) vgem_basic (second-client) [277.599480] Starting subtest: second-client [277.633723] Subtest second-client: SUCCESS (0.032s) [277.702900] [155/171] (733s left) vgem_basic (sysfs) [277.849575] Starting subtest: sysfs [277.850809] Subtest sysfs: SUCCESS (0.001s) [277.919061] [156/171] (733s left) core_hotunplug (unbind-rebind) [278.098656] Starting subtest: unbind-rebind [280.577330] Subtest unbind-rebind: SUCCESS (2.479s) [280.614421] [157/171] (731s left) vgem_basic (unload) [280.750993] Starting subtest: unload [309.801518] Subtest unload: SUCCESS (29.041s) [309.934499] [158/171] (701s left) i915_module_load (reload) [310.072372] Starting subtest: reload [313.619785] Subtest reload: SUCCESS (3.547s) [313.650876] [159/171] (698s left) gem_lmem_swapping (basic) [313.808795] Subtest basic: SKIP (0.000s) [313.854586] [160/171] (697s left) gem_lmem_swapping (parallel-random-engines) [314.002127] Subtest parallel-random-engines: SKIP (0.000s) [314.054698] [161/171] (697s left) gem_lmem_swapping (random-engines) [314.199767] Subtest random-engines: SKIP (0.000s) [314.254838] [162/171] (697s left) gem_lmem_swapping (verify-random) [314.400560] Subtest verify-random: SKIP (0.000s) [314.454041] [163/171] (697s left) i915_pm_rpm (module-reload) [326.974942] Starting subtest: module-reload [361.944521] Subtest module-reload: SUCCESS (34.961s) [361.968072] [164/171] (649s left) i915_selftest (live) [362.113194] Starting subtest: live [362.114594] Starting dynamic subtest: sanitycheck [363.938278] Dynamic subtest sanitycheck: SUCCESS (1.818s) [363.953043] Starting dynamic subtest: uncore [365.737797] Dynamic subtest uncore: SUCCESS (1.796s) [365.752422] Starting dynamic subtest: workarounds [367.804670] Dynamic subtest workarounds: SUCCESS (2.063s) [367.819360] Starting dynamic subtest: gt_engines [370.125755] Dynamic subtest gt_engines: SUCCESS (2.317s) [370.140169] Starting dynamic subtest: gt_timelines [382.923544] Dynamic subtest gt_timelines: SUCCESS (12.794s) [382.938426] Starting dynamic subtest: gt_contexts [384.882710] Dynamic subtest gt_contexts: SUCCESS (1.956s) [384.897197] Starting dynamic subtest: gt_lrc [389.251810] Dynamic subtest gt_lrc: SUCCESS (4.365s) [389.266232] Starting dynamic subtest: gt_mocs [391.159760] Dynamic subtest gt_mocs: SUCCESS (1.904s) [391.174195] Starting dynamic subtest: gt_pm [396.261612] Dynamic subtest gt_pm: SUCCESS (5.098s) [396.276268] Starting dynamic subtest: gt_heartbeat [398.201590] Dynamic subtest gt_heartbeat: SUCCESS (1.936s) [398.216385] Starting dynamic subtest: gt_tlb [401.353438] Dynamic subtest gt_tlb: SUCCESS (3.149s) [401.368266] Starting dynamic subtest: requests [408.992924] Dynamic subtest requests: SUCCESS (7.636s) [409.007376] Starting dynamic subtest: migrate [413.596734] Dynamic subtest migrate: SUCCESS (4.600s) [413.611178] Starting dynamic subtest: active [415.428771] Dynamic subtest active: SUCCESS (1.828s) [415.443404] Starting dynamic subtest: objects [418.994230] Dynamic subtest objects: SUCCESS (3.562s) [419.009236] Starting dynamic subtest: mman [425.993597] Dynamic subtest mman: SUCCESS (6.996s) [426.008717] Starting dynamic subtest: dmabuf [427.831484] Dynamic subtest dmabuf: SUCCESS (1.835s) [427.846543] Starting dynamic subtest: vma [429.682821] Dynamic subtest vma: SUCCESS (1.848s) [429.698119] Starting dynamic subtest: coherency [434.183496] Dynamic subtest coherency: SUCCESS (4.498s) [434.198561] Starting dynamic subtest: gtt [443.640412] Dynamic subtest gtt: SUCCESS (9.453s) [443.655459] Starting dynamic subtest: gem [445.665407] Dynamic subtest gem: SUCCESS (2.022s) [445.680436] Starting dynamic subtest: evict [449.524208] Dynamic subtest evict: SUCCESS (3.856s) [449.539381] Starting dynamic subtest: hugepages [457.278691] Dynamic subtest hugepages: SUCCESS (7.751s) [457.293722] Starting dynamic subtest: gem_contexts [472.753054] Dynamic subtest gem_contexts: SUCCESS (15.444s) [472.768397] Starting dynamic subtest: client [474.608581] Dynamic subtest client: SUCCESS (1.879s) [474.624031] Starting dynamic subtest: gem_migrate [476.477453] Dynamic subtest gem_migrate: SUCCESS (1.866s) [476.492847] Starting dynamic subtest: reset [481.886766] Dynamic subtest reset: SUCCESS (5.406s) [481.902065] Starting dynamic subtest: memory_region [483.756615] Dynamic subtest memory_region: SUCCESS (1.866s) [483.771920] Starting dynamic subtest: hangcheck [508.146405] Dynamic subtest hangcheck: SUCCESS (24.386s) [508.161839] Starting dynamic subtest: execlists [518.246648] Dynamic subtest execlists: SUCCESS (10.097s) [518.262170] Starting dynamic subtest: ring_submission [520.060869] Dynamic subtest ring_submission: SUCCESS (1.811s) [520.075929] Starting dynamic subtest: perf [522.048414] Dynamic subtest perf: SUCCESS (1.984s) [522.064020] Starting dynamic subtest: slpc [523.882673] Dynamic subtest slpc: SUCCESS (1.831s) [523.898191] Starting dynamic subtest: guc [525.735468] Dynamic subtest guc: SUCCESS (1.850s) [525.751140] Starting dynamic subtest: guc_multi_lrc [527.592579] Dynamic subtest guc_multi_lrc: SUCCESS (1.854s) [527.608044] Starting dynamic subtest: guc_hang [529.472636] Dynamic subtest guc_hang: SUCCESS (1.877s) [529.487753] Starting dynamic subtest: late_gt_pm [531.313629] Dynamic subtest late_gt_pm: SUCCESS (1.837s) [531.315222] Subtest live: SUCCESS (169.196s) [531.505521] [165/171] (480s left) dmabuf (all-tests) [531.645784] Starting subtest: all-tests [531.647182] Starting dynamic subtest: sanitycheck [531.711058] Dynamic subtest sanitycheck: SUCCESS (0.063s) [531.730927] Starting dynamic subtest: dma_fence [531.899069] Dynamic subtest dma_fence: SUCCESS (0.184s) [531.919352] Starting dynamic subtest: dma_fence_chain [549.609367] Dynamic subtest dma_fence_chain: SUCCESS (17.698s) [549.622387] Starting dynamic subtest: dma_fence_unwrap [549.679755] Dynamic subtest dma_fence_unwrap: SUCCESS (0.075s) [549.699513] Starting dynamic subtest: dma_resv [549.757992] Dynamic subtest dma_resv: SUCCESS (0.074s) [549.760509] Subtest all-tests: SUCCESS (18.113s) [549.791074] [166/171] (462s left) i915_suspend (basic-s2idle-without-i915) [549.925706] Starting subtest: basic-s2idle-without-i915 [572.910091] Subtest basic-s2idle-without-i915: SUCCESS (8.209s) [573.228137] [167/171] (453s left) i915_suspend (basic-s3-without-i915) [573.367772] Starting subtest: basic-s3-without-i915 [596.927129] Subtest basic-s3-without-i915: SUCCESS (6.992s) [597.252345] [168/171] (446s left) gem_exec_suspend (basic-s0) [597.409065] Starting subtest: basic-S0 [597.410216] Starting dynamic subtest: smem [617.238663] Dynamic subtest smem: SUCCESS (3.997s) [617.239886] Subtest basic-S0: SUCCESS (3.998s) [617.393887] [169/171] (441s left) gem_exec_suspend (basic-s3) [617.545180] Starting subtest: basic-S3 [617.546367] Starting dynamic subtest: smem [636.048315] Dynamic subtest smem: SUCCESS (3.692s) [636.049763] Subtest basic-S3: SUCCESS (3.694s) [636.202937] [170/171] (437s left) kms_chamelium_hpd (common-hpd-after-suspend) [636.697977] Subtest common-hpd-after-suspend: SKIP (0.000s) [637.621246] [171/171] (436s left) kms_pipe_crc_basic (suspend-read-crc) [637.784351] Starting subtest: suspend-read-crc [637.785660] Starting dynamic subtest: pipe-A-eDP-1 [655.977726] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.414s) [656.001202] Starting dynamic subtest: pipe-B-eDP-1 [673.943082] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.086s) [673.965896] Starting dynamic subtest: pipe-C-eDP-1 [692.937704] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.246s) [692.938997] Subtest suspend-read-crc: SUCCESS (8.758s) [693.812972] Closing watchdogs Done.