[48.099911] Initializing watchdogs [48.100055] /dev/watchdog0 [48.106930] [001/170] (960s left) i915_module_load (load) [48.211447] Starting subtest: load [49.232847] Subtest load: SUCCESS (1.021s) [49.262815] [002/170] (959s left) core_auth (basic-auth) [49.505477] Starting subtest: basic-auth [49.509837] Subtest basic-auth: SUCCESS (0.001s) [49.577988] [003/170] (959s left) debugfs_test (read_all_entries) [49.691087] Starting subtest: read_all_entries [49.775861] Subtest read_all_entries: SUCCESS (0.084s) [49.876937] [004/170] (958s left) debugfs_test (basic-hwmon) [49.996188] Starting subtest: basic-hwmon [49.997911] Subtest basic-hwmon: SKIP (0.000s) [50.111283] [005/170] (958s left) fbdev (eof) [50.222864] Starting subtest: eof [50.223927] Subtest eof: SUCCESS (0.000s) [50.277587] [006/170] (958s left) fbdev (info) [50.376143] Starting subtest: info [50.377072] Subtest info: SUCCESS (0.000s) [50.448877] [007/170] (958s left) fbdev (nullptr) [50.556546] Starting subtest: nullptr [50.557547] Subtest nullptr: SUCCESS (0.000s) [50.611105] [008/170] (958s left) fbdev (read) [50.723501] Starting subtest: read [51.571232] Subtest read: SUCCESS (0.839s) [51.611538] [009/170] (957s left) fbdev (write) [51.723301] Starting subtest: write [52.144561] Subtest write: SUCCESS (0.412s) [52.184107] [010/170] (956s left) gem_basic (bad-close) [52.283450] Starting subtest: bad-close [52.285333] Subtest bad-close: SUCCESS (0.000s) [52.360883] [011/170] (956s left) gem_basic (create-close) [52.471038] Starting subtest: create-close [52.472957] Subtest create-close: SUCCESS (0.000s) [52.585542] [012/170] (956s left) gem_basic (create-fd-close) [52.695212] Starting subtest: create-fd-close [52.697126] Subtest create-fd-close: SUCCESS (0.000s) [52.826614] [013/170] (956s left) gem_busy (busy@all-engines) [52.943508] Starting subtest: busy [52.944496] Starting dynamic subtest: all-engines [52.997811] Dynamic subtest all-engines: SUCCESS (0.054s) [52.998896] Subtest busy: SUCCESS (0.054s) [53.437298] [014/170] (955s left) gem_close_race (basic-process) [53.559444] Starting subtest: basic-process [53.632671] Subtest basic-process: SUCCESS (0.073s) [53.847348] [015/170] (955s left) gem_close_race (basic-threads) [53.966229] Starting subtest: basic-threads [55.126901] Subtest basic-threads: SUCCESS (1.160s) [55.356783] [016/170] (953s left) gem_ctx_create (basic) [55.474630] Starting subtest: basic [55.475622] Subtest basic: SUCCESS (0.000s) [55.690166] [017/170] (953s left) gem_ctx_create (basic-files) [55.803903] Starting subtest: basic-files [57.825845] Subtest basic-files: SUCCESS (2.022s) [58.110933] [018/170] (950s left) gem_ctx_exec (basic) [58.216770] Starting subtest: basic [58.222716] Subtest basic: SUCCESS (0.006s) [58.354455] [019/170] (950s left) gem_exec_basic (basic) [58.479806] Starting subtest: basic [58.480961] Starting dynamic subtest: rcs0-smem [58.483046] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [58.490647] Starting dynamic subtest: bcs0-smem [58.491511] Dynamic subtest bcs0-smem: SUCCESS (0.002s) [58.498571] Starting dynamic subtest: vcs0-smem [58.499799] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [58.506498] Starting dynamic subtest: vecs0-smem [58.507668] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [58.508882] Subtest basic: SUCCESS (0.007s) [58.721105] [020/170] (950s left) gem_exec_create (basic) [58.837239] Starting subtest: basic [58.838310] Starting dynamic subtest: smem [60.880386] Dynamic subtest smem: SUCCESS (2.043s) [60.881302] Subtest basic: SUCCESS (2.043s) [61.114189] [021/170] (947s left) gem_exec_fence (basic-busy) [61.384679] Starting subtest: basic-busy [61.387593] Starting dynamic subtest: rcs0 [61.424768] Dynamic subtest rcs0: SUCCESS (0.040s) [61.436704] Starting dynamic subtest: bcs0 [61.450465] Dynamic subtest bcs0: SUCCESS (0.026s) [61.459526] Starting dynamic subtest: vcs0 [61.475404] Dynamic subtest vcs0: SUCCESS (0.025s) [61.484556] Starting dynamic subtest: vecs0 [61.496371] Dynamic subtest vecs0: SUCCESS (0.021s) [61.497534] Subtest basic-busy: SUCCESS (0.113s) [62.180198] [022/170] (946s left) gem_exec_fence (basic-wait) [62.449090] Starting subtest: basic-wait [62.450566] Starting dynamic subtest: rcs0 [62.477399] Dynamic subtest rcs0: SUCCESS (0.029s) [62.487217] Starting dynamic subtest: bcs0 [62.499412] Dynamic subtest bcs0: SUCCESS (0.022s) [62.509072] Starting dynamic subtest: vcs0 [62.520261] Dynamic subtest vcs0: SUCCESS (0.021s) [62.528223] Starting dynamic subtest: vecs0 [62.548137] Dynamic subtest vecs0: SUCCESS (0.028s) [62.549061] Subtest basic-wait: SUCCESS (0.100s) [63.226594] [023/170] (945s left) gem_exec_fence (basic-await) [63.492470] Starting subtest: basic-await [63.493642] Starting dynamic subtest: rcs0 [63.594465] Dynamic subtest rcs0: SUCCESS (0.102s) [63.602495] Starting dynamic subtest: bcs0 [63.691450] Dynamic subtest bcs0: SUCCESS (0.097s) [63.702157] Starting dynamic subtest: vcs0 [63.802996] Dynamic subtest vcs0: SUCCESS (0.111s) [63.813613] Starting dynamic subtest: vecs0 [63.918667] Dynamic subtest vecs0: SUCCESS (0.116s) [63.919799] Subtest basic-await: SUCCESS (0.427s) [64.749695] [024/170] (944s left) gem_exec_fence (nb-await) [65.024423] Starting subtest: nb-await [65.025963] Starting dynamic subtest: rcs0 [65.096400] Dynamic subtest rcs0: SUCCESS (0.069s) [65.126285] Starting dynamic subtest: bcs0 [65.184839] Dynamic subtest bcs0: SUCCESS (0.088s) [65.219988] Starting dynamic subtest: vcs0 [65.275268] Dynamic subtest vcs0: SUCCESS (0.092s) [65.308576] Starting dynamic subtest: vecs0 [65.366095] Dynamic subtest vecs0: SUCCESS (0.089s) [65.369656] Subtest nb-await: SUCCESS (0.340s) [66.050779] [025/170] (943s left) gem_exec_gttfill (basic) [66.176200] Starting subtest: basic [75.541960] Subtest basic: SUCCESS (9.357s) [75.733776] [026/170] (933s left) gem_exec_parallel (engines) [75.908561] Starting subtest: engines [75.909632] Starting dynamic subtest: basic [78.279237] Dynamic subtest basic: SUCCESS (2.369s) [78.301131] Starting dynamic subtest: contexts [81.211213] Dynamic subtest contexts: SUCCESS (2.923s) [81.235435] Starting dynamic subtest: fds [84.366899] Dynamic subtest fds: SUCCESS (3.165s) [84.376241] Starting dynamic subtest: userptr [86.756845] Dynamic subtest userptr: SUCCESS (2.389s) [86.758544] Subtest engines: SUCCESS (10.848s) [86.979939] [027/170] (922s left) gem_exec_store (basic) [87.101989] Starting subtest: basic [87.153712] Subtest basic: SUCCESS (0.052s) [87.377800] [028/170] (921s left) gem_flink_basic (bad-flink) [87.485945] Starting subtest: bad-flink [87.486960] Subtest bad-flink: SUCCESS (0.000s) [87.579759] [029/170] (921s left) gem_flink_basic (bad-open) [87.687209] Starting subtest: bad-open [87.688279] Subtest bad-open: SUCCESS (0.000s) [87.783200] [030/170] (921s left) gem_flink_basic (basic) [87.889409] Starting subtest: basic [87.890486] Subtest basic: SUCCESS (0.000s) [87.992561] [031/170] (921s left) gem_flink_basic (double-flink) [88.098059] Starting subtest: double-flink [88.099116] Subtest double-flink: SUCCESS (0.000s) [88.205996] [032/170] (921s left) gem_flink_basic (flink-lifetime) [88.311533] Starting subtest: flink-lifetime [88.312674] Subtest flink-lifetime: SUCCESS (0.001s) [88.410457] [033/170] (920s left) gem_huc_copy (huc-copy) [88.521568] Starting subtest: huc-copy [88.523668] Subtest huc-copy: SKIP (0.000s) [88.665034] [034/170] (920s left) gem_linear_blits (basic) [88.932058] Starting subtest: basic [88.944067] Subtest basic: SUCCESS (0.012s) [89.100856] [035/170] (920s left) gem_mmap (basic) [89.267477] Starting subtest: basic [89.272914] Subtest basic: SUCCESS (0.000s) [89.348281] [036/170] (919s left) gem_mmap_gtt (basic) [89.455921] Starting subtest: basic [89.457054] Subtest basic: SUCCESS (0.001s) [89.559203] [037/170] (919s left) gem_render_linear_blits (basic) [89.674846] Starting subtest: basic [89.714558] Subtest basic: SUCCESS (0.040s) [89.771347] [038/170] (919s left) gem_render_tiled_blits (basic) [89.885839] Starting subtest: basic [89.937299] Subtest basic: SUCCESS (0.049s) [89.988494] [039/170] (919s left) gem_ringfill (basic-all) [90.285724] Starting subtest: basic-all [91.467189] Subtest basic-all: SUCCESS (1.177s) [91.577939] [040/170] (917s left) gem_softpin (allocator-basic) [91.702358] Starting subtest: allocator-basic [91.843016] Subtest allocator-basic: SUCCESS (0.132s) [91.961171] [041/170] (917s left) gem_softpin (allocator-basic-reserve) [92.082039] Starting subtest: allocator-basic-reserve [92.223661] Subtest allocator-basic-reserve: SUCCESS (0.133s) [92.355738] [042/170] (917s left) gem_softpin (safe-alignment) [92.466027] Starting subtest: safe-alignment [92.482075] Subtest safe-alignment: SUCCESS (0.015s) [92.622355] [043/170] (916s left) gem_sync (basic-all) [92.748062] Starting subtest: basic-all [94.939660] Subtest basic-all: SUCCESS (2.192s) [95.175575] [044/170] (914s left) gem_sync (basic-each) [95.297380] Starting subtest: basic-each [97.521402] Subtest basic-each: SUCCESS (2.224s) [97.780626] [045/170] (911s left) gem_tiled_blits (basic) [98.029458] Starting subtest: basic [98.055163] Subtest basic: SUCCESS (0.026s) [98.202495] [046/170] (911s left) gem_tiled_fence_blits (basic) [98.530800] Starting subtest: basic [98.543934] Subtest basic: SUCCESS (0.013s) [98.681615] [047/170] (910s left) gem_tiled_pread_basic [99.064740] [048/170] (910s left) gem_wait (busy@all-engines) [99.192766] Starting subtest: busy [99.193957] Starting dynamic subtest: all-engines [99.731150] Dynamic subtest all-engines: SUCCESS (0.538s) [99.733068] Subtest busy: SUCCESS (0.538s) [99.981813] [049/170] (909s left) gem_wait (wait@all-engines) [100.129339] Starting subtest: wait [100.130296] Starting dynamic subtest: all-engines [101.181505] Dynamic subtest all-engines: SUCCESS (1.051s) [101.184294] Subtest wait: SUCCESS (1.052s) [101.444410] [050/170] (908s left) i915_getparams_basic (basic-eu-total) [101.550169] Starting subtest: basic-eu-total [101.552451] Subtest basic-eu-total: SUCCESS (0.000s) [101.647149] [051/170] (908s left) i915_getparams_basic (basic-subslice-total) [101.753620] Starting subtest: basic-subslice-total [101.755677] Subtest basic-subslice-total: SUCCESS (0.000s) [101.859021] [052/170] (907s left) i915_hangman (error-state-basic) [101.983771] Starting subtest: error-state-basic [101.990860] Subtest error-state-basic: SUCCESS (0.007s) [102.079937] [053/170] (907s left) i915_pciid [102.287553] [054/170] (907s left) kms_addfb_basic (addfb25-bad-modifier) [102.393427] Starting subtest: addfb25-bad-modifier [102.394416] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [102.563404] [055/170] (907s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [102.674175] Starting subtest: addfb25-framebuffer-vs-set-tiling [102.675090] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [102.831730] [056/170] (906s left) kms_addfb_basic (addfb25-modifier-no-flag) [102.942523] Starting subtest: addfb25-modifier-no-flag [102.943628] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [103.078541] [057/170] (906s left) kms_addfb_basic (addfb25-x-tiled-legacy) [103.182516] Starting subtest: addfb25-x-tiled-legacy [103.183877] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [103.317127] [058/170] (906s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [103.423375] Starting subtest: addfb25-x-tiled-mismatch-legacy [103.424450] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [103.556168] [059/170] (906s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [103.704629] Starting subtest: addfb25-yf-tiled-legacy [103.705827] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [103.796180] [060/170] (906s left) kms_addfb_basic (addfb25-y-tiled-legacy) [103.938770] Starting subtest: addfb25-y-tiled-legacy [103.942029] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [104.046822] [061/170] (905s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [104.189488] Starting subtest: addfb25-y-tiled-small-legacy [104.190522] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [104.288188] [062/170] (905s left) kms_addfb_basic (bad-pitch-0) [104.388488] Starting subtest: bad-pitch-0 [104.389590] Subtest bad-pitch-0: SUCCESS (0.000s) [104.518507] [063/170] (905s left) kms_addfb_basic (bad-pitch-1024) [104.619931] Starting subtest: bad-pitch-1024 [104.621332] Subtest bad-pitch-1024: SUCCESS (0.000s) [104.752425] [064/170] (905s left) kms_addfb_basic (bad-pitch-128) [104.852453] Starting subtest: bad-pitch-128 [104.853420] Subtest bad-pitch-128: SUCCESS (0.000s) [104.996042] [065/170] (904s left) kms_addfb_basic (bad-pitch-256) [105.093547] Starting subtest: bad-pitch-256 [105.094628] Subtest bad-pitch-256: SUCCESS (0.000s) [105.234188] [066/170] (904s left) kms_addfb_basic (bad-pitch-32) [105.330709] Starting subtest: bad-pitch-32 [105.331856] Subtest bad-pitch-32: SUCCESS (0.000s) [105.465692] [067/170] (904s left) kms_addfb_basic (bad-pitch-63) [105.565466] Starting subtest: bad-pitch-63 [105.566497] Subtest bad-pitch-63: SUCCESS (0.000s) [105.708944] [068/170] (904s left) kms_addfb_basic (bad-pitch-65536) [105.809304] Starting subtest: bad-pitch-65536 [105.810363] Subtest bad-pitch-65536: SUCCESS (0.000s) [105.938442] [069/170] (904s left) kms_addfb_basic (bad-pitch-999) [106.035453] Starting subtest: bad-pitch-999 [106.036478] Subtest bad-pitch-999: SUCCESS (0.000s) [106.164389] [070/170] (903s left) kms_addfb_basic (basic) [106.261448] Starting subtest: basic [106.262455] Subtest basic: SUCCESS (0.000s) [106.389767] [071/170] (903s left) kms_addfb_basic (basic-x-tiled-legacy) [106.488086] Starting subtest: basic-x-tiled-legacy [106.488948] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [106.628506] [072/170] (903s left) kms_addfb_basic (basic-y-tiled-legacy) [106.726603] Starting subtest: basic-y-tiled-legacy [106.727726] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [106.859293] [073/170] (903s left) kms_addfb_basic (bo-too-small) [106.991498] Starting subtest: bo-too-small [106.992453] Subtest bo-too-small: SUCCESS (0.000s) [107.086404] [074/170] (903s left) kms_addfb_basic (bo-too-small-due-to-tiling) [107.223227] Starting subtest: bo-too-small-due-to-tiling [107.224196] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [107.320029] [075/170] (902s left) kms_addfb_basic (clobberred-modifier) [107.416315] Starting subtest: clobberred-modifier [107.417490] Subtest clobberred-modifier: SUCCESS (0.000s) [107.552048] [076/170] (902s left) kms_addfb_basic (framebuffer-vs-set-tiling) [107.646361] Starting subtest: framebuffer-vs-set-tiling [107.647370] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [107.775338] [077/170] (902s left) kms_addfb_basic (invalid-get-prop) [107.872572] Starting subtest: invalid-get-prop [107.873580] Subtest invalid-get-prop: SUCCESS (0.000s) [108.004691] [078/170] (902s left) kms_addfb_basic (invalid-get-prop-any) [108.101859] Starting subtest: invalid-get-prop-any [108.102907] Subtest invalid-get-prop-any: SUCCESS (0.000s) [108.235812] [079/170] (901s left) kms_addfb_basic (invalid-set-prop) [108.331840] Starting subtest: invalid-set-prop [108.332881] Subtest invalid-set-prop: SUCCESS (0.000s) [108.468379] [080/170] (901s left) kms_addfb_basic (invalid-set-prop-any) [108.561188] Starting subtest: invalid-set-prop-any [108.562211] Subtest invalid-set-prop-any: SUCCESS (0.000s) [108.687750] [081/170] (901s left) kms_addfb_basic (no-handle) [108.784591] Starting subtest: no-handle [108.785644] Subtest no-handle: SUCCESS (0.000s) [108.916166] [082/170] (901s left) kms_addfb_basic (size-max) [109.048432] Starting subtest: size-max [109.049448] Subtest size-max: SUCCESS (0.000s) [109.142792] [083/170] (901s left) kms_addfb_basic (small-bo) [109.272134] Starting subtest: small-bo [109.273168] Subtest small-bo: SUCCESS (0.000s) [109.369951] [084/170] (900s left) kms_addfb_basic (tile-pitch-mismatch) [109.467416] Starting subtest: tile-pitch-mismatch [109.468531] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [109.595433] [085/170] (900s left) kms_addfb_basic (too-high) [109.731261] Starting subtest: too-high [109.732235] Subtest too-high: SUCCESS (0.001s) [109.830787] [086/170] (900s left) kms_addfb_basic (too-wide) [109.960469] Starting subtest: too-wide [109.961529] Subtest too-wide: SUCCESS (0.000s) [110.070361] [087/170] (900s left) kms_addfb_basic (unused-handle) [110.165434] Starting subtest: unused-handle [110.166514] Subtest unused-handle: SUCCESS (0.000s) [110.297733] [088/170] (900s left) kms_addfb_basic (unused-modifier) [110.393876] Starting subtest: unused-modifier [110.395038] Subtest unused-modifier: SUCCESS (0.000s) [110.527513] [089/170] (899s left) kms_addfb_basic (unused-offsets) [110.624485] Starting subtest: unused-offsets [110.625423] Subtest unused-offsets: SUCCESS (0.000s) [110.757907] [090/170] (899s left) kms_addfb_basic (unused-pitches) [110.854750] Starting subtest: unused-pitches [110.855805] Subtest unused-pitches: SUCCESS (0.000s) [110.981929] [091/170] (899s left) kms_busy (basic) [111.125220] Starting subtest: basic [111.126244] Starting dynamic subtest: flip [112.027407] Dynamic subtest flip: SUCCESS (0.899s) [112.043326] Starting dynamic subtest: modeset [115.757521] Dynamic subtest modeset: SUCCESS (3.730s) [115.759413] Subtest basic: SUCCESS (4.631s) [116.658082] [092/170] (893s left) kms_chamelium_hpd (dp-hpd-fast) [117.108176] Subtest dp-hpd-fast: SKIP (0.000s) [117.973646] [093/170] (892s left) kms_chamelium_edid (dp-edid-read) [118.421973] Subtest dp-edid-read: SKIP (0.000s) [119.311373] [094/170] (891s left) kms_chamelium_frames (dp-crc-fast) [119.764984] Subtest dp-crc-fast: SKIP (0.000s) [120.654585] [095/170] (889s left) kms_chamelium_hpd (hdmi-hpd-fast) [121.102491] Subtest hdmi-hpd-fast: SKIP (0.000s) [122.000557] [096/170] (888s left) kms_chamelium_edid (hdmi-edid-read) [122.435487] Subtest hdmi-edid-read: SKIP (0.000s) [123.345366] [097/170] (887s left) kms_chamelium_frames (hdmi-crc-fast) [123.784120] Subtest hdmi-crc-fast: SKIP (0.000s) [124.684354] [098/170] (885s left) kms_chamelium_hpd (vga-hpd-fast) [125.121208] Subtest vga-hpd-fast: SKIP (0.000s) [126.031776] [099/170] (884s left) kms_chamelium_edid (vga-edid-read) [126.481600] Subtest vga-edid-read: SKIP (0.000s) [127.375871] [100/170] (883s left) kms_prop_blob (basic) [127.497302] Starting subtest: basic [127.498336] Subtest basic: SUCCESS (0.000s) [127.567374] [101/170] (883s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [127.713449] Starting subtest: basic-busy-flip-before-cursor-atomic [127.715278] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [127.802198] [102/170] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [127.943163] Starting subtest: basic-busy-flip-before-cursor-legacy [127.945239] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [128.028137] [103/170] (882s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [128.181344] Starting subtest: basic-flip-after-cursor-atomic [129.435297] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.253s) [130.361045] [104/170] (880s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [130.510161] Starting subtest: basic-flip-after-cursor-legacy [131.845733] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.324s) [132.795994] [105/170] (877s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [132.942450] Starting subtest: basic-flip-after-cursor-varying-size [134.240859] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.299s) [135.159031] [106/170] (875s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [135.301238] Starting subtest: basic-flip-before-cursor-atomic [136.551406] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.250s) [137.467628] [107/170] (873s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [137.611508] Starting subtest: basic-flip-before-cursor-legacy [138.957694] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.346s) [139.906091] [108/170] (870s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [140.055392] Starting subtest: basic-flip-before-cursor-varying-size [141.332335] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.276s) [142.267407] [109/170] (868s left) kms_flip (basic-flip-vs-dpms) [142.391857] Starting subtest: basic-flip-vs-dpms [142.394491] Starting dynamic subtest: A-eDP1 [145.191538] Dynamic subtest A-eDP1: SUCCESS (2.797s) [145.201686] Starting dynamic subtest: B-eDP1 [147.500180] Dynamic subtest B-eDP1: SUCCESS (2.307s) [147.511579] Starting dynamic subtest: C-eDP1 [149.867706] Dynamic subtest C-eDP1: SUCCESS (2.367s) [149.871407] Subtest basic-flip-vs-dpms: SUCCESS (7.482s) [150.772022] [110/170] (860s left) kms_flip (basic-flip-vs-modeset) [150.896870] Starting subtest: basic-flip-vs-modeset [150.901254] Starting dynamic subtest: A-eDP1 [153.711250] Dynamic subtest A-eDP1: SUCCESS (2.808s) [153.725472] Starting dynamic subtest: B-eDP1 [156.070896] Dynamic subtest B-eDP1: SUCCESS (2.360s) [156.081325] Starting dynamic subtest: C-eDP1 [158.442970] Dynamic subtest C-eDP1: SUCCESS (2.369s) [158.448764] Subtest basic-flip-vs-modeset: SUCCESS (7.552s) [159.347786] [111/170] (851s left) kms_flip (basic-flip-vs-wf_vblank) [159.471167] Starting subtest: basic-flip-vs-wf_vblank [159.475495] Starting dynamic subtest: A-eDP1 [161.748764] Dynamic subtest A-eDP1: SUCCESS (2.273s) [161.759358] Starting dynamic subtest: B-eDP1 [164.058857] Dynamic subtest B-eDP1: SUCCESS (2.308s) [164.070726] Starting dynamic subtest: C-eDP1 [166.360216] Dynamic subtest C-eDP1: SUCCESS (2.298s) [166.361316] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.891s) [167.681694] [112/170] (843s left) kms_flip (basic-plain-flip) [167.803494] Starting subtest: basic-plain-flip [167.808223] Starting dynamic subtest: A-eDP1 [169.864179] Dynamic subtest A-eDP1: SUCCESS (2.044s) [169.882245] Starting dynamic subtest: B-eDP1 [171.910228] Dynamic subtest B-eDP1: SUCCESS (2.044s) [171.936656] Starting dynamic subtest: C-eDP1 [173.953651] Dynamic subtest C-eDP1: SUCCESS (2.040s) [173.968888] Subtest basic-plain-flip: SUCCESS (6.165s) [175.294679] [113/170] (835s left) kms_force_connector_basic (force-connector-state) [175.430296] Starting subtest: force-connector-state [176.048962] Subtest force-connector-state: SUCCESS (0.619s) [177.083025] [114/170] (833s left) kms_force_connector_basic (force-edid) [177.217793] Starting subtest: force-edid [177.247525] Subtest force-edid: SUCCESS (0.031s) [177.438081] [115/170] (833s left) kms_force_connector_basic (force-load-detect) [177.566473] Starting subtest: force-load-detect [177.568634] Subtest force-load-detect: SKIP (0.000s) [177.718803] [116/170] (833s left) kms_force_connector_basic (prune-stale-modes) [177.846452] Starting subtest: prune-stale-modes [177.876531] Subtest prune-stale-modes: SUCCESS (0.031s) [178.056214] [117/170] (832s left) kms_frontbuffer_tracking (basic) [178.205066] Starting subtest: basic [181.050528] Subtest basic: SUCCESS (2.845s) [181.157923] [118/170] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [181.289068] Starting subtest: compare-crc-sanitycheck [181.292072] Starting dynamic subtest: pipe-A-eDP-1 [181.918392] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.627s) [181.930712] Starting dynamic subtest: pipe-B-eDP-1 [183.243762] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.319s) [183.256520] Starting dynamic subtest: pipe-C-eDP-1 [184.559788] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.314s) [184.560745] Subtest compare-crc-sanitycheck: SUCCESS (3.269s) [185.522357] [119/170] (825s left) kms_pipe_crc_basic (hang-read-crc) [185.659857] Starting subtest: hang-read-crc [185.662203] Starting dynamic subtest: pipe-A-eDP-1 [187.886730] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.222s) [187.907498] Starting dynamic subtest: pipe-B-eDP-1 [190.782194] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.881s) [190.802477] Starting dynamic subtest: pipe-C-eDP-1 [193.685457] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.902s) [193.686743] Subtest hang-read-crc: SUCCESS (8.026s) [194.605871] [120/170] (816s left) kms_pipe_crc_basic (nonblocking-crc) [194.750969] Starting subtest: nonblocking-crc [194.752092] Starting dynamic subtest: pipe-A-eDP-1 [195.561326] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.807s) [195.573116] Starting dynamic subtest: pipe-B-eDP-1 [197.111560] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.547s) [197.122201] Starting dynamic subtest: pipe-C-eDP-1 [198.619482] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.505s) [198.620656] Subtest nonblocking-crc: SUCCESS (3.870s) [199.553993] [121/170] (811s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [199.703485] Starting subtest: nonblocking-crc-frame-sequence [199.704595] Starting dynamic subtest: pipe-A-eDP-1 [200.530264] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.820s) [200.552256] Starting dynamic subtest: pipe-B-eDP-1 [202.061489] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.523s) [202.080053] Starting dynamic subtest: pipe-C-eDP-1 [203.557862] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.487s) [203.559892] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.853s) [204.483093] [122/170] (806s left) kms_pipe_crc_basic (read-crc) [204.630817] Starting subtest: read-crc [204.634017] Starting dynamic subtest: pipe-A-eDP-1 [205.366712] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.730s) [205.385100] Starting dynamic subtest: pipe-B-eDP-1 [206.837453] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.461s) [206.858186] Starting dynamic subtest: pipe-C-eDP-1 [208.290389] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.448s) [208.291998] Subtest read-crc: SUCCESS (3.657s) [209.211909] [123/170] (801s left) kms_pipe_crc_basic (read-crc-frame-sequence) [209.361074] Starting subtest: read-crc-frame-sequence [209.364387] Starting dynamic subtest: pipe-A-eDP-1 [210.110405] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.733s) [210.132095] Starting dynamic subtest: pipe-B-eDP-1 [211.569751] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.465s) [211.590997] Starting dynamic subtest: pipe-C-eDP-1 [213.029096] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.453s) [213.030422] Subtest read-crc-frame-sequence: SUCCESS (3.666s) [213.950911] [124/170] (797s left) kms_psr (primary_page_flip) [214.099495] Starting subtest: primary_page_flip [216.074073] Subtest primary_page_flip: SUCCESS (1.971s) [217.019117] [125/170] (794s left) kms_psr (cursor_plane_move) [217.165109] Starting subtest: cursor_plane_move [219.076979] Subtest cursor_plane_move: SUCCESS (1.909s) [220.027506] [126/170] (791s left) kms_psr (sprite_plane_onoff) [220.174884] Starting subtest: sprite_plane_onoff [222.190820] Subtest sprite_plane_onoff: SUCCESS (2.015s) [223.096811] [127/170] (788s left) kms_psr (primary_mmap_gtt) [223.244490] Starting subtest: primary_mmap_gtt [225.188573] Subtest primary_mmap_gtt: SUCCESS (1.943s) [226.114950] [128/170] (785s left) kms_setmode (basic-clone-single-crtc) [226.223525] Starting subtest: basic-clone-single-crtc [226.226515] Subtest basic-clone-single-crtc: SKIP (0.001s) [226.340966] [129/170] (784s left) i915_pm_backlight (basic-brightness) [226.501606] Starting subtest: basic-brightness [226.607256] Starting dynamic subtest: eDP-1 [227.216444] Dynamic subtest eDP-1: SUCCESS (0.608s) [227.218655] Subtest basic-brightness: SUCCESS (0.715s) [228.156239] [130/170] (783s left) i915_pm_rpm (basic-pci-d3-state) [229.906318] Starting subtest: basic-pci-d3-state [234.485216] Subtest basic-pci-d3-state: SUCCESS (4.581s) [234.593348] [131/170] (776s left) i915_pm_rpm (basic-rte) [234.696786] Starting subtest: basic-rte [240.862892] Subtest basic-rte: SUCCESS (6.165s) [246.174670] [132/170] (765s left) i915_pm_rps (basic-api) [246.288073] Starting subtest: basic-api [246.293142] Subtest basic-api: SUCCESS (0.005s) [246.423736] [133/170] (764s left) prime_self_import (basic-llseek-bad) [246.529237] Starting subtest: basic-llseek-bad [246.531579] Subtest basic-llseek-bad: SUCCESS (0.002s) [246.650082] [134/170] (764s left) prime_self_import (basic-llseek-size) [246.763191] Starting subtest: basic-llseek-size [246.767900] Subtest basic-llseek-size: SUCCESS (0.003s) [246.885111] [135/170] (764s left) prime_self_import (basic-with_fd_dup) [246.998959] Starting subtest: basic-with_fd_dup [247.003957] Subtest basic-with_fd_dup: SUCCESS (0.003s) [247.129606] [136/170] (764s left) prime_self_import (basic-with_one_bo) [247.239636] Starting subtest: basic-with_one_bo [247.244499] Subtest basic-with_one_bo: SUCCESS (0.004s) [247.315861] [137/170] (764s left) prime_self_import (basic-with_one_bo_two_files) [247.422600] Starting subtest: basic-with_one_bo_two_files [247.426930] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s) [247.531536] [138/170] (763s left) prime_self_import (basic-with_two_bos) [247.644345] Starting subtest: basic-with_two_bos [247.649259] Subtest basic-with_two_bos: SUCCESS (0.003s) [247.770492] [139/170] (763s left) prime_vgem (basic-fence-flip) [247.915814] Starting subtest: basic-fence-flip [248.424991] Subtest basic-fence-flip: SUCCESS (0.509s) [248.536743] [140/170] (763s left) prime_vgem (basic-fence-mmap) [248.689105] Starting subtest: basic-fence-mmap [248.726949] Subtest basic-fence-mmap: SUCCESS (0.038s) [248.824419] [141/170] (762s left) prime_vgem (basic-fence-read) [248.961879] Starting subtest: basic-fence-read [249.021919] Subtest basic-fence-read: SUCCESS (0.060s) [249.104402] [142/170] (762s left) prime_vgem (basic-gtt) [249.248528] Starting subtest: basic-gtt [249.264692] Subtest basic-gtt: SUCCESS (0.016s) [249.373392] [143/170] (762s left) prime_vgem (basic-read) [249.511155] Starting subtest: basic-read [249.537174] Subtest basic-read: SUCCESS (0.026s) [249.636742] [144/170] (762s left) prime_vgem (basic-write) [249.783273] Starting subtest: basic-write [249.812854] Subtest basic-write: SUCCESS (0.029s) [249.886135] [145/170] (761s left) vgem_basic (setversion) [250.031384] Starting subtest: setversion [250.034099] Subtest setversion: SUCCESS (0.000s) [250.112240] [146/170] (761s left) vgem_basic (create) [250.252499] Starting subtest: create [250.253641] Subtest create: SUCCESS (0.000s) [250.314821] [147/170] (761s left) vgem_basic (debugfs) [250.458110] Starting subtest: debugfs [250.459224] Subtest debugfs: SUCCESS (0.001s) [250.522808] [148/170] (761s left) vgem_basic (dmabuf-export) [250.663533] Starting subtest: dmabuf-export [250.667097] Subtest dmabuf-export: SUCCESS (0.002s) [250.779323] [149/170] (761s left) vgem_basic (dmabuf-fence) [250.908510] Starting subtest: dmabuf-fence [250.909714] Subtest dmabuf-fence: SUCCESS (0.001s) [250.974960] [150/170] (760s left) vgem_basic (dmabuf-fence-before) [251.113758] Starting subtest: dmabuf-fence-before [251.114763] Subtest dmabuf-fence-before: SUCCESS (0.000s) [251.176465] [151/170] (760s left) vgem_basic (dmabuf-mmap) [251.319216] Starting subtest: dmabuf-mmap [251.337876] Subtest dmabuf-mmap: SUCCESS (0.019s) [251.398611] [152/170] (760s left) vgem_basic (mmap) [251.544796] Starting subtest: mmap [251.554670] Subtest mmap: SUCCESS (0.012s) [251.618740] [153/170] (760s left) vgem_basic (second-client) [251.767344] Starting subtest: second-client [251.815120] Subtest second-client: SUCCESS (0.049s) [251.877635] [154/170] (760s left) vgem_basic (sysfs) [252.021376] Starting subtest: sysfs [252.022444] Subtest sysfs: SUCCESS (0.001s) [252.082625] [155/170] (759s left) core_hotunplug (unbind-rebind) [252.245415] Starting subtest: unbind-rebind [254.534891] Subtest unbind-rebind: SUCCESS (2.290s) [254.585017] [156/170] (757s left) vgem_basic (unload) [254.769240] Starting subtest: unload [283.852133] Subtest unload: SUCCESS (29.041s) [283.958280] [157/170] (728s left) i915_module_load (reload) [284.063068] Starting subtest: reload [287.368463] Subtest reload: SUCCESS (3.307s) [287.403749] [158/170] (724s left) gem_lmem_swapping (basic) [287.626049] Subtest basic: SKIP (0.000s) [287.666545] [159/170] (724s left) gem_lmem_swapping (parallel-random-engines) [287.779415] Subtest parallel-random-engines: SKIP (0.000s) [287.818786] [160/170] (724s left) gem_lmem_swapping (random-engines) [287.933269] Subtest random-engines: SKIP (0.000s) [287.966860] [161/170] (724s left) gem_lmem_swapping (verify-random) [288.075115] Subtest verify-random: SKIP (0.000s) [288.116323] [162/170] (724s left) i915_pm_rpm (module-reload) [290.813893] Starting subtest: module-reload [307.323313] Subtest module-reload: SUCCESS (16.499s) [307.364525] [163/170] (704s left) i915_selftest (live) [307.480573] Starting subtest: live [307.481784] Starting dynamic subtest: sanitycheck [309.159957] Dynamic subtest sanitycheck: SUCCESS (1.670s) [309.172525] Starting dynamic subtest: uncore [310.813798] Dynamic subtest uncore: SUCCESS (1.653s) [310.822680] Starting dynamic subtest: workarounds [312.699857] Dynamic subtest workarounds: SUCCESS (1.885s) [312.711202] Starting dynamic subtest: gt_engines [314.803768] Dynamic subtest gt_engines: SUCCESS (2.103s) [314.811777] Starting dynamic subtest: gt_timelines [324.741841] Dynamic subtest gt_timelines: SUCCESS (9.937s) [324.753453] Starting dynamic subtest: gt_contexts [326.507719] Dynamic subtest gt_contexts: SUCCESS (1.765s) [326.515330] Starting dynamic subtest: gt_lrc [330.688616] Dynamic subtest gt_lrc: SUCCESS (4.180s) [330.698931] Starting dynamic subtest: gt_mocs [332.456839] Dynamic subtest gt_mocs: SUCCESS (1.767s) [332.468526] Starting dynamic subtest: gt_pm [338.501920] Dynamic subtest gt_pm: SUCCESS (6.043s) [338.511239] Starting dynamic subtest: gt_heartbeat [340.265858] Dynamic subtest gt_heartbeat: SUCCESS (1.764s) [340.277586] Starting dynamic subtest: gt_tlb [342.695787] Dynamic subtest gt_tlb: SUCCESS (2.429s) [342.707155] Starting dynamic subtest: requests [350.146046] Dynamic subtest requests: SUCCESS (7.449s) [350.154436] Starting dynamic subtest: migrate [354.615035] Dynamic subtest migrate: SUCCESS (4.468s) [354.624409] Starting dynamic subtest: active [356.269963] Dynamic subtest active: SUCCESS (1.654s) [356.283235] Starting dynamic subtest: objects [359.078751] Dynamic subtest objects: SUCCESS (2.808s) [359.086443] Starting dynamic subtest: mman [364.202836] Dynamic subtest mman: SUCCESS (5.123s) [364.213870] Starting dynamic subtest: dmabuf [365.857777] Dynamic subtest dmabuf: SUCCESS (1.654s) [365.865480] Starting dynamic subtest: vma [367.529124] Dynamic subtest vma: SUCCESS (1.670s) [367.544436] Starting dynamic subtest: coherency [370.354584] Dynamic subtest coherency: SUCCESS (2.825s) [370.361804] Starting dynamic subtest: gtt [378.836907] Dynamic subtest gtt: SUCCESS (8.481s) [378.845375] Starting dynamic subtest: gem [380.744774] Dynamic subtest gem: SUCCESS (1.907s) [380.755254] Starting dynamic subtest: evict [383.974855] Dynamic subtest evict: SUCCESS (3.229s) [383.983364] Starting dynamic subtest: hugepages [390.037750] Dynamic subtest hugepages: SUCCESS (6.062s) [390.045556] Starting dynamic subtest: gem_contexts [404.620828] Dynamic subtest gem_contexts: SUCCESS (14.582s) [404.628554] Starting dynamic subtest: client [406.352788] Dynamic subtest client: SUCCESS (1.731s) [406.361262] Starting dynamic subtest: gem_migrate [407.975777] Dynamic subtest gem_migrate: SUCCESS (1.622s) [407.984259] Starting dynamic subtest: reset [410.338709] Dynamic subtest reset: SUCCESS (2.362s) [410.346258] Starting dynamic subtest: memory_region [412.006891] Dynamic subtest memory_region: SUCCESS (1.667s) [412.015701] Starting dynamic subtest: hangcheck [436.178167] Dynamic subtest hangcheck: SUCCESS (24.170s) [436.189482] Starting dynamic subtest: execlists [445.627764] Dynamic subtest execlists: SUCCESS (9.448s) [445.643078] Starting dynamic subtest: ring_submission [447.266716] Dynamic subtest ring_submission: SUCCESS (1.638s) [447.274329] Starting dynamic subtest: perf [449.063035] Dynamic subtest perf: SUCCESS (1.795s) [449.071496] Starting dynamic subtest: slpc [450.735842] Dynamic subtest slpc: SUCCESS (1.672s) [450.744558] Starting dynamic subtest: guc [452.379671] Dynamic subtest guc: SUCCESS (1.643s) [452.387348] Starting dynamic subtest: guc_multi_lrc [453.995939] Dynamic subtest guc_multi_lrc: SUCCESS (1.618s) [454.005812] Starting dynamic subtest: guc_hang [455.670858] Dynamic subtest guc_hang: SUCCESS (1.671s) [455.683754] Starting dynamic subtest: late_gt_pm [457.392677] Dynamic subtest late_gt_pm: SUCCESS (1.721s) [457.393938] Subtest live: SUCCESS (149.904s) [457.631722] [164/170] (554s left) dmabuf (all-tests) [457.752417] Starting subtest: all-tests [457.754225] Starting dynamic subtest: sanitycheck [457.821158] Dynamic subtest sanitycheck: SUCCESS (0.067s) [457.846121] Starting dynamic subtest: dma_fence [458.009533] Dynamic subtest dma_fence: SUCCESS (0.187s) [458.019536] Starting dynamic subtest: dma_fence_chain [474.039813] Dynamic subtest dma_fence_chain: SUCCESS (16.018s) [474.069992] Starting dynamic subtest: dma_fence_unwrap [474.118828] Dynamic subtest dma_fence_unwrap: SUCCESS (0.086s) [474.133943] Starting dynamic subtest: dma_resv [474.200729] Dynamic subtest dma_resv: SUCCESS (0.080s) [474.202251] Subtest all-tests: SUCCESS (16.448s) [474.243361] [165/170] (537s left) i915_suspend (basic-s2idle-without-i915) [474.359666] Starting subtest: basic-s2idle-without-i915 [493.900984] Subtest basic-s2idle-without-i915: SUCCESS (4.244s) [496.241380] [166/170] (533s left) i915_suspend (basic-s3-without-i915) [496.356294] Starting subtest: basic-s3-without-i915 [516.841888] Subtest basic-s3-without-i915: SUCCESS (4.436s) [520.178375] [167/170] (528s left) gem_exec_suspend (basic-s0) [520.317547] Starting subtest: basic-S0 [520.318705] Starting dynamic subtest: smem [538.950543] Dynamic subtest smem: SUCCESS (3.256s) [538.951595] Subtest basic-S0: SUCCESS (3.257s) [542.222707] [168/170] (524s left) gem_exec_suspend (basic-s3) [542.377342] Starting subtest: basic-S3 [542.380896] Starting dynamic subtest: smem [563.237439] Dynamic subtest smem: SUCCESS (4.591s) [563.238497] Subtest basic-S3: SUCCESS (4.593s) [565.510856] [169/170] (519s left) kms_chamelium_hpd (common-hpd-after-suspend) [565.989165] Subtest common-hpd-after-suspend: SKIP (0.000s) [566.851922] [170/170] (518s left) kms_pipe_crc_basic (suspend-read-crc) [567.005158] Starting subtest: suspend-read-crc [567.008472] Starting dynamic subtest: pipe-A-eDP-1 [586.766946] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.180s) [586.780777] Starting dynamic subtest: pipe-B-eDP-1 [617.759869] Dynamic subtest pipe-B-eDP-1: SUCCESS (13.894s) [617.773634] Starting dynamic subtest: pipe-C-eDP-1 [637.907300] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.977s) [637.908561] Subtest suspend-read-crc: SUCCESS (21.063s) [640.772577] Closing watchdogs Done.