[42.953834] Initializing watchdogs [42.953957] /dev/watchdog0 [42.960697] [001/171] (960s left) i915_module_load (load) [43.059809] Starting subtest: load [44.050699] Subtest load: SUCCESS (0.991s) [44.078521] [002/171] (959s left) core_auth (basic-auth) [44.211745] Starting subtest: basic-auth [44.212845] Subtest basic-auth: SUCCESS (0.001s) [44.291430] [003/171] (959s left) debugfs_test (read_all_entries) [44.426261] Starting subtest: read_all_entries [44.508399] Subtest read_all_entries: SUCCESS (0.081s) [44.610657] [004/171] (958s left) debugfs_test (basic-hwmon) [44.706942] Starting subtest: basic-hwmon [44.708791] Subtest basic-hwmon: SKIP (0.000s) [44.818893] [005/171] (958s left) fbdev (eof) [44.916836] Starting subtest: eof [44.917842] Subtest eof: SUCCESS (0.000s) [44.970222] [006/171] (958s left) fbdev (info) [45.069547] Starting subtest: info [45.070549] Subtest info: SUCCESS (0.000s) [45.135260] [007/171] (958s left) fbdev (nullptr) [45.233293] Starting subtest: nullptr [45.234304] Subtest nullptr: SUCCESS (0.000s) [45.285403] [008/171] (958s left) fbdev (read) [45.383870] Starting subtest: read [46.231936] Subtest read: SUCCESS (0.839s) [46.272393] [009/171] (957s left) fbdev (write) [46.367137] Starting subtest: write [46.788992] Subtest write: SUCCESS (0.413s) [46.841660] [010/171] (956s left) gem_basic (bad-close) [46.928485] Starting subtest: bad-close [46.930301] Subtest bad-close: SUCCESS (0.000s) [46.993395] [011/171] (956s left) gem_basic (create-close) [47.083903] Starting subtest: create-close [47.085576] Subtest create-close: SUCCESS (0.000s) [47.165361] [012/171] (956s left) gem_basic (create-fd-close) [47.263992] Starting subtest: create-fd-close [47.265778] Subtest create-fd-close: SUCCESS (0.000s) [47.384222] [013/171] (956s left) gem_busy (busy@all-engines) [47.501518] Starting subtest: busy [47.502592] Starting dynamic subtest: all-engines [47.544477] Dynamic subtest all-engines: SUCCESS (0.043s) [47.545486] Subtest busy: SUCCESS (0.043s) [47.943517] [014/171] (955s left) gem_close_race (basic-process) [48.050604] Starting subtest: basic-process [48.113323] Subtest basic-process: SUCCESS (0.063s) [48.315219] [015/171] (955s left) gem_close_race (basic-threads) [48.432219] Starting subtest: basic-threads [49.577080] Subtest basic-threads: SUCCESS (1.145s) [49.823982] [016/171] (953s left) gem_ctx_create (basic) [49.997552] Starting subtest: basic [49.998654] Subtest basic: SUCCESS (0.000s) [50.196321] [017/171] (953s left) gem_ctx_create (basic-files) [50.361560] Starting subtest: basic-files [52.382357] Subtest basic-files: SUCCESS (2.021s) [52.696399] [018/171] (951s left) gem_ctx_exec (basic) [52.817725] Starting subtest: basic [52.822477] Subtest basic: SUCCESS (0.005s) [52.901248] [019/171] (950s left) gem_exec_basic (basic) [53.005805] Starting subtest: basic [53.006993] Starting dynamic subtest: rcs0-smem [53.008180] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [53.016849] Starting dynamic subtest: bcs0-smem [53.017918] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [53.024773] Starting dynamic subtest: vcs0-smem [53.026077] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [53.032670] Starting dynamic subtest: vecs0-smem [53.033984] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [53.035369] Subtest basic: SUCCESS (0.005s) [53.216099] [020/171] (950s left) gem_exec_create (basic) [53.322371] Starting subtest: basic [53.323708] Starting dynamic subtest: smem [55.364875] Dynamic subtest smem: SUCCESS (2.042s) [55.366265] Subtest basic: SUCCESS (2.042s) [55.604384] [021/171] (948s left) gem_exec_fence (basic-busy) [55.850189] Starting subtest: basic-busy [55.851464] Starting dynamic subtest: rcs0 [55.876159] Dynamic subtest rcs0: SUCCESS (0.027s) [55.883050] Starting dynamic subtest: bcs0 [55.901368] Dynamic subtest bcs0: SUCCESS (0.025s) [55.908365] Starting dynamic subtest: vcs0 [55.927215] Dynamic subtest vcs0: SUCCESS (0.026s) [55.934414] Starting dynamic subtest: vecs0 [55.953645] Dynamic subtest vecs0: SUCCESS (0.026s) [55.955087] Subtest basic-busy: SUCCESS (0.104s) [56.618514] [022/171] (947s left) gem_exec_fence (basic-wait) [56.864233] Starting subtest: basic-wait [56.866026] Starting dynamic subtest: rcs0 [56.893256] Dynamic subtest rcs0: SUCCESS (0.029s) [56.900568] Starting dynamic subtest: bcs0 [56.921270] Dynamic subtest bcs0: SUCCESS (0.028s) [56.929026] Starting dynamic subtest: vcs0 [56.943250] Dynamic subtest vcs0: SUCCESS (0.022s) [56.951019] Starting dynamic subtest: vecs0 [56.971361] Dynamic subtest vecs0: SUCCESS (0.028s) [56.972386] Subtest basic-wait: SUCCESS (0.108s) [57.598604] [023/171] (946s left) gem_exec_fence (basic-await) [57.873101] Starting subtest: basic-await [57.874889] Starting dynamic subtest: rcs0 [57.966565] Dynamic subtest rcs0: SUCCESS (0.094s) [57.979150] Starting dynamic subtest: bcs0 [58.061857] Dynamic subtest bcs0: SUCCESS (0.095s) [58.071129] Starting dynamic subtest: vcs0 [58.154164] Dynamic subtest vcs0: SUCCESS (0.092s) [58.165074] Starting dynamic subtest: vecs0 [58.253830] Dynamic subtest vecs0: SUCCESS (0.099s) [58.255267] Subtest basic-await: SUCCESS (0.382s) [59.003186] [024/171] (944s left) gem_exec_fence (nb-await) [59.251148] Starting subtest: nb-await [59.253710] Starting dynamic subtest: rcs0 [59.326788] Dynamic subtest rcs0: SUCCESS (0.075s) [59.345320] Starting dynamic subtest: bcs0 [59.404313] Dynamic subtest bcs0: SUCCESS (0.076s) [59.446885] Starting dynamic subtest: vcs0 [59.513972] Dynamic subtest vcs0: SUCCESS (0.107s) [59.553411] Starting dynamic subtest: vecs0 [59.614347] Dynamic subtest vecs0: SUCCESS (0.101s) [59.618095] Subtest nb-await: SUCCESS (0.362s) [60.388897] [025/171] (943s left) gem_exec_gttfill (basic) [60.501150] Starting subtest: basic [69.787299] Subtest basic: SUCCESS (9.278s) [69.971647] [026/171] (933s left) gem_exec_parallel (engines) [70.142089] Starting subtest: engines [70.143125] Starting dynamic subtest: basic [72.513148] Dynamic subtest basic: SUCCESS (2.371s) [72.521525] Starting dynamic subtest: contexts [75.416411] Dynamic subtest contexts: SUCCESS (2.894s) [75.441648] Starting dynamic subtest: fds [78.648734] Dynamic subtest fds: SUCCESS (3.241s) [78.659092] Starting dynamic subtest: userptr [81.011802] Dynamic subtest userptr: SUCCESS (2.363s) [81.012796] Subtest engines: SUCCESS (10.870s) [81.224160] [027/171] (922s left) gem_exec_store (basic) [81.335016] Starting subtest: basic [81.387535] Subtest basic: SUCCESS (0.053s) [81.608086] [028/171] (922s left) gem_flink_basic (bad-flink) [81.705152] Starting subtest: bad-flink [81.706076] Subtest bad-flink: SUCCESS (0.000s) [81.773603] [029/171] (922s left) gem_flink_basic (bad-open) [81.869035] Starting subtest: bad-open [81.870095] Subtest bad-open: SUCCESS (0.000s) [81.940650] [030/171] (922s left) gem_flink_basic (basic) [82.035621] Starting subtest: basic [82.036704] Subtest basic: SUCCESS (0.000s) [82.149521] [031/171] (921s left) gem_flink_basic (double-flink) [82.247724] Starting subtest: double-flink [82.248651] Subtest double-flink: SUCCESS (0.000s) [82.351214] [032/171] (921s left) gem_flink_basic (flink-lifetime) [82.448382] Starting subtest: flink-lifetime [82.449506] Subtest flink-lifetime: SUCCESS (0.001s) [82.559344] [033/171] (921s left) gem_huc_copy (huc-copy) [82.662052] Starting subtest: huc-copy [82.664070] Subtest huc-copy: SKIP (0.000s) [82.795821] [034/171] (921s left) gem_linear_blits (basic) [83.053142] Starting subtest: basic [83.064085] Subtest basic: SUCCESS (0.011s) [83.222955] [035/171] (920s left) gem_mmap (basic) [83.375463] Starting subtest: basic [83.380290] Subtest basic: SUCCESS (0.001s) [83.459857] [036/171] (920s left) gem_mmap_gtt (basic) [83.558132] Starting subtest: basic [83.559094] Subtest basic: SUCCESS (0.001s) [83.614927] [037/171] (920s left) gem_render_linear_blits (basic) [83.719058] Starting subtest: basic [83.759873] Subtest basic: SUCCESS (0.040s) [83.796166] [038/171] (920s left) gem_render_tiled_blits (basic) [83.900748] Starting subtest: basic [83.947523] Subtest basic: SUCCESS (0.047s) [83.996534] [039/171] (920s left) gem_ringfill (basic-all) [84.275296] Starting subtest: basic-all [85.442198] Subtest basic-all: SUCCESS (1.159s) [85.557729] [040/171] (918s left) gem_softpin (allocator-basic) [85.665971] Starting subtest: allocator-basic [85.801817] Subtest allocator-basic: SUCCESS (0.127s) [85.927086] [041/171] (918s left) gem_softpin (allocator-basic-reserve) [86.028631] Starting subtest: allocator-basic-reserve [86.165102] Subtest allocator-basic-reserve: SUCCESS (0.128s) [86.274777] [042/171] (917s left) gem_softpin (safe-alignment) [86.375383] Starting subtest: safe-alignment [86.389228] Subtest safe-alignment: SUCCESS (0.013s) [86.527807] [043/171] (917s left) gem_sync (basic-all) [86.637492] Starting subtest: basic-all [89.046377] Subtest basic-all: SUCCESS (2.409s) [89.261343] [044/171] (915s left) gem_sync (basic-each) [89.371379] Starting subtest: basic-each [91.537334] Subtest basic-each: SUCCESS (2.166s) [91.816053] [045/171] (912s left) gem_tiled_blits (basic) [92.063405] Starting subtest: basic [92.089248] Subtest basic: SUCCESS (0.026s) [92.238795] [046/171] (912s left) gem_tiled_fence_blits (basic) [92.547951] Starting subtest: basic [92.559896] Subtest basic: SUCCESS (0.012s) [92.709247] [047/171] (911s left) gem_tiled_pread_basic [93.078419] [048/171] (911s left) gem_wait (busy@all-engines) [93.202060] Starting subtest: busy [93.203082] Starting dynamic subtest: all-engines [93.730511] Dynamic subtest all-engines: SUCCESS (0.528s) [93.731532] Subtest busy: SUCCESS (0.529s) [93.947650] [049/171] (910s left) gem_wait (wait@all-engines) [94.079846] Starting subtest: wait [94.080910] Starting dynamic subtest: all-engines [95.141626] Dynamic subtest all-engines: SUCCESS (1.060s) [95.144601] Subtest wait: SUCCESS (1.060s) [95.393831] [050/171] (909s left) i915_getparams_basic (basic-eu-total) [95.491137] Starting subtest: basic-eu-total [95.492929] Subtest basic-eu-total: SUCCESS (0.000s) [95.558415] [051/171] (908s left) i915_getparams_basic (basic-subslice-total) [95.655410] Starting subtest: basic-subslice-total [95.657160] Subtest basic-subslice-total: SUCCESS (0.000s) [95.727884] [052/171] (908s left) i915_hangman (error-state-basic) [95.842156] Starting subtest: error-state-basic [95.852479] Subtest error-state-basic: SUCCESS (0.011s) [95.946358] [053/171] (908s left) i915_pciid [96.124086] [054/171] (908s left) kms_addfb_basic (addfb25-bad-modifier) [96.220767] Starting subtest: addfb25-bad-modifier [96.222029] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [96.376565] [055/171] (908s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [96.475599] Starting subtest: addfb25-framebuffer-vs-set-tiling [96.476629] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [96.610584] [056/171] (907s left) kms_addfb_basic (addfb25-modifier-no-flag) [96.709329] Starting subtest: addfb25-modifier-no-flag [96.710344] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [96.849209] [057/171] (907s left) kms_addfb_basic (addfb25-x-tiled-legacy) [96.944332] Starting subtest: addfb25-x-tiled-legacy [96.945257] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [97.085981] [058/171] (907s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [97.182430] Starting subtest: addfb25-x-tiled-mismatch-legacy [97.183334] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [97.321794] [059/171] (907s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [97.454146] Starting subtest: addfb25-yf-tiled-legacy [97.455126] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [97.545949] [060/171] (907s left) kms_addfb_basic (addfb25-y-tiled-legacy) [97.672511] Starting subtest: addfb25-y-tiled-legacy [97.673543] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [97.777383] [061/171] (906s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [97.910204] Starting subtest: addfb25-y-tiled-small-legacy [97.911121] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [98.004899] [062/171] (906s left) kms_addfb_basic (bad-pitch-0) [98.097506] Starting subtest: bad-pitch-0 [98.098492] Subtest bad-pitch-0: SUCCESS (0.000s) [98.226863] [063/171] (906s left) kms_addfb_basic (bad-pitch-1024) [98.318151] Starting subtest: bad-pitch-1024 [98.319070] Subtest bad-pitch-1024: SUCCESS (0.000s) [98.450564] [064/171] (906s left) kms_addfb_basic (bad-pitch-128) [98.539411] Starting subtest: bad-pitch-128 [98.540465] Subtest bad-pitch-128: SUCCESS (0.000s) [98.678042] [065/171] (906s left) kms_addfb_basic (bad-pitch-256) [98.766433] Starting subtest: bad-pitch-256 [98.767450] Subtest bad-pitch-256: SUCCESS (0.000s) [98.897888] [066/171] (905s left) kms_addfb_basic (bad-pitch-32) [98.988576] Starting subtest: bad-pitch-32 [98.989631] Subtest bad-pitch-32: SUCCESS (0.000s) [99.117692] [067/171] (905s left) kms_addfb_basic (bad-pitch-63) [99.204183] Starting subtest: bad-pitch-63 [99.205145] Subtest bad-pitch-63: SUCCESS (0.000s) [99.340968] [068/171] (905s left) kms_addfb_basic (bad-pitch-65536) [99.429233] Starting subtest: bad-pitch-65536 [99.430307] Subtest bad-pitch-65536: SUCCESS (0.000s) [99.558814] [069/171] (905s left) kms_addfb_basic (bad-pitch-999) [99.648130] Starting subtest: bad-pitch-999 [99.648975] Subtest bad-pitch-999: SUCCESS (0.000s) [99.782716] [070/171] (904s left) kms_addfb_basic (basic) [99.871174] Starting subtest: basic [99.872051] Subtest basic: SUCCESS (0.000s) [100.012119] [071/171] (904s left) kms_addfb_basic (basic-x-tiled-legacy) [100.102061] Starting subtest: basic-x-tiled-legacy [100.103058] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [100.227464] [072/171] (904s left) kms_addfb_basic (basic-y-tiled-legacy) [100.314833] Starting subtest: basic-y-tiled-legacy [100.315807] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [100.450306] [073/171] (904s left) kms_addfb_basic (bo-too-small) [100.574762] Starting subtest: bo-too-small [100.575749] Subtest bo-too-small: SUCCESS (0.000s) [100.668418] [074/171] (904s left) kms_addfb_basic (bo-too-small-due-to-tiling) [100.796986] Starting subtest: bo-too-small-due-to-tiling [100.798092] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [100.897912] [075/171] (903s left) kms_addfb_basic (clobberred-modifier) [100.982766] Starting subtest: clobberred-modifier [100.983938] Subtest clobberred-modifier: SUCCESS (0.000s) [101.111117] [076/171] (903s left) kms_addfb_basic (framebuffer-vs-set-tiling) [101.200122] Starting subtest: framebuffer-vs-set-tiling [101.200957] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [101.334161] [077/171] (903s left) kms_addfb_basic (invalid-get-prop) [101.421803] Starting subtest: invalid-get-prop [101.422797] Subtest invalid-get-prop: SUCCESS (0.000s) [101.555501] [078/171] (903s left) kms_addfb_basic (invalid-get-prop-any) [101.642227] Starting subtest: invalid-get-prop-any [101.643056] Subtest invalid-get-prop-any: SUCCESS (0.000s) [101.772101] [079/171] (903s left) kms_addfb_basic (invalid-set-prop) [101.860402] Starting subtest: invalid-set-prop [101.861234] Subtest invalid-set-prop: SUCCESS (0.000s) [101.994548] [080/171] (902s left) kms_addfb_basic (invalid-set-prop-any) [102.082486] Starting subtest: invalid-set-prop-any [102.083449] Subtest invalid-set-prop-any: SUCCESS (0.000s) [102.215489] [081/171] (902s left) kms_addfb_basic (no-handle) [102.301043] Starting subtest: no-handle [102.301872] Subtest no-handle: SUCCESS (0.000s) [102.433193] [082/171] (902s left) kms_addfb_basic (size-max) [102.561912] Starting subtest: size-max [102.562890] Subtest size-max: SUCCESS (0.000s) [102.661701] [083/171] (902s left) kms_addfb_basic (small-bo) [102.780735] Starting subtest: small-bo [102.781743] Subtest small-bo: SUCCESS (0.000s) [102.874046] [084/171] (902s left) kms_addfb_basic (tile-pitch-mismatch) [102.961538] Starting subtest: tile-pitch-mismatch [102.962497] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [103.084735] [085/171] (901s left) kms_addfb_basic (too-high) [103.207617] Starting subtest: too-high [103.208636] Subtest too-high: SUCCESS (0.001s) [103.301977] [086/171] (901s left) kms_addfb_basic (too-wide) [103.429209] Starting subtest: too-wide [103.430279] Subtest too-wide: SUCCESS (0.000s) [103.534960] [087/171] (901s left) kms_addfb_basic (unused-handle) [103.618747] Starting subtest: unused-handle [103.619632] Subtest unused-handle: SUCCESS (0.000s) [103.762103] [088/171] (901s left) kms_addfb_basic (unused-modifier) [103.852143] Starting subtest: unused-modifier [103.853067] Subtest unused-modifier: SUCCESS (0.000s) [103.993718] [089/171] (901s left) kms_addfb_basic (unused-offsets) [104.079707] Starting subtest: unused-offsets [104.080699] Subtest unused-offsets: SUCCESS (0.000s) [104.209783] [090/171] (900s left) kms_addfb_basic (unused-pitches) [104.295467] Starting subtest: unused-pitches [104.296484] Subtest unused-pitches: SUCCESS (0.000s) [104.420907] [091/171] (900s left) kms_busy (basic) [104.554834] Starting subtest: basic [104.555705] Starting dynamic subtest: flip [105.452576] Dynamic subtest flip: SUCCESS (0.894s) [105.467823] Starting dynamic subtest: modeset [109.195563] Dynamic subtest modeset: SUCCESS (3.744s) [109.196962] Subtest basic: SUCCESS (4.640s) [110.109876] [092/171] (895s left) kms_chamelium_hpd (dp-hpd-fast) [110.549850] Subtest dp-hpd-fast: SKIP (0.000s) [111.470428] [093/171] (893s left) kms_chamelium_edid (dp-edid-read) [111.918143] Subtest dp-edid-read: SKIP (0.000s) [112.820293] [094/171] (892s left) kms_chamelium_frames (dp-crc-fast) [113.249924] Subtest dp-crc-fast: SKIP (0.000s) [114.161174] [095/171] (891s left) kms_chamelium_hpd (hdmi-hpd-fast) [114.593826] Subtest hdmi-hpd-fast: SKIP (0.000s) [115.484797] [096/171] (889s left) kms_chamelium_edid (hdmi-edid-read) [115.916023] Subtest hdmi-edid-read: SKIP (0.000s) [116.784852] [097/171] (888s left) kms_chamelium_frames (hdmi-crc-fast) [117.219226] Subtest hdmi-crc-fast: SKIP (0.000s) [118.133679] [098/171] (887s left) kms_chamelium_hpd (vga-hpd-fast) [118.563077] Subtest vga-hpd-fast: SKIP (0.000s) [119.471290] [099/171] (885s left) kms_chamelium_edid (vga-edid-read) [119.904401] Subtest vga-edid-read: SKIP (0.000s) [120.815937] [100/171] (884s left) kms_prop_blob (basic) [120.914796] Starting subtest: basic [120.915941] Subtest basic: SUCCESS (0.000s) [120.975147] [101/171] (884s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [121.111042] Starting subtest: basic-busy-flip-before-cursor-atomic [121.113090] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [121.194762] [102/171] (884s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [121.335072] Starting subtest: basic-busy-flip-before-cursor-legacy [121.336933] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [121.420323] [103/171] (883s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [121.552091] Starting subtest: basic-flip-after-cursor-atomic [122.801313] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.248s) [123.745809] [104/171] (881s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [123.876305] Starting subtest: basic-flip-after-cursor-legacy [125.119945] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.244s) [126.039409] [105/171] (879s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [126.172361] Starting subtest: basic-flip-after-cursor-varying-size [127.436311] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.263s) [128.344859] [106/171] (877s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [128.481365] Starting subtest: basic-flip-before-cursor-atomic [129.750518] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.269s) [130.714827] [107/171] (874s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [130.851374] Starting subtest: basic-flip-before-cursor-legacy [132.095981] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.245s) [133.017768] [108/171] (872s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [133.149405] Starting subtest: basic-flip-before-cursor-varying-size [134.398715] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.249s) [135.325024] [109/171] (870s left) kms_flip (basic-flip-vs-dpms) [135.436378] Starting subtest: basic-flip-vs-dpms [135.440446] Starting dynamic subtest: A-eDP1 [138.253512] Dynamic subtest A-eDP1: SUCCESS (2.812s) [138.264369] Starting dynamic subtest: B-eDP1 [140.636501] Dynamic subtest B-eDP1: SUCCESS (2.382s) [140.649059] Starting dynamic subtest: C-eDP1 [142.993759] Dynamic subtest C-eDP1: SUCCESS (2.355s) [142.997328] Subtest basic-flip-vs-dpms: SUCCESS (7.561s) [143.902653] [110/171] (861s left) kms_flip (basic-flip-vs-modeset) [144.015928] Starting subtest: basic-flip-vs-modeset [144.021975] Starting dynamic subtest: A-eDP1 [146.840569] Dynamic subtest A-eDP1: SUCCESS (2.816s) [146.855276] Starting dynamic subtest: B-eDP1 [149.190969] Dynamic subtest B-eDP1: SUCCESS (2.349s) [149.200645] Starting dynamic subtest: C-eDP1 [151.570626] Dynamic subtest C-eDP1: SUCCESS (2.377s) [151.576211] Subtest basic-flip-vs-modeset: SUCCESS (7.560s) [152.466315] [111/171] (853s left) kms_flip (basic-flip-vs-wf_vblank) [152.577926] Starting subtest: basic-flip-vs-wf_vblank [152.582204] Starting dynamic subtest: A-eDP1 [154.875993] Dynamic subtest A-eDP1: SUCCESS (2.294s) [154.890120] Starting dynamic subtest: B-eDP1 [157.115271] Dynamic subtest B-eDP1: SUCCESS (2.237s) [157.126609] Starting dynamic subtest: C-eDP1 [159.415313] Dynamic subtest C-eDP1: SUCCESS (2.299s) [159.417356] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.840s) [160.734431] [112/171] (844s left) kms_flip (basic-plain-flip) [160.847131] Starting subtest: basic-plain-flip [160.852324] Starting dynamic subtest: A-eDP1 [162.861854] Dynamic subtest A-eDP1: SUCCESS (1.997s) [162.880916] Starting dynamic subtest: B-eDP1 [164.827587] Dynamic subtest B-eDP1: SUCCESS (1.970s) [164.838697] Starting dynamic subtest: C-eDP1 [166.819186] Dynamic subtest C-eDP1: SUCCESS (1.988s) [166.836271] Subtest basic-plain-flip: SUCCESS (5.990s) [168.091540] [113/171] (837s left) kms_force_connector_basic (force-connector-state) [168.217706] Starting subtest: force-connector-state [168.850712] Subtest force-connector-state: SUCCESS (0.634s) [169.837795] [114/171] (835s left) kms_force_connector_basic (force-edid) [169.959786] Starting subtest: force-edid [169.987454] Subtest force-edid: SUCCESS (0.028s) [170.141454] [115/171] (835s left) kms_force_connector_basic (force-load-detect) [170.258774] Starting subtest: force-load-detect [170.260891] Subtest force-load-detect: SKIP (0.000s) [170.409268] [116/171] (835s left) kms_force_connector_basic (prune-stale-modes) [170.527657] Starting subtest: prune-stale-modes [170.553764] Subtest prune-stale-modes: SUCCESS (0.029s) [170.711149] [117/171] (834s left) kms_frontbuffer_tracking (basic) [170.843817] Starting subtest: basic [173.631200] Subtest basic: SUCCESS (2.789s) [173.743406] [118/171] (831s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [173.869377] Starting subtest: compare-crc-sanitycheck [173.871970] Starting dynamic subtest: pipe-A-eDP-1 [174.505396] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.634s) [174.516979] Starting dynamic subtest: pipe-B-eDP-1 [175.830093] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.320s) [175.842447] Starting dynamic subtest: pipe-C-eDP-1 [177.146629] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.315s) [177.147589] Subtest compare-crc-sanitycheck: SUCCESS (3.275s) [178.051577] [119/171] (827s left) kms_pipe_crc_basic (hang-read-crc) [178.175974] Starting subtest: hang-read-crc [178.179064] Starting dynamic subtest: pipe-A-eDP-1 [180.433208] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.254s) [180.445521] Starting dynamic subtest: pipe-B-eDP-1 [183.378087] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.941s) [183.401186] Starting dynamic subtest: pipe-C-eDP-1 [186.297872] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.916s) [186.299242] Subtest hang-read-crc: SUCCESS (8.122s) [187.273266] [120/171] (818s left) kms_pipe_crc_basic (nonblocking-crc) [187.413763] Starting subtest: nonblocking-crc [187.416291] Starting dynamic subtest: pipe-A-eDP-1 [188.225260] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.807s) [188.240493] Starting dynamic subtest: pipe-B-eDP-1 [189.753122] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.523s) [189.764328] Starting dynamic subtest: pipe-C-eDP-1 [191.291970] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.535s) [191.294194] Subtest nonblocking-crc: SUCCESS (3.875s) [192.218734] [121/171] (813s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [192.357858] Starting subtest: nonblocking-crc-frame-sequence [192.360653] Starting dynamic subtest: pipe-A-eDP-1 [193.158684] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.798s) [193.170336] Starting dynamic subtest: pipe-B-eDP-1 [194.669358] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.508s) [194.680687] Starting dynamic subtest: pipe-C-eDP-1 [196.181600] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.510s) [196.183394] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.822s) [197.143345] [122/171] (808s left) kms_pipe_crc_basic (read-crc) [197.280892] Starting subtest: read-crc [197.283548] Starting dynamic subtest: pipe-A-eDP-1 [198.019573] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.733s) [198.038521] Starting dynamic subtest: pipe-B-eDP-1 [199.476986] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.455s) [199.492152] Starting dynamic subtest: pipe-C-eDP-1 [200.942738] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.458s) [200.944853] Subtest read-crc: SUCCESS (3.658s) [201.884156] [123/171] (803s left) kms_pipe_crc_basic (read-crc-frame-sequence) [202.022394] Starting subtest: read-crc-frame-sequence [202.023456] Starting dynamic subtest: pipe-A-eDP-1 [202.775435] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.749s) [202.791754] Starting dynamic subtest: pipe-B-eDP-1 [204.252895] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.470s) [204.268180] Starting dynamic subtest: pipe-C-eDP-1 [205.692912] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.437s) [205.694100] Subtest read-crc-frame-sequence: SUCCESS (3.670s) [206.615262] [124/171] (799s left) kms_psr (primary_page_flip) [206.763583] Starting subtest: primary_page_flip [208.738655] Subtest primary_page_flip: SUCCESS (1.973s) [209.654647] [125/171] (796s left) kms_psr (cursor_plane_move) [209.786376] Starting subtest: cursor_plane_move [211.694381] Subtest cursor_plane_move: SUCCESS (1.902s) [212.637358] [126/171] (793s left) kms_psr (sprite_plane_onoff) [212.771371] Starting subtest: sprite_plane_onoff [214.771042] Subtest sprite_plane_onoff: SUCCESS (1.996s) [215.709558] [127/171] (790s left) kms_psr (primary_mmap_gtt) [215.847873] Starting subtest: primary_mmap_gtt [217.830844] Subtest primary_mmap_gtt: SUCCESS (1.983s) [218.780514] [128/171] (787s left) kms_setmode (basic-clone-single-crtc) [218.879240] Starting subtest: basic-clone-single-crtc [218.882093] Subtest basic-clone-single-crtc: SKIP (0.000s) [218.945744] [129/171] (786s left) i915_pm_backlight (basic-brightness) [219.080522] Starting subtest: basic-brightness [219.183788] Starting dynamic subtest: eDP-1 [219.835053] Dynamic subtest eDP-1: SUCCESS (0.651s) [219.836051] Subtest basic-brightness: SUCCESS (0.755s) [220.761788] [130/171] (785s left) i915_pm_rpm (basic-pci-d3-state) [222.509098] Starting subtest: basic-pci-d3-state [227.011420] Subtest basic-pci-d3-state: SUCCESS (4.514s) [227.170199] [131/171] (778s left) i915_pm_rpm (basic-rte) [227.267670] Starting subtest: basic-rte [233.413736] Subtest basic-rte: SUCCESS (6.144s) [234.834923] [132/171] (771s left) i915_pm_rps (basic-api) [234.939681] Starting subtest: basic-api [234.944754] Subtest basic-api: SUCCESS (0.005s) [235.056911] [133/171] (770s left) prime_self_import (basic-llseek-bad) [235.160371] Starting subtest: basic-llseek-bad [235.163891] Subtest basic-llseek-bad: SUCCESS (0.002s) [235.281253] [134/171] (770s left) prime_self_import (basic-llseek-size) [235.376435] Starting subtest: basic-llseek-size [235.380106] Subtest basic-llseek-size: SUCCESS (0.003s) [235.491630] [135/171] (770s left) prime_self_import (basic-with_fd_dup) [235.587859] Starting subtest: basic-with_fd_dup [235.591357] Subtest basic-with_fd_dup: SUCCESS (0.003s) [235.708633] [136/171] (770s left) prime_self_import (basic-with_one_bo) [235.803429] Starting subtest: basic-with_one_bo [235.807705] Subtest basic-with_one_bo: SUCCESS (0.004s) [235.885124] [137/171] (770s left) prime_self_import (basic-with_one_bo_two_files) [235.982206] Starting subtest: basic-with_one_bo_two_files [235.985915] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [236.070247] [138/171] (769s left) prime_self_import (basic-with_two_bos) [236.170484] Starting subtest: basic-with_two_bos [236.173757] Subtest basic-with_two_bos: SUCCESS (0.003s) [236.306325] [139/171] (769s left) prime_vgem (basic-fence-flip) [236.438158] Starting subtest: basic-fence-flip [236.945712] Subtest basic-fence-flip: SUCCESS (0.507s) [237.071465] [140/171] (769s left) prime_vgem (basic-fence-mmap) [237.193998] Starting subtest: basic-fence-mmap [237.224502] Subtest basic-fence-mmap: SUCCESS (0.030s) [237.310549] [141/171] (768s left) prime_vgem (basic-fence-read) [237.458971] Starting subtest: basic-fence-read [237.512395] Subtest basic-fence-read: SUCCESS (0.053s) [237.602394] [142/171] (768s left) prime_vgem (basic-gtt) [237.708989] Starting subtest: basic-gtt [237.724177] Subtest basic-gtt: SUCCESS (0.015s) [237.815079] [143/171] (768s left) prime_vgem (basic-read) [237.966073] Starting subtest: basic-read [237.992326] Subtest basic-read: SUCCESS (0.026s) [238.087289] [144/171] (768s left) prime_vgem (basic-write) [238.235965] Starting subtest: basic-write [238.265700] Subtest basic-write: SUCCESS (0.030s) [238.336113] [145/171] (767s left) prime_vgem (basic-userptr) [238.459303] Starting subtest: basic-userptr [238.469741] Subtest basic-userptr: SKIP (0.009s) [238.578651] [146/171] (767s left) vgem_basic (setversion) [238.701232] Starting subtest: setversion [238.703535] Subtest setversion: SUCCESS (0.000s) [238.764787] [147/171] (767s left) vgem_basic (create) [238.867594] Starting subtest: create [238.868660] Subtest create: SUCCESS (0.000s) [238.925674] [148/171] (767s left) vgem_basic (debugfs) [239.034463] Starting subtest: debugfs [239.035564] Subtest debugfs: SUCCESS (0.001s) [239.092622] [149/171] (767s left) vgem_basic (dmabuf-export) [239.201182] Starting subtest: dmabuf-export [239.204984] Subtest dmabuf-export: SUCCESS (0.002s) [239.307297] [150/171] (767s left) vgem_basic (dmabuf-fence) [239.440336] Starting subtest: dmabuf-fence [239.441452] Subtest dmabuf-fence: SUCCESS (0.001s) [239.530853] [151/171] (766s left) vgem_basic (dmabuf-fence-before) [239.652522] Starting subtest: dmabuf-fence-before [239.653555] Subtest dmabuf-fence-before: SUCCESS (0.000s) [239.712141] [152/171] (766s left) vgem_basic (dmabuf-mmap) [239.818973] Starting subtest: dmabuf-mmap [239.837566] Subtest dmabuf-mmap: SUCCESS (0.018s) [239.893147] [153/171] (766s left) vgem_basic (mmap) [240.002679] Starting subtest: mmap [240.014951] Subtest mmap: SUCCESS (0.012s) [240.078769] [154/171] (766s left) vgem_basic (second-client) [240.187891] Starting subtest: second-client [240.237653] Subtest second-client: SUCCESS (0.049s) [240.294704] [155/171] (766s left) vgem_basic (sysfs) [240.404640] Starting subtest: sysfs [240.405739] Subtest sysfs: SUCCESS (0.001s) [240.461402] [156/171] (765s left) core_hotunplug (unbind-rebind) [240.613817] Starting subtest: unbind-rebind [242.799440] Subtest unbind-rebind: SUCCESS (2.184s) [242.828703] [157/171] (763s left) vgem_basic (unload) [242.949197] Starting subtest: unload [271.840175] Subtest unload: SUCCESS (28.856s) [271.920249] [158/171] (734s left) i915_module_load (reload) [272.014980] Starting subtest: reload [274.936523] Subtest reload: SUCCESS (2.921s) [274.963407] [159/171] (731s left) gem_lmem_swapping (basic) [275.199798] Subtest basic: SKIP (0.000s) [275.255411] [160/171] (731s left) gem_lmem_swapping (parallel-random-engines) [275.358061] Subtest parallel-random-engines: SKIP (0.000s) [275.388738] [161/171] (731s left) gem_lmem_swapping (random-engines) [275.490910] Subtest random-engines: SKIP (0.000s) [275.521800] [162/171] (731s left) gem_lmem_swapping (verify-random) [275.624426] Subtest verify-random: SKIP (0.000s) [275.655143] [163/171] (730s left) i915_pm_rpm (module-reload) [278.276520] Starting subtest: module-reload [296.536459] Subtest module-reload: SUCCESS (18.252s) [296.551100] [164/171] (710s left) i915_selftest (live) [296.659157] Starting subtest: live [296.660274] Starting dynamic subtest: sanitycheck [298.269472] Dynamic subtest sanitycheck: SUCCESS (1.601s) [298.276690] Starting dynamic subtest: uncore [299.888709] Dynamic subtest uncore: SUCCESS (1.618s) [299.899853] Starting dynamic subtest: workarounds [301.673795] Dynamic subtest workarounds: SUCCESS (1.784s) [301.681079] Starting dynamic subtest: gt_engines [303.718486] Dynamic subtest gt_engines: SUCCESS (2.044s) [303.725997] Starting dynamic subtest: gt_timelines [313.281787] Dynamic subtest gt_timelines: SUCCESS (9.562s) [313.289177] Starting dynamic subtest: gt_contexts [314.981400] Dynamic subtest gt_contexts: SUCCESS (1.699s) [314.988412] Starting dynamic subtest: gt_lrc [319.075265] Dynamic subtest gt_lrc: SUCCESS (4.093s) [319.082560] Starting dynamic subtest: gt_mocs [320.825812] Dynamic subtest gt_mocs: SUCCESS (1.750s) [320.833076] Starting dynamic subtest: gt_pm [326.676524] Dynamic subtest gt_pm: SUCCESS (5.850s) [326.684079] Starting dynamic subtest: gt_heartbeat [328.428194] Dynamic subtest gt_heartbeat: SUCCESS (1.753s) [328.439413] Starting dynamic subtest: requests [335.693479] Dynamic subtest requests: SUCCESS (7.262s) [335.701077] Starting dynamic subtest: migrate [340.138377] Dynamic subtest migrate: SUCCESS (4.444s) [340.149598] Starting dynamic subtest: active [341.737504] Dynamic subtest active: SUCCESS (1.598s) [341.744755] Starting dynamic subtest: objects [344.486524] Dynamic subtest objects: SUCCESS (2.748s) [344.497804] Starting dynamic subtest: mman [349.589544] Dynamic subtest mman: SUCCESS (5.102s) [349.596911] Starting dynamic subtest: dmabuf [351.271390] Dynamic subtest dmabuf: SUCCESS (1.681s) [351.278734] Starting dynamic subtest: vma [352.866444] Dynamic subtest vma: SUCCESS (1.594s) [352.874134] Starting dynamic subtest: coherency [355.636476] Dynamic subtest coherency: SUCCESS (2.769s) [355.644027] Starting dynamic subtest: gtt [366.462381] Dynamic subtest gtt: SUCCESS (10.825s) [366.469783] Starting dynamic subtest: gem [368.320678] Dynamic subtest gem: SUCCESS (1.857s) [368.328334] Starting dynamic subtest: evict [371.486468] Dynamic subtest evict: SUCCESS (3.165s) [371.493961] Starting dynamic subtest: hugepages [377.487746] Dynamic subtest hugepages: SUCCESS (6.000s) [377.495537] Starting dynamic subtest: gem_contexts [391.862801] Dynamic subtest gem_contexts: SUCCESS (14.374s) [391.870427] Starting dynamic subtest: client [393.515835] Dynamic subtest client: SUCCESS (1.652s) [393.523702] Starting dynamic subtest: gem_migrate [395.115476] Dynamic subtest gem_migrate: SUCCESS (1.599s) [395.123247] Starting dynamic subtest: reset [397.424672] Dynamic subtest reset: SUCCESS (2.308s) [397.432145] Starting dynamic subtest: memory_region [399.009359] Dynamic subtest memory_region: SUCCESS (1.584s) [399.016972] Starting dynamic subtest: hangcheck [423.094483] Dynamic subtest hangcheck: SUCCESS (24.084s) [423.102199] Starting dynamic subtest: execlists [432.479776] Dynamic subtest execlists: SUCCESS (9.384s) [432.487441] Starting dynamic subtest: ring_submission [434.065403] Dynamic subtest ring_submission: SUCCESS (1.585s) [434.076851] Starting dynamic subtest: perf [435.805934] Dynamic subtest perf: SUCCESS (1.745s) [435.813072] Starting dynamic subtest: slpc [437.403513] Dynamic subtest slpc: SUCCESS (1.591s) [437.411213] Starting dynamic subtest: guc [439.007309] Dynamic subtest guc: SUCCESS (1.603s) [439.014925] Starting dynamic subtest: guc_multi_lrc [440.615634] Dynamic subtest guc_multi_lrc: SUCCESS (1.607s) [440.623241] Starting dynamic subtest: guc_hang [442.210772] Dynamic subtest guc_hang: SUCCESS (1.594s) [442.222248] Starting dynamic subtest: late_gt_pm [443.867822] Dynamic subtest late_gt_pm: SUCCESS (1.655s) [443.869480] Subtest live: SUCCESS (147.199s) [443.939037] [165/171] (562s left) dmabuf (all-tests) [444.042094] Starting subtest: all-tests [444.043338] Starting dynamic subtest: sanitycheck [444.064334] Dynamic subtest sanitycheck: SUCCESS (0.021s) [444.073203] Starting dynamic subtest: dma_fence [444.199348] Dynamic subtest dma_fence: SUCCESS (0.134s) [444.206612] Starting dynamic subtest: dma_fence_chain [460.399933] Dynamic subtest dma_fence_chain: SUCCESS (16.191s) [460.408296] Starting dynamic subtest: dma_fence_unwrap [460.416237] Dynamic subtest dma_fence_unwrap: SUCCESS (0.024s) [460.423952] Starting dynamic subtest: dma_resv [460.445373] Dynamic subtest dma_resv: SUCCESS (0.028s) [460.446401] Subtest all-tests: SUCCESS (16.403s) [460.464820] [166/171] (546s left) i915_suspend (basic-s2idle-without-i915) [460.559977] Starting subtest: basic-s2idle-without-i915 [478.958207] Subtest basic-s2idle-without-i915: SUCCESS (3.942s) [481.298507] [167/171] (541s left) i915_suspend (basic-s3-without-i915) [481.405360] Starting subtest: basic-s3-without-i915 [501.984536] Subtest basic-s3-without-i915: SUCCESS (4.062s) [504.330924] [168/171] (537s left) gem_exec_suspend (basic-s0) [504.448265] Starting subtest: basic-S0 [504.449289] Starting dynamic subtest: smem [523.065917] Dynamic subtest smem: SUCCESS (3.193s) [523.066891] Subtest basic-S0: SUCCESS (3.194s) [525.342122] [169/171] (533s left) gem_exec_suspend (basic-s3) [525.492556] Starting subtest: basic-S3 [525.493624] Starting dynamic subtest: smem [545.929618] Dynamic subtest smem: SUCCESS (4.128s) [545.930487] Subtest basic-S3: SUCCESS (4.129s) [548.169028] [170/171] (529s left) kms_chamelium_hpd (common-hpd-after-suspend) [548.612777] Subtest common-hpd-after-suspend: SKIP (0.000s) [549.534416] [171/171] (528s left) kms_pipe_crc_basic (suspend-read-crc) [549.669991] Starting subtest: suspend-read-crc [549.670999] Starting dynamic subtest: pipe-A-eDP-1 [570.062208] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.361s) [570.075386] Starting dynamic subtest: pipe-B-eDP-1 [592.049910] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.003s) [592.062762] Starting dynamic subtest: pipe-C-eDP-1 [622.295364] Dynamic subtest pipe-C-eDP-1: SUCCESS (13.123s) [622.296484] Subtest suspend-read-crc: SUCCESS (21.494s) [623.164879] Closing watchdogs Done.