[39.738075] Initializing watchdogs [39.738302] /dev/watchdog0 [39.749668] [001/171] (960s left) i915_module_load (load) [39.914531] Starting subtest: load [42.533950] Subtest load: SUCCESS (2.619s) [42.563040] [002/171] (957s left) core_auth (basic-auth) [42.704405] Starting subtest: basic-auth [42.705613] Subtest basic-auth: SUCCESS (0.001s) [42.794959] [003/171] (957s left) debugfs_test (read_all_entries) [42.942167] Starting subtest: read_all_entries [43.019753] Subtest read_all_entries: SUCCESS (0.077s) [43.132719] [004/171] (957s left) debugfs_test (basic-hwmon) [43.276926] Starting subtest: basic-hwmon [43.279150] Subtest basic-hwmon: SKIP (0.000s) [43.388687] [005/171] (956s left) fbdev (eof) [43.585135] Starting subtest: eof [43.586493] Subtest eof: SUCCESS (0.001s) [43.649935] [006/171] (956s left) fbdev (info) [43.796359] Starting subtest: info [43.797596] Subtest info: SUCCESS (0.000s) [43.865317] [007/171] (956s left) fbdev (nullptr) [44.020033] Starting subtest: nullptr [44.021412] Subtest nullptr: SUCCESS (0.000s) [44.084421] [008/171] (956s left) fbdev (read) [44.234716] Starting subtest: read [51.342813] Subtest read: SUCCESS (7.073s) [51.384398] [009/171] (949s left) fbdev (write) [51.519999] Starting subtest: write [55.291991] Subtest write: SUCCESS (3.738s) [55.334485] [010/171] (945s left) gem_basic (bad-close) [55.476319] Starting subtest: bad-close [55.478563] Subtest bad-close: SUCCESS (0.000s) [55.550359] [011/171] (944s left) gem_basic (create-close) [55.687618] Starting subtest: create-close [55.689780] Subtest create-close: SUCCESS (0.000s) [55.784027] [012/171] (944s left) gem_basic (create-fd-close) [55.921571] Starting subtest: create-fd-close [55.923835] Subtest create-fd-close: SUCCESS (0.000s) [56.033361] [013/171] (944s left) gem_busy (busy@all-engines) [56.182264] Starting subtest: busy [56.183494] Starting dynamic subtest: all-engines [56.242266] Dynamic subtest all-engines: SUCCESS (0.060s) [56.243364] Subtest busy: SUCCESS (0.060s) [56.634837] [014/171] (943s left) gem_close_race (basic-process) [56.786561] Starting subtest: basic-process [56.868535] Subtest basic-process: SUCCESS (0.081s) [57.067596] [015/171] (943s left) gem_close_race (basic-threads) [57.223963] Starting subtest: basic-threads [58.389788] Subtest basic-threads: SUCCESS (1.166s) [58.618314] [016/171] (942s left) gem_ctx_create (basic) [58.781734] Starting subtest: basic [58.782852] Subtest basic: SUCCESS (0.000s) [58.985814] [017/171] (941s left) gem_ctx_create (basic-files) [59.138449] Starting subtest: basic-files [61.165401] Subtest basic-files: SUCCESS (2.027s) [61.436435] [018/171] (939s left) gem_ctx_exec (basic) [61.589118] Starting subtest: basic [61.594200] Subtest basic: SUCCESS (0.005s) [61.700922] [019/171] (939s left) gem_exec_basic (basic) [61.849570] Starting subtest: basic [61.850808] Starting dynamic subtest: rcs0-smem [61.851969] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [61.863676] Starting dynamic subtest: bcs0-smem [61.864786] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [61.875184] Starting dynamic subtest: vcs0-smem [61.876504] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [61.886871] Starting dynamic subtest: vecs0-smem [61.888211] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [61.889521] Subtest basic: SUCCESS (0.005s) [62.051311] [020/171] (938s left) gem_exec_create (basic) [62.200246] Starting subtest: basic [62.201330] Starting dynamic subtest: smem [64.266591] Dynamic subtest smem: SUCCESS (2.043s) [64.268630] Subtest basic: SUCCESS (2.043s) [64.455342] [021/171] (936s left) gem_exec_fence (basic-busy) [64.840655] Starting subtest: basic-busy [64.842942] Starting dynamic subtest: rcs0 [64.853416] Dynamic subtest rcs0: SUCCESS (0.035s) [64.864584] Starting dynamic subtest: bcs0 [64.867621] Dynamic subtest bcs0: SUCCESS (0.031s) [64.878543] Starting dynamic subtest: vcs0 [64.880663] Dynamic subtest vcs0: SUCCESS (0.035s) [64.892421] Starting dynamic subtest: vecs0 [64.896760] Dynamic subtest vecs0: SUCCESS (0.031s) [64.897936] Subtest basic-busy: SUCCESS (0.135s) [65.501894] [022/171] (935s left) gem_exec_fence (basic-wait) [65.800128] Starting subtest: basic-wait [65.801391] Starting dynamic subtest: rcs0 [65.825479] Dynamic subtest rcs0: SUCCESS (0.032s) [65.839067] Starting dynamic subtest: bcs0 [65.856955] Dynamic subtest bcs0: SUCCESS (0.030s) [65.870115] Starting dynamic subtest: vcs0 [65.877068] Dynamic subtest vcs0: SUCCESS (0.020s) [65.888505] Starting dynamic subtest: vecs0 [65.895723] Dynamic subtest vecs0: SUCCESS (0.018s) [65.897045] Subtest basic-wait: SUCCESS (0.103s) [66.452599] [023/171] (934s left) gem_exec_fence (basic-await) [66.757247] Starting subtest: basic-await [66.758410] Starting dynamic subtest: rcs0 [66.851412] Dynamic subtest rcs0: SUCCESS (0.101s) [66.877082] Starting dynamic subtest: bcs0 [66.965747] Dynamic subtest bcs0: SUCCESS (0.113s) [66.993427] Starting dynamic subtest: vcs0 [67.099917] Dynamic subtest vcs0: SUCCESS (0.128s) [67.127433] Starting dynamic subtest: vecs0 [67.223867] Dynamic subtest vecs0: SUCCESS (0.130s) [67.225901] Subtest basic-await: SUCCESS (0.475s) [67.836269] [024/171] (932s left) gem_exec_fence (nb-await) [68.135147] Starting subtest: nb-await [68.136322] Starting dynamic subtest: rcs0 [68.193677] Dynamic subtest rcs0: SUCCESS (0.066s) [68.211877] Starting dynamic subtest: bcs0 [68.263272] Dynamic subtest bcs0: SUCCESS (0.069s) [68.285313] Starting dynamic subtest: vcs0 [68.335225] Dynamic subtest vcs0: SUCCESS (0.071s) [68.362826] Starting dynamic subtest: vecs0 [68.411916] Dynamic subtest vecs0: SUCCESS (0.076s) [68.413948] Subtest nb-await: SUCCESS (0.285s) [68.985610] [025/171] (931s left) gem_exec_gttfill (basic) [69.139470] Starting subtest: basic [75.164835] Subtest basic: SUCCESS (6.017s) [75.355865] [026/171] (925s left) gem_exec_parallel (engines) [75.572390] Starting subtest: engines [75.573626] Starting dynamic subtest: basic [78.140273] Dynamic subtest basic: SUCCESS (2.549s) [78.165891] Starting dynamic subtest: contexts [81.838467] Dynamic subtest contexts: SUCCESS (3.707s) [81.851965] Starting dynamic subtest: fds [86.086143] Dynamic subtest fds: SUCCESS (4.255s) [86.118848] Starting dynamic subtest: userptr [88.655175] Dynamic subtest userptr: SUCCESS (2.543s) [88.657163] Subtest engines: SUCCESS (13.056s) [88.875567] [027/171] (912s left) gem_exec_store (basic) [89.043307] Starting subtest: basic [89.099581] Subtest basic: SUCCESS (0.056s) [89.304482] [028/171] (911s left) gem_flink_basic (bad-flink) [89.438682] Starting subtest: bad-flink [89.439980] Subtest bad-flink: SUCCESS (0.000s) [89.520499] [029/171] (911s left) gem_flink_basic (bad-open) [89.656622] Starting subtest: bad-open [89.657949] Subtest bad-open: SUCCESS (0.000s) [89.736796] [030/171] (911s left) gem_flink_basic (basic) [89.872427] Starting subtest: basic [89.873552] Subtest basic: SUCCESS (0.000s) [89.970505] [031/171] (911s left) gem_flink_basic (double-flink) [90.105166] Starting subtest: double-flink [90.106560] Subtest double-flink: SUCCESS (0.000s) [90.203416] [032/171] (910s left) gem_flink_basic (flink-lifetime) [90.337823] Starting subtest: flink-lifetime [90.340785] Subtest flink-lifetime: SUCCESS (0.002s) [90.453800] [033/171] (910s left) gem_huc_copy (huc-copy) [90.593118] Starting subtest: huc-copy [90.595405] Subtest huc-copy: SKIP (0.000s) [90.703752] [034/171] (910s left) gem_linear_blits (basic) [91.019766] Starting subtest: basic [91.032823] Subtest basic: SUCCESS (0.013s) [91.152834] [035/171] (909s left) gem_mmap (basic) [91.346630] Starting subtest: basic [91.352217] Subtest basic: SUCCESS (0.001s) [91.437351] [036/171] (909s left) gem_mmap_gtt (basic) [91.572716] Starting subtest: basic [91.573935] Subtest basic: SUCCESS (0.001s) [91.653318] [037/171] (909s left) gem_render_linear_blits (basic) [91.792375] Starting subtest: basic [91.845699] Subtest basic: SUCCESS (0.053s) [91.903709] [038/171] (909s left) gem_render_tiled_blits (basic) [92.045560] Starting subtest: basic [92.106405] Subtest basic: SUCCESS (0.060s) [92.193865] [039/171] (909s left) gem_ringfill (basic-all) [92.467905] Starting subtest: basic-all [93.715865] Subtest basic-all: SUCCESS (1.247s) [93.805173] [040/171] (907s left) gem_softpin (allocator-basic) [93.946935] Starting subtest: allocator-basic [94.118241] Subtest allocator-basic: SUCCESS (0.163s) [94.239531] [041/171] (907s left) gem_softpin (allocator-basic-reserve) [94.399319] Starting subtest: allocator-basic-reserve [94.571573] Subtest allocator-basic-reserve: SUCCESS (0.164s) [94.671061] [042/171] (906s left) gem_softpin (safe-alignment) [94.824241] Starting subtest: safe-alignment [94.844695] Subtest safe-alignment: SUCCESS (0.020s) [94.953651] [043/171] (906s left) gem_sync (basic-all) [95.108641] Starting subtest: basic-all [97.301296] Subtest basic-all: SUCCESS (2.194s) [97.506590] [044/171] (903s left) gem_sync (basic-each) [97.663670] Starting subtest: basic-each [99.932415] Subtest basic-each: SUCCESS (2.268s) [100.158750] [045/171] (901s left) gem_tiled_blits (basic) [100.457668] Starting subtest: basic [100.485929] Subtest basic: SUCCESS (0.028s) [100.602200] [046/171] (900s left) gem_tiled_fence_blits (basic) [100.979558] Starting subtest: basic [101.006412] Subtest basic: SUCCESS (0.027s) [101.135816] [047/171] (900s left) gem_tiled_pread_basic [101.688099] [048/171] (899s left) gem_wait (busy@all-engines) [101.842458] Starting subtest: busy [101.843690] Starting dynamic subtest: all-engines [102.380762] Dynamic subtest all-engines: SUCCESS (0.538s) [102.381967] Subtest busy: SUCCESS (0.539s) [102.621995] [049/171] (898s left) gem_wait (wait@all-engines) [102.773878] Starting subtest: wait [102.775129] Starting dynamic subtest: all-engines [103.816429] Dynamic subtest all-engines: SUCCESS (1.042s) [103.818464] Subtest wait: SUCCESS (1.042s) [104.072353] [050/171] (897s left) i915_getparams_basic (basic-eu-total) [104.207398] Starting subtest: basic-eu-total [104.209983] Subtest basic-eu-total: SUCCESS (0.000s) [104.288885] [051/171] (897s left) i915_getparams_basic (basic-subslice-total) [104.424583] Starting subtest: basic-subslice-total [104.427261] Subtest basic-subslice-total: SUCCESS (0.000s) [104.503920] [052/171] (897s left) i915_hangman (error-state-basic) [104.648683] Starting subtest: error-state-basic [104.660702] Subtest error-state-basic: SUCCESS (0.011s) [104.754275] [053/171] (896s left) i915_pciid [104.970977] [054/171] (896s left) kms_addfb_basic (addfb25-bad-modifier) [105.100371] Starting subtest: addfb25-bad-modifier [105.101455] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [105.237477] [055/171] (896s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [105.375232] Starting subtest: addfb25-framebuffer-vs-set-tiling [105.376387] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [105.526391] [056/171] (896s left) kms_addfb_basic (addfb25-modifier-no-flag) [105.662565] Starting subtest: addfb25-modifier-no-flag [105.663779] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [105.787278] [057/171] (895s left) kms_addfb_basic (addfb25-x-tiled-legacy) [105.926151] Starting subtest: addfb25-x-tiled-legacy [105.927222] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [106.054219] [058/171] (895s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [106.193973] Starting subtest: addfb25-x-tiled-mismatch-legacy [106.195259] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [106.320294] [059/171] (895s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [106.486507] Starting subtest: addfb25-yf-tiled-legacy [106.487681] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [106.605353] [060/171] (895s left) kms_addfb_basic (addfb25-y-tiled-legacy) [106.772252] Starting subtest: addfb25-y-tiled-legacy [106.773387] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [106.870260] [061/171] (895s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [107.037432] Starting subtest: addfb25-y-tiled-small-legacy [107.038713] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [107.136644] [062/171] (894s left) kms_addfb_basic (bad-pitch-0) [107.264791] Starting subtest: bad-pitch-0 [107.265991] Subtest bad-pitch-0: SUCCESS (0.000s) [107.386934] [063/171] (894s left) kms_addfb_basic (bad-pitch-1024) [107.524359] Starting subtest: bad-pitch-1024 [107.525509] Subtest bad-pitch-1024: SUCCESS (0.000s) [107.654143] [064/171] (894s left) kms_addfb_basic (bad-pitch-128) [107.791233] Starting subtest: bad-pitch-128 [107.792343] Subtest bad-pitch-128: SUCCESS (0.000s) [107.920261] [065/171] (894s left) kms_addfb_basic (bad-pitch-256) [108.058356] Starting subtest: bad-pitch-256 [108.059438] Subtest bad-pitch-256: SUCCESS (0.000s) [108.187261] [066/171] (893s left) kms_addfb_basic (bad-pitch-32) [108.327650] Starting subtest: bad-pitch-32 [108.328821] Subtest bad-pitch-32: SUCCESS (0.000s) [108.453995] [067/171] (893s left) kms_addfb_basic (bad-pitch-63) [108.590364] Starting subtest: bad-pitch-63 [108.591545] Subtest bad-pitch-63: SUCCESS (0.000s) [108.720727] [068/171] (893s left) kms_addfb_basic (bad-pitch-65536) [108.860233] Starting subtest: bad-pitch-65536 [108.861538] Subtest bad-pitch-65536: SUCCESS (0.000s) [108.987369] [069/171] (893s left) kms_addfb_basic (bad-pitch-999) [109.124712] Starting subtest: bad-pitch-999 [109.125917] Subtest bad-pitch-999: SUCCESS (0.000s) [109.275764] [070/171] (892s left) kms_addfb_basic (basic) [109.404815] Starting subtest: basic [109.406117] Subtest basic: SUCCESS (0.000s) [109.537828] [071/171] (892s left) kms_addfb_basic (basic-x-tiled-legacy) [109.675080] Starting subtest: basic-x-tiled-legacy [109.676254] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [109.803257] [072/171] (892s left) kms_addfb_basic (basic-y-tiled-legacy) [109.942418] Starting subtest: basic-y-tiled-legacy [109.943635] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [110.070856] [073/171] (892s left) kms_addfb_basic (bo-too-small) [110.234583] Starting subtest: bo-too-small [110.236235] Subtest bo-too-small: SUCCESS (0.000s) [110.337305] [074/171] (891s left) kms_addfb_basic (bo-too-small-due-to-tiling) [110.503693] Starting subtest: bo-too-small-due-to-tiling [110.504849] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [110.604282] [075/171] (891s left) kms_addfb_basic (clobberred-modifier) [110.741266] Starting subtest: clobberred-modifier [110.742437] Subtest clobberred-modifier: SUCCESS (0.000s) [110.870557] [076/171] (891s left) kms_addfb_basic (framebuffer-vs-set-tiling) [111.009781] Starting subtest: framebuffer-vs-set-tiling [111.011170] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [111.137269] [077/171] (891s left) kms_addfb_basic (invalid-get-prop) [111.274888] Starting subtest: invalid-get-prop [111.276106] Subtest invalid-get-prop: SUCCESS (0.000s) [111.403815] [078/171] (890s left) kms_addfb_basic (invalid-get-prop-any) [111.540367] Starting subtest: invalid-get-prop-any [111.541558] Subtest invalid-get-prop-any: SUCCESS (0.000s) [111.670885] [079/171] (890s left) kms_addfb_basic (invalid-set-prop) [111.807886] Starting subtest: invalid-set-prop [111.809158] Subtest invalid-set-prop: SUCCESS (0.000s) [111.937405] [080/171] (890s left) kms_addfb_basic (invalid-set-prop-any) [112.076501] Starting subtest: invalid-set-prop-any [112.077624] Subtest invalid-set-prop-any: SUCCESS (0.000s) [112.204244] [081/171] (890s left) kms_addfb_basic (no-handle) [112.344337] Starting subtest: no-handle [112.345424] Subtest no-handle: SUCCESS (0.000s) [112.491776] [082/171] (889s left) kms_addfb_basic (size-max) [112.649413] Starting subtest: size-max [112.650606] Subtest size-max: SUCCESS (0.000s) [112.753705] [083/171] (889s left) kms_addfb_basic (small-bo) [112.911449] Starting subtest: small-bo [112.912606] Subtest small-bo: SUCCESS (0.000s) [113.022565] [084/171] (889s left) kms_addfb_basic (tile-pitch-mismatch) [113.161403] Starting subtest: tile-pitch-mismatch [113.162493] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [113.287361] [085/171] (889s left) kms_addfb_basic (too-high) [113.453468] Starting subtest: too-high [113.454781] Subtest too-high: SUCCESS (0.001s) [113.553630] [086/171] (889s left) kms_addfb_basic (too-wide) [113.720425] Starting subtest: too-wide [113.721599] Subtest too-wide: SUCCESS (0.000s) [113.820683] [087/171] (888s left) kms_addfb_basic (unused-handle) [113.956629] Starting subtest: unused-handle [113.957896] Subtest unused-handle: SUCCESS (0.000s) [114.087600] [088/171] (888s left) kms_addfb_basic (unused-modifier) [114.225604] Starting subtest: unused-modifier [114.226787] Subtest unused-modifier: SUCCESS (0.000s) [114.354056] [089/171] (888s left) kms_addfb_basic (unused-offsets) [114.489656] Starting subtest: unused-offsets [114.491065] Subtest unused-offsets: SUCCESS (0.000s) [114.603449] [090/171] (888s left) kms_addfb_basic (unused-pitches) [114.740931] Starting subtest: unused-pitches [114.742132] Subtest unused-pitches: SUCCESS (0.000s) [114.870847] [091/171] (887s left) kms_busy (basic) [115.042242] Starting subtest: basic [115.044741] Starting dynamic subtest: flip [116.131087] Dynamic subtest flip: SUCCESS (1.080s) [116.147812] Starting dynamic subtest: modeset [120.052137] Dynamic subtest modeset: SUCCESS (3.922s) [120.053704] Subtest basic: SUCCESS (5.005s) [121.022874] [092/171] (881s left) kms_chamelium_hpd (dp-hpd-fast) [121.508751] Subtest dp-hpd-fast: SKIP (0.000s) [122.376772] [093/171] (880s left) kms_chamelium_edid (dp-edid-read) [122.860633] Subtest dp-edid-read: SKIP (0.000s) [123.721529] [094/171] (879s left) kms_chamelium_frames (dp-crc-fast) [124.220488] Subtest dp-crc-fast: SKIP (0.000s) [125.131158] [095/171] (877s left) kms_chamelium_hpd (hdmi-hpd-fast) [125.612633] Subtest hdmi-hpd-fast: SKIP (0.000s) [126.475324] [096/171] (876s left) kms_chamelium_edid (hdmi-edid-read) [126.958140] Subtest hdmi-edid-read: SKIP (0.000s) [127.841336] [097/171] (875s left) kms_chamelium_frames (hdmi-crc-fast) [128.321526] Subtest hdmi-crc-fast: SKIP (0.000s) [129.225492] [098/171] (873s left) kms_chamelium_hpd (vga-hpd-fast) [129.706843] Subtest vga-hpd-fast: SKIP (0.000s) [130.572084] [099/171] (872s left) kms_chamelium_edid (vga-edid-read) [131.051739] Subtest vga-edid-read: SKIP (0.000s) [131.921373] [100/171] (871s left) kms_prop_blob (basic) [132.058298] Starting subtest: basic [132.059471] Subtest basic: SUCCESS (0.000s) [132.140826] [101/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [132.305074] Starting subtest: basic-busy-flip-before-cursor-atomic [132.307413] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [132.385673] [102/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [132.550163] Starting subtest: basic-busy-flip-before-cursor-legacy [132.552459] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [132.635535] [103/171] (870s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [132.799351] Starting subtest: basic-flip-after-cursor-atomic [134.066526] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.267s) [135.036992] [104/171] (868s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [135.201209] Starting subtest: basic-flip-after-cursor-legacy [136.496811] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.295s) [137.466943] [105/171] (865s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [137.632435] Starting subtest: basic-flip-after-cursor-varying-size [138.914622] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.277s) [139.838177] [106/171] (863s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [140.005768] Starting subtest: basic-flip-before-cursor-atomic [141.297520] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.292s) [142.269024] [107/171] (860s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [142.435515] Starting subtest: basic-flip-before-cursor-legacy [143.726988] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.291s) [144.700635] [108/171] (858s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [144.865649] Starting subtest: basic-flip-before-cursor-varying-size [146.129725] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.264s) [147.068213] [109/171] (856s left) kms_flip (basic-flip-vs-dpms) [147.212742] Starting subtest: basic-flip-vs-dpms [147.215728] Starting dynamic subtest: A-eDP1 [150.087400] Dynamic subtest A-eDP1: SUCCESS (2.871s) [150.113792] Starting dynamic subtest: B-eDP1 [152.447093] Dynamic subtest B-eDP1: SUCCESS (2.353s) [152.462148] Starting dynamic subtest: C-eDP1 [154.810239] Dynamic subtest C-eDP1: SUCCESS (2.366s) [154.812968] Subtest basic-flip-vs-dpms: SUCCESS (7.600s) [155.716791] [110/171] (847s left) kms_flip (basic-flip-vs-modeset) [155.861305] Starting subtest: basic-flip-vs-modeset [155.864933] Starting dynamic subtest: A-eDP1 [158.723818] Dynamic subtest A-eDP1: SUCCESS (2.854s) [158.738929] Starting dynamic subtest: B-eDP1 [161.082457] Dynamic subtest B-eDP1: SUCCESS (2.358s) [161.097591] Starting dynamic subtest: C-eDP1 [163.454606] Dynamic subtest C-eDP1: SUCCESS (2.365s) [163.455892] Subtest basic-flip-vs-modeset: SUCCESS (7.586s) [164.416915] [111/171] (838s left) kms_flip (basic-flip-vs-wf_vblank) [164.560450] Starting subtest: basic-flip-vs-wf_vblank [164.564336] Starting dynamic subtest: A-eDP1 [166.875161] Dynamic subtest A-eDP1: SUCCESS (2.311s) [166.892596] Starting dynamic subtest: B-eDP1 [169.100263] Dynamic subtest B-eDP1: SUCCESS (2.224s) [169.119831] Starting dynamic subtest: C-eDP1 [171.340060] Dynamic subtest C-eDP1: SUCCESS (2.238s) [171.343265] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.783s) [172.666205] [112/171] (830s left) kms_flip (basic-plain-flip) [172.813545] Starting subtest: basic-plain-flip [172.814950] Starting dynamic subtest: A-eDP1 [174.848061] Dynamic subtest A-eDP1: SUCCESS (2.035s) [174.865143] Starting dynamic subtest: B-eDP1 [176.816410] Dynamic subtest B-eDP1: SUCCESS (1.967s) [176.835447] Starting dynamic subtest: C-eDP1 [178.799444] Dynamic subtest C-eDP1: SUCCESS (1.981s) [178.806676] Subtest basic-plain-flip: SUCCESS (5.993s) [180.094689] [113/171] (823s left) kms_force_connector_basic (force-connector-state) [180.238645] Starting subtest: force-connector-state [181.967390] Subtest force-connector-state: SUCCESS (1.728s) [182.579425] [114/171] (820s left) kms_force_connector_basic (force-edid) [182.721338] Starting subtest: force-edid [182.976023] Subtest force-edid: SUCCESS (0.252s) [183.111100] [115/171] (820s left) kms_force_connector_basic (force-load-detect) [183.245111] Starting subtest: force-load-detect [183.247882] Subtest force-load-detect: SKIP (0.000s) [183.377956] [116/171] (820s left) kms_force_connector_basic (prune-stale-modes) [183.519666] Starting subtest: prune-stale-modes [183.828198] Subtest prune-stale-modes: SUCCESS (0.304s) [183.961299] [117/171] (819s left) kms_frontbuffer_tracking (basic) [184.143287] Starting subtest: basic [187.409397] Subtest basic: SUCCESS (3.266s) [187.529254] [118/171] (815s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [187.699917] Starting subtest: compare-crc-sanitycheck-xr24 [187.702996] Starting dynamic subtest: pipe-A-eDP-1 [188.378220] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.673s) [188.402065] Starting dynamic subtest: pipe-B-eDP-1 [189.744748] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.364s) [189.763231] Starting dynamic subtest: pipe-C-eDP-1 [191.292105] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.544s) [191.293256] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.593s) [192.246875] [119/171] (811s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [192.414965] Starting subtest: compare-crc-sanitycheck-nv12 [192.418349] Starting dynamic subtest: pipe-A-eDP-1 [193.338703] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.921s) [193.358540] Starting dynamic subtest: pipe-B-eDP-1 [194.812761] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.462s) [194.828966] Starting dynamic subtest: pipe-C-eDP-1 [196.596464] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.780s) [196.597542] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.174s) [197.495822] [120/171] (806s left) kms_pipe_crc_basic (hang-read-crc) [197.663767] Starting subtest: hang-read-crc [197.665123] Starting dynamic subtest: pipe-A-eDP-1 [199.972379] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.307s) [199.992018] Starting dynamic subtest: pipe-B-eDP-1 [202.961986] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.984s) [202.980927] Starting dynamic subtest: pipe-C-eDP-1 [206.265668] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.300s) [206.266963] Subtest hang-read-crc: SUCCESS (8.604s) [207.222621] [121/171] (796s left) kms_pipe_crc_basic (nonblocking-crc) [207.391475] Starting subtest: nonblocking-crc [207.394763] Starting dynamic subtest: pipe-A-eDP-1 [208.249524] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.853s) [208.268940] Starting dynamic subtest: pipe-B-eDP-1 [209.808739] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.548s) [209.824519] Starting dynamic subtest: pipe-C-eDP-1 [211.513917] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.709s) [211.515209] Subtest nonblocking-crc: SUCCESS (4.122s) [212.470910] [122/171] (791s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [212.639330] Starting subtest: nonblocking-crc-frame-sequence [212.640465] Starting dynamic subtest: pipe-A-eDP-1 [213.495159] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.854s) [213.516428] Starting dynamic subtest: pipe-B-eDP-1 [215.047617] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.548s) [215.066794] Starting dynamic subtest: pipe-C-eDP-1 [216.773656] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.722s) [216.774841] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.136s) [217.717863] [123/171] (785s left) kms_pipe_crc_basic (read-crc) [217.884043] Starting subtest: read-crc [217.887443] Starting dynamic subtest: pipe-A-eDP-1 [218.676312] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.788s) [218.695394] Starting dynamic subtest: pipe-B-eDP-1 [220.166429] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.486s) [220.185571] Starting dynamic subtest: pipe-C-eDP-1 [221.817493] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.648s) [221.818796] Subtest read-crc: SUCCESS (3.935s) [222.774647] [124/171] (780s left) kms_pipe_crc_basic (read-crc-frame-sequence) [222.944360] Starting subtest: read-crc-frame-sequence [222.947782] Starting dynamic subtest: pipe-A-eDP-1 [223.732970] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.786s) [223.752121] Starting dynamic subtest: pipe-B-eDP-1 [225.222330] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.483s) [225.239677] Starting dynamic subtest: pipe-C-eDP-1 [226.870630] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.646s) [226.871873] Subtest read-crc-frame-sequence: SUCCESS (3.928s) [227.830363] [125/171] (775s left) kms_psr (primary_page_flip) [228.002083] Starting subtest: primary_page_flip [232.190706] Subtest primary_page_flip: SUCCESS (4.189s) [233.142861] [126/171] (770s left) kms_psr (cursor_plane_move) [233.312467] Starting subtest: cursor_plane_move [237.403031] Subtest cursor_plane_move: SUCCESS (4.090s) [238.332067] [127/171] (765s left) kms_psr (sprite_plane_onoff) [238.503542] Starting subtest: sprite_plane_onoff [242.752356] Subtest sprite_plane_onoff: SUCCESS (4.249s) [243.702679] [128/171] (760s left) kms_psr (primary_mmap_gtt) [243.873070] Starting subtest: primary_mmap_gtt [248.054068] Subtest primary_mmap_gtt: SUCCESS (4.171s) [249.015177] [129/171] (754s left) kms_setmode (basic-clone-single-crtc) [249.157930] Starting subtest: basic-clone-single-crtc [249.161344] Subtest basic-clone-single-crtc: SKIP (0.000s) [249.247834] [130/171] (754s left) i915_pm_backlight (basic-brightness) [249.416071] Starting subtest: basic-brightness [249.513066] Starting dynamic subtest: eDP-1 [250.074775] Dynamic subtest eDP-1: SUCCESS (0.562s) [250.076348] Subtest basic-brightness: SUCCESS (0.660s) [250.998878] [131/171] (752s left) i915_pm_rpm (basic-pci-d3-state) [252.869872] Starting subtest: basic-pci-d3-state [257.232990] Subtest basic-pci-d3-state: SUCCESS (4.362s) [257.436186] [132/171] (746s left) i915_pm_rpm (basic-rte) [257.571770] Starting subtest: basic-rte [263.696682] Subtest basic-rte: SUCCESS (6.124s) [265.171242] [133/171] (738s left) i915_pm_rps (basic-api) [265.317029] Starting subtest: basic-api [265.321360] Subtest basic-api: SUCCESS (0.004s) [265.419980] [134/171] (738s left) prime_self_import (basic-llseek-bad) [265.555505] Starting subtest: basic-llseek-bad [265.560359] Subtest basic-llseek-bad: SUCCESS (0.003s) [265.669888] [135/171] (738s left) prime_self_import (basic-llseek-size) [265.804413] Starting subtest: basic-llseek-size [265.809175] Subtest basic-llseek-size: SUCCESS (0.004s) [265.903233] [136/171] (738s left) prime_self_import (basic-with_fd_dup) [266.038348] Starting subtest: basic-with_fd_dup [266.043369] Subtest basic-with_fd_dup: SUCCESS (0.005s) [266.153233] [137/171] (737s left) prime_self_import (basic-with_one_bo) [266.295157] Starting subtest: basic-with_one_bo [266.300507] Subtest basic-with_one_bo: SUCCESS (0.005s) [266.384903] [138/171] (737s left) prime_self_import (basic-with_one_bo_two_files) [266.519730] Starting subtest: basic-with_one_bo_two_files [266.524747] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [266.636840] [139/171] (737s left) prime_self_import (basic-with_two_bos) [266.771375] Starting subtest: basic-with_two_bos [266.776355] Subtest basic-with_two_bos: SUCCESS (0.005s) [266.886943] [140/171] (737s left) prime_vgem (basic-fence-flip) [267.039922] Starting subtest: basic-fence-flip [267.514260] Subtest basic-fence-flip: SUCCESS (0.474s) [267.637733] [141/171] (736s left) prime_vgem (basic-fence-mmap) [267.801852] Starting subtest: basic-fence-mmap [267.850244] Subtest basic-fence-mmap: SUCCESS (0.048s) [267.937465] [142/171] (736s left) prime_vgem (basic-fence-read) [268.103806] Starting subtest: basic-fence-read [268.178215] Subtest basic-fence-read: SUCCESS (0.074s) [268.271203] [143/171] (736s left) prime_vgem (basic-gtt) [268.442604] Starting subtest: basic-gtt [268.460522] Subtest basic-gtt: SUCCESS (0.022s) [268.553773] [144/171] (735s left) prime_vgem (basic-read) [268.712761] Starting subtest: basic-read [268.748464] Subtest basic-read: SUCCESS (0.036s) [268.836891] [145/171] (735s left) prime_vgem (basic-write) [268.995466] Starting subtest: basic-write [269.038165] Subtest basic-write: SUCCESS (0.042s) [269.121489] [146/171] (735s left) vgem_basic (setversion) [269.265664] Starting subtest: setversion [269.268275] Subtest setversion: SUCCESS (0.000s) [269.336614] [147/171] (735s left) vgem_basic (create) [269.482489] Starting subtest: create [269.483822] Subtest create: SUCCESS (0.000s) [269.553524] [148/171] (734s left) vgem_basic (debugfs) [269.699397] Starting subtest: debugfs [269.700834] Subtest debugfs: SUCCESS (0.001s) [269.770351] [149/171] (734s left) vgem_basic (dmabuf-export) [269.915722] Starting subtest: dmabuf-export [269.918487] Subtest dmabuf-export: SUCCESS (0.003s) [270.019883] [150/171] (734s left) vgem_basic (dmabuf-fence) [270.165804] Starting subtest: dmabuf-fence [270.167168] Subtest dmabuf-fence: SUCCESS (0.001s) [270.237071] [151/171] (734s left) vgem_basic (dmabuf-fence-before) [270.384698] Starting subtest: dmabuf-fence-before [270.385862] Subtest dmabuf-fence-before: SUCCESS (0.000s) [270.454930] [152/171] (734s left) vgem_basic (dmabuf-mmap) [270.601707] Starting subtest: dmabuf-mmap [270.626432] Subtest dmabuf-mmap: SUCCESS (0.027s) [270.704932] [153/171] (733s left) vgem_basic (mmap) [270.851755] Starting subtest: mmap [270.866408] Subtest mmap: SUCCESS (0.017s) [270.937695] [154/171] (733s left) vgem_basic (second-client) [271.084795] Starting subtest: second-client [271.118742] Subtest second-client: SUCCESS (0.033s) [271.188056] [155/171] (733s left) vgem_basic (sysfs) [271.334801] Starting subtest: sysfs [271.336074] Subtest sysfs: SUCCESS (0.001s) [271.404089] [156/171] (733s left) core_hotunplug (unbind-rebind) [271.583824] Starting subtest: unbind-rebind [274.060652] Subtest unbind-rebind: SUCCESS (2.475s) [274.119296] [157/171] (730s left) vgem_basic (unload) [274.258232] Starting subtest: unload [303.316601] Subtest unload: SUCCESS (29.022s) [303.431524] [158/171] (701s left) i915_module_load (reload) [303.562801] Starting subtest: reload [307.165266] Subtest reload: SUCCESS (3.602s) [307.196953] [159/171] (697s left) gem_lmem_swapping (basic) [307.350987] Subtest basic: SKIP (0.000s) [307.400663] [160/171] (697s left) gem_lmem_swapping (parallel-random-engines) [307.536375] Subtest parallel-random-engines: SKIP (0.000s) [307.584543] [161/171] (697s left) gem_lmem_swapping (random-engines) [307.728538] Subtest random-engines: SKIP (0.000s) [307.787081] [162/171] (697s left) gem_lmem_swapping (verify-random) [307.930487] Subtest verify-random: SKIP (0.000s) [307.983049] [163/171] (696s left) i915_pm_rpm (module-reload) [320.511403] Starting subtest: module-reload [355.474894] Subtest module-reload: SUCCESS (34.957s) [355.501477] [164/171] (649s left) i915_selftest (live) [355.646725] Starting subtest: live [355.648068] Starting dynamic subtest: sanitycheck [357.524600] Dynamic subtest sanitycheck: SUCCESS (1.870s) [357.538974] Starting dynamic subtest: uncore [359.399442] Dynamic subtest uncore: SUCCESS (1.871s) [359.413622] Starting dynamic subtest: workarounds [361.526587] Dynamic subtest workarounds: SUCCESS (2.123s) [361.541102] Starting dynamic subtest: gt_engines [363.864683] Dynamic subtest gt_engines: SUCCESS (2.335s) [363.879164] Starting dynamic subtest: gt_timelines [376.931427] Dynamic subtest gt_timelines: SUCCESS (13.063s) [376.946068] Starting dynamic subtest: gt_contexts [378.949408] Dynamic subtest gt_contexts: SUCCESS (2.015s) [378.963818] Starting dynamic subtest: gt_lrc [383.364759] Dynamic subtest gt_lrc: SUCCESS (4.412s) [383.379428] Starting dynamic subtest: gt_mocs [385.322437] Dynamic subtest gt_mocs: SUCCESS (1.954s) [385.337122] Starting dynamic subtest: gt_pm [390.475633] Dynamic subtest gt_pm: SUCCESS (5.150s) [390.490045] Starting dynamic subtest: gt_heartbeat [392.425534] Dynamic subtest gt_heartbeat: SUCCESS (1.946s) [392.440170] Starting dynamic subtest: gt_tlb [395.579717] Dynamic subtest gt_tlb: SUCCESS (3.151s) [395.593991] Starting dynamic subtest: requests [403.244500] Dynamic subtest requests: SUCCESS (7.661s) [403.259073] Starting dynamic subtest: migrate [407.828495] Dynamic subtest migrate: SUCCESS (4.580s) [407.843247] Starting dynamic subtest: active [409.691392] Dynamic subtest active: SUCCESS (1.860s) [409.706120] Starting dynamic subtest: objects [413.151734] Dynamic subtest objects: SUCCESS (3.457s) [413.166055] Starting dynamic subtest: mman [420.156642] Dynamic subtest mman: SUCCESS (7.001s) [420.171145] Starting dynamic subtest: dmabuf [422.060162] Dynamic subtest dmabuf: SUCCESS (1.899s) [422.075263] Starting dynamic subtest: vma [423.973628] Dynamic subtest vma: SUCCESS (1.910s) [423.988459] Starting dynamic subtest: coherency [428.537778] Dynamic subtest coherency: SUCCESS (4.560s) [428.552501] Starting dynamic subtest: gtt [438.181412] Dynamic subtest gtt: SUCCESS (9.640s) [438.196218] Starting dynamic subtest: gem [440.181523] Dynamic subtest gem: SUCCESS (1.997s) [440.196318] Starting dynamic subtest: evict [444.084428] Dynamic subtest evict: SUCCESS (3.899s) [444.099386] Starting dynamic subtest: hugepages [451.880460] Dynamic subtest hugepages: SUCCESS (7.793s) [451.895431] Starting dynamic subtest: gem_contexts [467.424844] Dynamic subtest gem_contexts: SUCCESS (15.514s) [467.440188] Starting dynamic subtest: client [469.283519] Dynamic subtest client: SUCCESS (1.882s) [469.298845] Starting dynamic subtest: gem_migrate [471.130549] Dynamic subtest gem_migrate: SUCCESS (1.844s) [471.145811] Starting dynamic subtest: reset [476.544742] Dynamic subtest reset: SUCCESS (5.411s) [476.560280] Starting dynamic subtest: memory_region [478.418457] Dynamic subtest memory_region: SUCCESS (1.871s) [478.433962] Starting dynamic subtest: hangcheck [502.829654] Dynamic subtest hangcheck: SUCCESS (24.408s) [502.845158] Starting dynamic subtest: execlists [512.986718] Dynamic subtest execlists: SUCCESS (10.154s) [513.002285] Starting dynamic subtest: ring_submission [514.859783] Dynamic subtest ring_submission: SUCCESS (1.870s) [514.875049] Starting dynamic subtest: perf [516.846589] Dynamic subtest perf: SUCCESS (1.983s) [516.861959] Starting dynamic subtest: slpc [518.688686] Dynamic subtest slpc: SUCCESS (1.839s) [518.704318] Starting dynamic subtest: guc [520.552421] Dynamic subtest guc: SUCCESS (1.860s) [520.567969] Starting dynamic subtest: guc_multi_lrc [522.414533] Dynamic subtest guc_multi_lrc: SUCCESS (1.859s) [522.429912] Starting dynamic subtest: guc_hang [524.271495] Dynamic subtest guc_hang: SUCCESS (1.854s) [524.286882] Starting dynamic subtest: late_gt_pm [526.172514] Dynamic subtest late_gt_pm: SUCCESS (1.898s) [526.173843] Subtest live: SUCCESS (170.521s) [526.365116] [165/171] (478s left) dmabuf (all-tests) [526.503715] Starting subtest: all-tests [526.505221] Starting dynamic subtest: sanitycheck [526.572064] Dynamic subtest sanitycheck: SUCCESS (0.066s) [526.591497] Starting dynamic subtest: dma_fence [526.766989] Dynamic subtest dma_fence: SUCCESS (0.191s) [526.787081] Starting dynamic subtest: dma_fence_chain [544.523358] Dynamic subtest dma_fence_chain: SUCCESS (17.744s) [544.536452] Starting dynamic subtest: dma_fence_unwrap [544.589848] Dynamic subtest dma_fence_unwrap: SUCCESS (0.071s) [544.609725] Starting dynamic subtest: dma_resv [544.662960] Dynamic subtest dma_resv: SUCCESS (0.069s) [544.665548] Subtest all-tests: SUCCESS (18.160s) [544.696794] [166/171] (460s left) i915_suspend (basic-s2idle-without-i915) [544.833661] Starting subtest: basic-s2idle-without-i915 [568.918627] Subtest basic-s2idle-without-i915: SUCCESS (8.434s) [569.214532] [167/171] (451s left) i915_suspend (basic-s3-without-i915) [569.353636] Starting subtest: basic-s3-without-i915 [592.992002] Subtest basic-s3-without-i915: SUCCESS (7.165s) [593.295332] [168/171] (443s left) gem_exec_suspend (basic-s0) [593.453810] Starting subtest: basic-S0 [593.455757] Starting dynamic subtest: smem [613.244360] Dynamic subtest smem: SUCCESS (3.985s) [613.245522] Subtest basic-S0: SUCCESS (3.987s) [613.453957] [169/171] (439s left) gem_exec_suspend (basic-s3) [613.601702] Starting subtest: basic-S3 [613.602918] Starting dynamic subtest: smem [632.144489] Dynamic subtest smem: SUCCESS (4.087s) [632.145788] Subtest basic-S3: SUCCESS (4.089s) [632.301148] [170/171] (435s left) kms_chamelium_hpd (common-hpd-after-suspend) [632.804728] Subtest common-hpd-after-suspend: SKIP (0.000s) [633.718167] [171/171] (433s left) kms_pipe_crc_basic (suspend-read-crc) [633.881330] Starting subtest: suspend-read-crc [633.884441] Starting dynamic subtest: pipe-A-eDP-1 [652.031300] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.449s) [652.053993] Starting dynamic subtest: pipe-B-eDP-1 [669.994791] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.082s) [670.018511] Starting dynamic subtest: pipe-C-eDP-1 [688.987124] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.278s) [688.988454] Subtest suspend-read-crc: SUCCESS (8.821s) [689.861868] Closing watchdogs Done.