[42.473738] Initializing watchdogs [42.473860] /dev/watchdog0 [42.480971] [001/171] (960s left) i915_module_load (load) [42.709268] Starting subtest: load [43.754439] Subtest load: SUCCESS (1.042s) [43.781518] [002/171] (959s left) core_auth (basic-auth) [43.954088] Starting subtest: basic-auth [43.956432] Subtest basic-auth: SUCCESS (0.001s) [44.042165] [003/171] (958s left) debugfs_test (read_all_entries) [44.158202] Starting subtest: read_all_entries [44.242933] Subtest read_all_entries: SUCCESS (0.084s) [44.346268] [004/171] (958s left) debugfs_test (basic-hwmon) [44.453069] Starting subtest: basic-hwmon [44.454997] Subtest basic-hwmon: SKIP (0.000s) [44.569774] [005/171] (958s left) fbdev (eof) [44.680665] Starting subtest: eof [44.681631] Subtest eof: SUCCESS (0.000s) [44.740837] [006/171] (958s left) fbdev (info) [44.836212] Starting subtest: info [44.837091] Subtest info: SUCCESS (0.000s) [44.907683] [007/171] (958s left) fbdev (nullptr) [45.014085] Starting subtest: nullptr [45.015062] Subtest nullptr: SUCCESS (0.000s) [45.069558] [008/171] (958s left) fbdev (read) [45.180455] Starting subtest: read [46.029663] Subtest read: SUCCESS (0.841s) [46.068669] [009/171] (957s left) fbdev (write) [46.181269] Starting subtest: write [46.603210] Subtest write: SUCCESS (0.414s) [46.688173] [010/171] (956s left) gem_basic (bad-close) [46.787118] Starting subtest: bad-close [46.788924] Subtest bad-close: SUCCESS (0.000s) [46.847555] [011/171] (956s left) gem_basic (create-close) [46.948093] Starting subtest: create-close [46.949800] Subtest create-close: SUCCESS (0.000s) [47.064617] [012/171] (956s left) gem_basic (create-fd-close) [47.175955] Starting subtest: create-fd-close [47.177845] Subtest create-fd-close: SUCCESS (0.000s) [47.298708] [013/171] (955s left) gem_busy (busy@all-engines) [47.415126] Starting subtest: busy [47.416063] Starting dynamic subtest: all-engines [47.476266] Dynamic subtest all-engines: SUCCESS (0.061s) [47.477162] Subtest busy: SUCCESS (0.061s) [47.906396] [014/171] (955s left) gem_close_race (basic-process) [48.025507] Starting subtest: basic-process [48.084492] Subtest basic-process: SUCCESS (0.059s) [48.299374] [015/171] (954s left) gem_close_race (basic-threads) [48.421433] Starting subtest: basic-threads [49.576420] Subtest basic-threads: SUCCESS (1.155s) [49.810345] [016/171] (953s left) gem_ctx_create (basic) [49.928199] Starting subtest: basic [49.929213] Subtest basic: SUCCESS (0.000s) [50.151159] [017/171] (953s left) gem_ctx_create (basic-files) [50.265651] Starting subtest: basic-files [52.286941] Subtest basic-files: SUCCESS (2.021s) [52.576385] [018/171] (950s left) gem_ctx_exec (basic) [52.688761] Starting subtest: basic [52.694149] Subtest basic: SUCCESS (0.005s) [52.769865] [019/171] (950s left) gem_exec_basic (basic) [52.886078] Starting subtest: basic [52.887434] Starting dynamic subtest: rcs0-smem [52.888509] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [52.896408] Starting dynamic subtest: bcs0-smem [52.897403] Dynamic subtest bcs0-smem: SUCCESS (0.002s) [52.904621] Starting dynamic subtest: vcs0-smem [52.905949] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [52.912959] Starting dynamic subtest: vecs0-smem [52.914300] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [52.915614] Subtest basic: SUCCESS (0.007s) [53.101765] [020/171] (950s left) gem_exec_create (basic) [53.221542] Starting subtest: basic [53.222664] Starting dynamic subtest: smem [55.271556] Dynamic subtest smem: SUCCESS (2.050s) [55.272664] Subtest basic: SUCCESS (2.050s) [55.500225] [021/171] (947s left) gem_exec_fence (basic-busy) [55.769423] Starting subtest: basic-busy [55.770760] Starting dynamic subtest: rcs0 [55.792399] Dynamic subtest rcs0: SUCCESS (0.023s) [55.800734] Starting dynamic subtest: bcs0 [55.811368] Dynamic subtest bcs0: SUCCESS (0.019s) [55.818666] Starting dynamic subtest: vcs0 [55.841257] Dynamic subtest vcs0: SUCCESS (0.030s) [55.848947] Starting dynamic subtest: vecs0 [55.866466] Dynamic subtest vecs0: SUCCESS (0.025s) [55.867514] Subtest basic-busy: SUCCESS (0.097s) [56.540218] [022/171] (946s left) gem_exec_fence (basic-wait) [56.804440] Starting subtest: basic-wait [56.806025] Starting dynamic subtest: rcs0 [56.835352] Dynamic subtest rcs0: SUCCESS (0.031s) [56.843720] Starting dynamic subtest: bcs0 [56.858382] Dynamic subtest bcs0: SUCCESS (0.023s) [56.865795] Starting dynamic subtest: vcs0 [56.888294] Dynamic subtest vcs0: SUCCESS (0.030s) [56.896518] Starting dynamic subtest: vecs0 [56.908360] Dynamic subtest vecs0: SUCCESS (0.020s) [56.909530] Subtest basic-wait: SUCCESS (0.104s) [57.558957] [023/171] (945s left) gem_exec_fence (basic-await) [57.841697] Starting subtest: basic-await [57.842941] Starting dynamic subtest: rcs0 [57.944511] Dynamic subtest rcs0: SUCCESS (0.103s) [57.954087] Starting dynamic subtest: bcs0 [58.045100] Dynamic subtest bcs0: SUCCESS (0.100s) [58.053488] Starting dynamic subtest: vcs0 [58.144321] Dynamic subtest vcs0: SUCCESS (0.099s) [58.152970] Starting dynamic subtest: vecs0 [58.244456] Dynamic subtest vecs0: SUCCESS (0.100s) [58.245543] Subtest basic-await: SUCCESS (0.403s) [58.851577] [024/171] (944s left) gem_exec_fence (nb-await) [59.120757] Starting subtest: nb-await [59.122886] Starting dynamic subtest: rcs0 [59.196458] Dynamic subtest rcs0: SUCCESS (0.075s) [59.209604] Starting dynamic subtest: bcs0 [59.270105] Dynamic subtest bcs0: SUCCESS (0.070s) [59.307177] Starting dynamic subtest: vcs0 [59.364160] Dynamic subtest vcs0: SUCCESS (0.094s) [59.406247] Starting dynamic subtest: vecs0 [59.473504] Dynamic subtest vecs0: SUCCESS (0.108s) [59.478414] Subtest nb-await: SUCCESS (0.351s) [60.154778] [025/171] (943s left) gem_exec_gttfill (basic) [60.274074] Starting subtest: basic [69.796955] Subtest basic: SUCCESS (9.514s) [69.986785] [026/171] (933s left) gem_exec_parallel (engines) [70.159581] Starting subtest: engines [70.160662] Starting dynamic subtest: basic [72.493066] Dynamic subtest basic: SUCCESS (2.334s) [72.502234] Starting dynamic subtest: contexts [75.442603] Dynamic subtest contexts: SUCCESS (2.940s) [75.450937] Starting dynamic subtest: fds [78.779914] Dynamic subtest fds: SUCCESS (3.346s) [78.788840] Starting dynamic subtest: userptr [81.188414] Dynamic subtest userptr: SUCCESS (2.408s) [81.189438] Subtest engines: SUCCESS (11.029s) [81.409158] [027/171] (922s left) gem_exec_store (basic) [81.533247] Starting subtest: basic [81.588367] Subtest basic: SUCCESS (0.055s) [81.810899] [028/171] (921s left) gem_flink_basic (bad-flink) [81.919212] Starting subtest: bad-flink [81.920216] Subtest bad-flink: SUCCESS (0.000s) [82.011241] [029/171] (921s left) gem_flink_basic (bad-open) [82.117574] Starting subtest: bad-open [82.118671] Subtest bad-open: SUCCESS (0.000s) [82.215946] [030/171] (921s left) gem_flink_basic (basic) [82.321977] Starting subtest: basic [82.322975] Subtest basic: SUCCESS (0.000s) [82.425168] [031/171] (921s left) gem_flink_basic (double-flink) [82.533162] Starting subtest: double-flink [82.534282] Subtest double-flink: SUCCESS (0.000s) [82.633964] [032/171] (920s left) gem_flink_basic (flink-lifetime) [82.740826] Starting subtest: flink-lifetime [82.741992] Subtest flink-lifetime: SUCCESS (0.001s) [82.846999] [033/171] (920s left) gem_huc_copy (huc-copy) [82.965310] Starting subtest: huc-copy [82.967297] Subtest huc-copy: SKIP (0.000s) [83.098557] [034/171] (920s left) gem_linear_blits (basic) [83.352703] Starting subtest: basic [83.361369] Subtest basic: SUCCESS (0.009s) [83.508133] [035/171] (920s left) gem_mmap (basic) [83.665919] Starting subtest: basic [83.670888] Subtest basic: SUCCESS (0.001s) [83.748943] [036/171] (919s left) gem_mmap_gtt (basic) [83.856541] Starting subtest: basic [83.857627] Subtest basic: SUCCESS (0.001s) [83.957228] [037/171] (919s left) gem_render_linear_blits (basic) [84.070855] Starting subtest: basic [84.126605] Subtest basic: SUCCESS (0.055s) [84.170950] [038/171] (919s left) gem_render_tiled_blits (basic) [84.289854] Starting subtest: basic [84.337109] Subtest basic: SUCCESS (0.046s) [84.371509] [039/171] (919s left) gem_ringfill (basic-all) [84.668571] Starting subtest: basic-all [85.839178] Subtest basic-all: SUCCESS (1.163s) [85.977975] [040/171] (917s left) gem_softpin (allocator-basic) [86.103760] Starting subtest: allocator-basic [86.241278] Subtest allocator-basic: SUCCESS (0.129s) [86.353716] [041/171] (917s left) gem_softpin (allocator-basic-reserve) [86.465769] Starting subtest: allocator-basic-reserve [86.601818] Subtest allocator-basic-reserve: SUCCESS (0.128s) [86.736842] [042/171] (917s left) gem_softpin (safe-alignment) [86.850104] Starting subtest: safe-alignment [86.865260] Subtest safe-alignment: SUCCESS (0.016s) [86.991913] [043/171] (916s left) gem_sync (basic-all) [87.118191] Starting subtest: basic-all [89.314439] Subtest basic-all: SUCCESS (2.197s) [89.546681] [044/171] (914s left) gem_sync (basic-each) [89.670497] Starting subtest: basic-each [91.857360] Subtest basic-each: SUCCESS (2.187s) [92.127945] [045/171] (911s left) gem_tiled_blits (basic) [92.415690] Starting subtest: basic [92.438377] Subtest basic: SUCCESS (0.024s) [92.601685] [046/171] (911s left) gem_tiled_fence_blits (basic) [92.926665] Starting subtest: basic [92.942060] Subtest basic: SUCCESS (0.015s) [93.101418] [047/171] (910s left) gem_tiled_pread_basic [93.484180] [048/171] (910s left) gem_wait (busy@all-engines) [93.617332] Starting subtest: busy [93.618401] Starting dynamic subtest: all-engines [94.157502] Dynamic subtest all-engines: SUCCESS (0.540s) [94.158812] Subtest busy: SUCCESS (0.540s) [94.393499] [049/171] (909s left) gem_wait (wait@all-engines) [94.524727] Starting subtest: wait [94.525864] Starting dynamic subtest: all-engines [95.576469] Dynamic subtest all-engines: SUCCESS (1.047s) [95.580452] Subtest wait: SUCCESS (1.048s) [95.849955] [050/171] (908s left) i915_getparams_basic (basic-eu-total) [95.959163] Starting subtest: basic-eu-total [95.960963] Subtest basic-eu-total: SUCCESS (0.000s) [96.050956] [051/171] (907s left) i915_getparams_basic (basic-subslice-total) [96.157447] Starting subtest: basic-subslice-total [96.159192] Subtest basic-subslice-total: SUCCESS (0.000s) [96.258077] [052/171] (907s left) i915_hangman (error-state-basic) [96.380204] Starting subtest: error-state-basic [96.392185] Subtest error-state-basic: SUCCESS (0.009s) [96.486782] [053/171] (907s left) i915_pciid [96.682562] [054/171] (907s left) kms_addfb_basic (addfb25-bad-modifier) [96.791604] Starting subtest: addfb25-bad-modifier [96.792728] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [96.953677] [055/171] (907s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [97.062789] Starting subtest: addfb25-framebuffer-vs-set-tiling [97.063813] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [97.205251] [056/171] (906s left) kms_addfb_basic (addfb25-modifier-no-flag) [97.314908] Starting subtest: addfb25-modifier-no-flag [97.316059] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [97.463401] [057/171] (906s left) kms_addfb_basic (addfb25-x-tiled-legacy) [97.571440] Starting subtest: addfb25-x-tiled-legacy [97.572337] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [97.714655] [058/171] (906s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [97.819952] Starting subtest: addfb25-x-tiled-mismatch-legacy [97.821032] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [97.965654] [059/171] (906s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [98.107469] Starting subtest: addfb25-yf-tiled-legacy [98.108487] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [98.203369] [060/171] (906s left) kms_addfb_basic (addfb25-y-tiled-legacy) [98.343164] Starting subtest: addfb25-y-tiled-legacy [98.344095] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [98.439382] [061/171] (905s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [98.578637] Starting subtest: addfb25-y-tiled-small-legacy [98.579868] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [98.671200] [062/171] (905s left) kms_addfb_basic (bad-pitch-0) [98.771319] Starting subtest: bad-pitch-0 [98.772343] Subtest bad-pitch-0: SUCCESS (0.000s) [98.909895] [063/171] (905s left) kms_addfb_basic (bad-pitch-1024) [99.006894] Starting subtest: bad-pitch-1024 [99.007954] Subtest bad-pitch-1024: SUCCESS (0.000s) [99.178982] [064/171] (905s left) kms_addfb_basic (bad-pitch-128) [99.281474] Starting subtest: bad-pitch-128 [99.282539] Subtest bad-pitch-128: SUCCESS (0.000s) [99.411749] [065/171] (904s left) kms_addfb_basic (bad-pitch-256) [99.511493] Starting subtest: bad-pitch-256 [99.512473] Subtest bad-pitch-256: SUCCESS (0.000s) [99.644958] [066/171] (904s left) kms_addfb_basic (bad-pitch-32) [99.744481] Starting subtest: bad-pitch-32 [99.745499] Subtest bad-pitch-32: SUCCESS (0.000s) [99.892122] [067/171] (904s left) kms_addfb_basic (bad-pitch-63) [99.991167] Starting subtest: bad-pitch-63 [99.992229] Subtest bad-pitch-63: SUCCESS (0.000s) [100.121077] [068/171] (904s left) kms_addfb_basic (bad-pitch-65536) [100.219011] Starting subtest: bad-pitch-65536 [100.219972] Subtest bad-pitch-65536: SUCCESS (0.000s) [100.367313] [069/171] (904s left) kms_addfb_basic (bad-pitch-999) [100.465377] Starting subtest: bad-pitch-999 [100.466354] Subtest bad-pitch-999: SUCCESS (0.000s) [100.607613] [070/171] (903s left) kms_addfb_basic (basic) [100.705452] Starting subtest: basic [100.706476] Subtest basic: SUCCESS (0.000s) [100.832823] [071/171] (903s left) kms_addfb_basic (basic-x-tiled-legacy) [100.931377] Starting subtest: basic-x-tiled-legacy [100.932357] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [101.087831] [072/171] (903s left) kms_addfb_basic (basic-y-tiled-legacy) [101.186343] Starting subtest: basic-y-tiled-legacy [101.187317] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [101.320219] [073/171] (903s left) kms_addfb_basic (bo-too-small) [101.450921] Starting subtest: bo-too-small [101.451991] Subtest bo-too-small: SUCCESS (0.000s) [101.546709] [074/171] (902s left) kms_addfb_basic (bo-too-small-due-to-tiling) [101.681973] Starting subtest: bo-too-small-due-to-tiling [101.683051] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [101.775712] [075/171] (902s left) kms_addfb_basic (clobberred-modifier) [101.872498] Starting subtest: clobberred-modifier [101.873541] Subtest clobberred-modifier: SUCCESS (0.000s) [102.001720] [076/171] (902s left) kms_addfb_basic (framebuffer-vs-set-tiling) [102.099499] Starting subtest: framebuffer-vs-set-tiling [102.100536] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [102.225408] [077/171] (902s left) kms_addfb_basic (invalid-get-prop) [102.321886] Starting subtest: invalid-get-prop [102.323140] Subtest invalid-get-prop: SUCCESS (0.000s) [102.451439] [078/171] (902s left) kms_addfb_basic (invalid-get-prop-any) [102.549228] Starting subtest: invalid-get-prop-any [102.550197] Subtest invalid-get-prop-any: SUCCESS (0.000s) [102.690909] [079/171] (901s left) kms_addfb_basic (invalid-set-prop) [102.787572] Starting subtest: invalid-set-prop [102.788470] Subtest invalid-set-prop: SUCCESS (0.000s) [102.931195] [080/171] (901s left) kms_addfb_basic (invalid-set-prop-any) [103.030385] Starting subtest: invalid-set-prop-any [103.031347] Subtest invalid-set-prop-any: SUCCESS (0.000s) [103.169620] [081/171] (901s left) kms_addfb_basic (no-handle) [103.265165] Starting subtest: no-handle [103.266071] Subtest no-handle: SUCCESS (0.000s) [103.411251] [082/171] (901s left) kms_addfb_basic (size-max) [103.542981] Starting subtest: size-max [103.543955] Subtest size-max: SUCCESS (0.000s) [103.646471] [083/171] (901s left) kms_addfb_basic (small-bo) [103.782192] Starting subtest: small-bo [103.783123] Subtest small-bo: SUCCESS (0.000s) [103.882318] [084/171] (900s left) kms_addfb_basic (tile-pitch-mismatch) [103.979435] Starting subtest: tile-pitch-mismatch [103.980402] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [104.110403] [085/171] (900s left) kms_addfb_basic (too-high) [104.243081] Starting subtest: too-high [104.244021] Subtest too-high: SUCCESS (0.001s) [104.350598] [086/171] (900s left) kms_addfb_basic (too-wide) [104.482110] Starting subtest: too-wide [104.483072] Subtest too-wide: SUCCESS (0.000s) [104.587734] [087/171] (900s left) kms_addfb_basic (unused-handle) [104.685580] Starting subtest: unused-handle [104.686626] Subtest unused-handle: SUCCESS (0.000s) [104.818038] [088/171] (899s left) kms_addfb_basic (unused-modifier) [104.915034] Starting subtest: unused-modifier [104.916063] Subtest unused-modifier: SUCCESS (0.000s) [105.063450] [089/171] (899s left) kms_addfb_basic (unused-offsets) [105.158449] Starting subtest: unused-offsets [105.159466] Subtest unused-offsets: SUCCESS (0.000s) [105.292797] [090/171] (899s left) kms_addfb_basic (unused-pitches) [105.388738] Starting subtest: unused-pitches [105.389778] Subtest unused-pitches: SUCCESS (0.000s) [105.526782] [091/171] (899s left) kms_busy (basic) [105.661398] Starting subtest: basic [105.663608] Starting dynamic subtest: flip [106.625413] Dynamic subtest flip: SUCCESS (0.960s) [106.644291] Starting dynamic subtest: modeset [110.313786] Dynamic subtest modeset: SUCCESS (3.689s) [110.315438] Subtest basic: SUCCESS (4.651s) [111.229122] [092/171] (893s left) kms_chamelium_hpd (dp-hpd-fast) [111.687568] Subtest dp-hpd-fast: SKIP (0.000s) [112.594068] [093/171] (892s left) kms_chamelium_edid (dp-edid-read) [113.040241] Subtest dp-edid-read: SKIP (0.000s) [113.935345] [094/171] (890s left) kms_chamelium_frames (dp-crc-fast) [114.384634] Subtest dp-crc-fast: SKIP (0.000s) [115.278388] [095/171] (889s left) kms_chamelium_hpd (hdmi-hpd-fast) [115.716205] Subtest hdmi-hpd-fast: SKIP (0.000s) [116.624798] [096/171] (888s left) kms_chamelium_edid (hdmi-edid-read) [117.071515] Subtest hdmi-edid-read: SKIP (0.000s) [117.963955] [097/171] (886s left) kms_chamelium_frames (hdmi-crc-fast) [118.409620] Subtest hdmi-crc-fast: SKIP (0.000s) [119.313182] [098/171] (885s left) kms_chamelium_hpd (vga-hpd-fast) [119.753426] Subtest vga-hpd-fast: SKIP (0.000s) [120.660039] [099/171] (884s left) kms_chamelium_edid (vga-edid-read) [121.119099] Subtest vga-edid-read: SKIP (0.000s) [122.000068] [100/171] (883s left) kms_prop_blob (basic) [122.110654] Starting subtest: basic [122.111732] Subtest basic: SUCCESS (0.000s) [122.177918] [101/171] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [122.326459] Starting subtest: basic-busy-flip-before-cursor-atomic [122.328465] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [122.408754] [102/171] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [122.556663] Starting subtest: basic-busy-flip-before-cursor-legacy [122.558791] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [122.639453] [103/171] (882s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [122.780572] Starting subtest: basic-flip-after-cursor-atomic [124.032821] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.251s) [124.988438] [104/171] (880s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [125.129891] Starting subtest: basic-flip-after-cursor-legacy [126.382019] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.250s) [127.291766] [105/171] (877s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [127.441494] Starting subtest: basic-flip-after-cursor-varying-size [128.758732] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.317s) [129.726146] [106/171] (875s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [129.871249] Starting subtest: basic-flip-before-cursor-atomic [131.180760] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.310s) [132.093343] [107/171] (873s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [132.237135] Starting subtest: basic-flip-before-cursor-legacy [133.506519] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.268s) [134.460066] [108/171] (870s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [134.606494] Starting subtest: basic-flip-before-cursor-varying-size [135.863478] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.255s) [136.826661] [109/171] (868s left) kms_flip (basic-flip-vs-dpms) [136.952880] Starting subtest: basic-flip-vs-dpms [136.959019] Starting dynamic subtest: A-eDP1 [139.816366] Dynamic subtest A-eDP1: SUCCESS (2.856s) [139.826224] Starting dynamic subtest: B-eDP1 [142.189163] Dynamic subtest B-eDP1: SUCCESS (2.372s) [142.199229] Starting dynamic subtest: C-eDP1 [144.560373] Dynamic subtest C-eDP1: SUCCESS (2.368s) [144.564869] Subtest basic-flip-vs-dpms: SUCCESS (7.612s) [145.464338] [110/171] (859s left) kms_flip (basic-flip-vs-modeset) [145.590124] Starting subtest: basic-flip-vs-modeset [145.595175] Starting dynamic subtest: A-eDP1 [148.459703] Dynamic subtest A-eDP1: SUCCESS (2.864s) [148.474242] Starting dynamic subtest: B-eDP1 [150.835337] Dynamic subtest B-eDP1: SUCCESS (2.372s) [150.850212] Starting dynamic subtest: C-eDP1 [153.199186] Dynamic subtest C-eDP1: SUCCESS (2.357s) [153.200191] Subtest basic-flip-vs-modeset: SUCCESS (7.607s) [154.111901] [111/171] (851s left) kms_flip (basic-flip-vs-wf_vblank) [154.234398] Starting subtest: basic-flip-vs-wf_vblank [154.238928] Starting dynamic subtest: A-eDP1 [156.571893] Dynamic subtest A-eDP1: SUCCESS (2.332s) [156.581922] Starting dynamic subtest: B-eDP1 [158.878496] Dynamic subtest B-eDP1: SUCCESS (2.306s) [158.892047] Starting dynamic subtest: C-eDP1 [161.171485] Dynamic subtest C-eDP1: SUCCESS (2.291s) [161.175474] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.941s) [162.493836] [112/171] (842s left) kms_flip (basic-plain-flip) [162.616626] Starting subtest: basic-plain-flip [162.621125] Starting dynamic subtest: A-eDP1 [164.618332] Dynamic subtest A-eDP1: SUCCESS (1.983s) [164.636155] Starting dynamic subtest: B-eDP1 [166.664871] Dynamic subtest B-eDP1: SUCCESS (2.038s) [166.682761] Starting dynamic subtest: C-eDP1 [168.712383] Dynamic subtest C-eDP1: SUCCESS (2.037s) [168.718884] Subtest basic-plain-flip: SUCCESS (6.102s) [170.045625] [113/171] (835s left) kms_force_connector_basic (force-connector-state) [170.182196] Starting subtest: force-connector-state [170.784674] Subtest force-connector-state: SUCCESS (0.604s) [171.833529] [114/171] (833s left) kms_force_connector_basic (force-edid) [171.968051] Starting subtest: force-edid [172.002039] Subtest force-edid: SUCCESS (0.035s) [172.189110] [115/171] (833s left) kms_force_connector_basic (force-load-detect) [172.318300] Starting subtest: force-load-detect [172.320314] Subtest force-load-detect: SKIP (0.000s) [172.465651] [116/171] (832s left) kms_force_connector_basic (prune-stale-modes) [172.593174] Starting subtest: prune-stale-modes [172.625629] Subtest prune-stale-modes: SUCCESS (0.030s) [172.813168] [117/171] (832s left) kms_frontbuffer_tracking (basic) [172.954868] Starting subtest: basic [175.776617] Subtest basic: SUCCESS (2.822s) [175.887457] [118/171] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [176.022144] Starting subtest: compare-crc-sanitycheck-xr24 [176.023170] Starting dynamic subtest: pipe-A-eDP-1 [176.652079] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.628s) [176.664530] Starting dynamic subtest: pipe-B-eDP-1 [177.969329] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.314s) [177.983337] Starting dynamic subtest: pipe-C-eDP-1 [179.289912] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.315s) [179.291065] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.270s) [180.207897] [119/171] (825s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [180.346362] Starting subtest: compare-crc-sanitycheck-nv12 [180.348822] Starting dynamic subtest: pipe-A-eDP-1 [180.350406] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [180.360903] Starting dynamic subtest: pipe-B-eDP-1 [180.362755] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [180.371132] Starting dynamic subtest: pipe-C-eDP-1 [180.372796] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [180.374404] Subtest compare-crc-sanitycheck-nv12: SKIP (0.014s) [180.453415] [120/171] (824s left) kms_pipe_crc_basic (hang-read-crc) [180.585121] Starting subtest: hang-read-crc [180.588174] Starting dynamic subtest: pipe-A-eDP-1 [182.770090] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.182s) [182.786240] Starting dynamic subtest: pipe-B-eDP-1 [185.682145] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.907s) [185.698738] Starting dynamic subtest: pipe-C-eDP-1 [188.626526] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.934s) [188.627878] Subtest hang-read-crc: SUCCESS (8.038s) [189.562125] [121/171] (815s left) kms_pipe_crc_basic (nonblocking-crc) [189.711313] Starting subtest: nonblocking-crc [189.712360] Starting dynamic subtest: pipe-A-eDP-1 [190.510594] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.798s) [190.537454] Starting dynamic subtest: pipe-B-eDP-1 [192.045289] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.524s) [192.064855] Starting dynamic subtest: pipe-C-eDP-1 [193.585513] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.535s) [193.586575] Subtest nonblocking-crc: SUCCESS (3.876s) [194.556822] [122/171] (810s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [194.705753] Starting subtest: nonblocking-crc-frame-sequence [194.707361] Starting dynamic subtest: pipe-A-eDP-1 [195.519814] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.810s) [195.533897] Starting dynamic subtest: pipe-B-eDP-1 [197.070227] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.543s) [197.088689] Starting dynamic subtest: pipe-C-eDP-1 [198.594791] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.514s) [198.596764] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.889s) [199.549913] [123/171] (805s left) kms_pipe_crc_basic (read-crc) [199.698866] Starting subtest: read-crc [199.702019] Starting dynamic subtest: pipe-A-eDP-1 [200.442125] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.739s) [200.463954] Starting dynamic subtest: pipe-B-eDP-1 [201.923415] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.473s) [201.943590] Starting dynamic subtest: pipe-C-eDP-1 [203.381732] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.449s) [203.383608] Subtest read-crc: SUCCESS (3.684s) [204.280899] [124/171] (801s left) kms_pipe_crc_basic (read-crc-frame-sequence) [204.431906] Starting subtest: read-crc-frame-sequence [204.435019] Starting dynamic subtest: pipe-A-eDP-1 [205.146725] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s) [205.161319] Starting dynamic subtest: pipe-B-eDP-1 [206.609459] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.460s) [206.623045] Starting dynamic subtest: pipe-C-eDP-1 [208.071528] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.456s) [208.072592] Subtest read-crc-frame-sequence: SUCCESS (3.640s) [209.019011] [125/171] (796s left) kms_psr (primary_page_flip) [209.168582] Starting subtest: primary_page_flip [211.159029] Subtest primary_page_flip: SUCCESS (1.988s) [212.092845] [126/171] (793s left) kms_psr (cursor_plane_move) [212.239337] Starting subtest: cursor_plane_move [214.172881] Subtest cursor_plane_move: SUCCESS (1.933s) [215.059042] [127/171] (790s left) kms_psr (sprite_plane_onoff) [215.208667] Starting subtest: sprite_plane_onoff [217.205524] Subtest sprite_plane_onoff: SUCCESS (1.995s) [218.179089] [128/171] (787s left) kms_psr (primary_mmap_gtt) [218.329480] Starting subtest: primary_mmap_gtt [220.310945] Subtest primary_mmap_gtt: SUCCESS (1.978s) [221.241059] [129/171] (784s left) kms_setmode (basic-clone-single-crtc) [221.350380] Starting subtest: basic-clone-single-crtc [221.353489] Subtest basic-clone-single-crtc: SKIP (0.000s) [221.461701] [130/171] (784s left) i915_pm_backlight (basic-brightness) [221.611599] Starting subtest: basic-brightness [221.715434] Starting dynamic subtest: eDP-1 [222.336157] Dynamic subtest eDP-1: SUCCESS (0.619s) [222.337964] Subtest basic-brightness: SUCCESS (0.726s) [223.241757] [131/171] (782s left) i915_pm_rpm (basic-pci-d3-state) [225.000269] Starting subtest: basic-pci-d3-state [229.539428] Subtest basic-pci-d3-state: SUCCESS (4.540s) [229.707329] [132/171] (775s left) i915_pm_rpm (basic-rte) [229.824049] Starting subtest: basic-rte [236.033837] Subtest basic-rte: SUCCESS (6.208s) [241.369937] [133/171] (764s left) i915_pm_rps (basic-api) [241.485137] Starting subtest: basic-api [241.489969] Subtest basic-api: SUCCESS (0.005s) [241.611722] [134/171] (764s left) prime_self_import (basic-llseek-bad) [241.725544] Starting subtest: basic-llseek-bad [241.727330] Subtest basic-llseek-bad: SUCCESS (0.002s) [241.859186] [135/171] (763s left) prime_self_import (basic-llseek-size) [241.969080] Starting subtest: basic-llseek-size [241.973617] Subtest basic-llseek-size: SUCCESS (0.003s) [242.096575] [136/171] (763s left) prime_self_import (basic-with_fd_dup) [242.206679] Starting subtest: basic-with_fd_dup [242.210460] Subtest basic-with_fd_dup: SUCCESS (0.004s) [242.339180] [137/171] (763s left) prime_self_import (basic-with_one_bo) [242.450213] Starting subtest: basic-with_one_bo [242.454860] Subtest basic-with_one_bo: SUCCESS (0.005s) [242.547240] [138/171] (763s left) prime_self_import (basic-with_one_bo_two_files) [242.655474] Starting subtest: basic-with_one_bo_two_files [242.660343] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s) [242.746181] [139/171] (763s left) prime_self_import (basic-with_two_bos) [242.852412] Starting subtest: basic-with_two_bos [242.854465] Subtest basic-with_two_bos: SUCCESS (0.003s) [243.011955] [140/171] (762s left) prime_vgem (basic-fence-flip) [243.134290] Starting subtest: basic-fence-flip [243.610157] Subtest basic-fence-flip: SUCCESS (0.476s) [243.724018] [141/171] (762s left) prime_vgem (basic-fence-mmap) [243.874261] Starting subtest: basic-fence-mmap [243.913095] Subtest basic-fence-mmap: SUCCESS (0.039s) [244.005014] [142/171] (761s left) prime_vgem (basic-fence-read) [244.150405] Starting subtest: basic-fence-read [244.207902] Subtest basic-fence-read: SUCCESS (0.060s) [244.295716] [143/171] (761s left) prime_vgem (basic-gtt) [244.438079] Starting subtest: basic-gtt [244.453982] Subtest basic-gtt: SUCCESS (0.016s) [244.562285] [144/171] (761s left) prime_vgem (basic-read) [244.710931] Starting subtest: basic-read [244.736834] Subtest basic-read: SUCCESS (0.026s) [244.836178] [145/171] (761s left) prime_vgem (basic-write) [244.982968] Starting subtest: basic-write [245.012163] Subtest basic-write: SUCCESS (0.029s) [245.084161] [146/171] (761s left) vgem_basic (setversion) [245.231187] Starting subtest: setversion [245.233416] Subtest setversion: SUCCESS (0.000s) [245.300940] [147/171] (760s left) vgem_basic (create) [245.436180] Starting subtest: create [245.437399] Subtest create: SUCCESS (0.000s) [245.513656] [148/171] (760s left) vgem_basic (debugfs) [245.650695] Starting subtest: debugfs [245.651711] Subtest debugfs: SUCCESS (0.000s) [245.709950] [149/171] (760s left) vgem_basic (dmabuf-export) [245.816630] Starting subtest: dmabuf-export [245.820512] Subtest dmabuf-export: SUCCESS (0.002s) [245.922359] [150/171] (760s left) vgem_basic (dmabuf-fence) [246.064109] Starting subtest: dmabuf-fence [246.065145] Subtest dmabuf-fence: SUCCESS (0.001s) [246.126363] [151/171] (760s left) vgem_basic (dmabuf-fence-before) [246.269424] Starting subtest: dmabuf-fence-before [246.270603] Subtest dmabuf-fence-before: SUCCESS (0.001s) [246.347351] [152/171] (759s left) vgem_basic (dmabuf-mmap) [246.485985] Starting subtest: dmabuf-mmap [246.504676] Subtest dmabuf-mmap: SUCCESS (0.019s) [246.561595] [153/171] (759s left) vgem_basic (mmap) [246.669045] Starting subtest: mmap [246.681750] Subtest mmap: SUCCESS (0.013s) [246.746379] [154/171] (759s left) vgem_basic (second-client) [246.894779] Starting subtest: second-client [246.927730] Subtest second-client: SUCCESS (0.032s) [246.985469] [155/171] (759s left) vgem_basic (sysfs) [247.133136] Starting subtest: sysfs [247.134188] Subtest sysfs: SUCCESS (0.001s) [247.193145] [156/171] (759s left) core_hotunplug (unbind-rebind) [247.358337] Starting subtest: unbind-rebind [249.637117] Subtest unbind-rebind: SUCCESS (2.277s) [249.706648] [157/171] (756s left) vgem_basic (unload) [249.881433] Starting subtest: unload [278.945130] Subtest unload: SUCCESS (29.024s) [279.041778] [158/171] (727s left) i915_module_load (reload) [279.144759] Starting subtest: reload [282.457512] Subtest reload: SUCCESS (3.313s) [282.490896] [159/171] (723s left) gem_lmem_swapping (basic) [282.728232] Subtest basic: SKIP (0.000s) [282.784630] [160/171] (723s left) gem_lmem_swapping (parallel-random-engines) [282.895255] Subtest parallel-random-engines: SKIP (0.000s) [282.937080] [161/171] (723s left) gem_lmem_swapping (random-engines) [283.046515] Subtest random-engines: SKIP (0.000s) [283.084702] [162/171] (723s left) gem_lmem_swapping (verify-random) [283.193581] Subtest verify-random: SKIP (0.000s) [283.234572] [163/171] (723s left) i915_pm_rpm (module-reload) [285.899446] Starting subtest: module-reload [302.547848] Subtest module-reload: SUCCESS (16.639s) [302.583388] [164/171] (703s left) i915_selftest (live) [302.698512] Starting subtest: live [302.699475] Starting dynamic subtest: sanitycheck [304.349708] Dynamic subtest sanitycheck: SUCCESS (1.641s) [304.357068] Starting dynamic subtest: uncore [305.944748] Dynamic subtest uncore: SUCCESS (1.593s) [305.952862] Starting dynamic subtest: workarounds [307.786842] Dynamic subtest workarounds: SUCCESS (1.840s) [307.797847] Starting dynamic subtest: gt_engines [309.935872] Dynamic subtest gt_engines: SUCCESS (2.147s) [309.946169] Starting dynamic subtest: gt_timelines [320.067058] Dynamic subtest gt_timelines: SUCCESS (10.129s) [320.076527] Starting dynamic subtest: gt_contexts [321.832712] Dynamic subtest gt_contexts: SUCCESS (1.764s) [321.842464] Starting dynamic subtest: gt_lrc [326.042837] Dynamic subtest gt_lrc: SUCCESS (4.208s) [326.051296] Starting dynamic subtest: gt_mocs [327.786683] Dynamic subtest gt_mocs: SUCCESS (1.742s) [327.794144] Starting dynamic subtest: gt_pm [333.794292] Dynamic subtest gt_pm: SUCCESS (6.013s) [333.802883] Starting dynamic subtest: gt_heartbeat [335.601595] Dynamic subtest gt_heartbeat: SUCCESS (1.798s) [335.609220] Starting dynamic subtest: gt_tlb [338.110686] Dynamic subtest gt_tlb: SUCCESS (2.507s) [338.121758] Starting dynamic subtest: requests [345.477946] Dynamic subtest requests: SUCCESS (7.365s) [345.489900] Starting dynamic subtest: migrate [349.951075] Dynamic subtest migrate: SUCCESS (4.471s) [349.962642] Starting dynamic subtest: active [351.593787] Dynamic subtest active: SUCCESS (1.641s) [351.602072] Starting dynamic subtest: objects [354.413832] Dynamic subtest objects: SUCCESS (2.818s) [354.426737] Starting dynamic subtest: mman [359.511728] Dynamic subtest mman: SUCCESS (5.096s) [359.519983] Starting dynamic subtest: dmabuf [361.174787] Dynamic subtest dmabuf: SUCCESS (1.661s) [361.187224] Starting dynamic subtest: vma [362.924725] Dynamic subtest vma: SUCCESS (1.748s) [362.936108] Starting dynamic subtest: coherency [365.733628] Dynamic subtest coherency: SUCCESS (2.807s) [365.741153] Starting dynamic subtest: gtt [374.147744] Dynamic subtest gtt: SUCCESS (8.412s) [374.155304] Starting dynamic subtest: gem [376.094680] Dynamic subtest gem: SUCCESS (1.944s) [376.104595] Starting dynamic subtest: evict [379.298691] Dynamic subtest evict: SUCCESS (3.203s) [379.309554] Starting dynamic subtest: hugepages [385.340699] Dynamic subtest hugepages: SUCCESS (6.040s) [385.350884] Starting dynamic subtest: gem_contexts [399.894917] Dynamic subtest gem_contexts: SUCCESS (14.552s) [399.904245] Starting dynamic subtest: client [401.572751] Dynamic subtest client: SUCCESS (1.676s) [401.583900] Starting dynamic subtest: gem_migrate [403.245903] Dynamic subtest gem_migrate: SUCCESS (1.671s) [403.254903] Starting dynamic subtest: reset [405.632610] Dynamic subtest reset: SUCCESS (2.385s) [405.641388] Starting dynamic subtest: memory_region [407.270702] Dynamic subtest memory_region: SUCCESS (1.636s) [407.280803] Starting dynamic subtest: hangcheck [431.368778] Dynamic subtest hangcheck: SUCCESS (24.096s) [431.380332] Starting dynamic subtest: execlists [440.735683] Dynamic subtest execlists: SUCCESS (9.365s) [440.743246] Starting dynamic subtest: ring_submission [442.438741] Dynamic subtest ring_submission: SUCCESS (1.701s) [442.446980] Starting dynamic subtest: perf [444.267624] Dynamic subtest perf: SUCCESS (1.827s) [444.275167] Starting dynamic subtest: slpc [445.928730] Dynamic subtest slpc: SUCCESS (1.659s) [445.936503] Starting dynamic subtest: guc [447.587609] Dynamic subtest guc: SUCCESS (1.657s) [447.597796] Starting dynamic subtest: guc_multi_lrc [449.254919] Dynamic subtest guc_multi_lrc: SUCCESS (1.666s) [449.264733] Starting dynamic subtest: guc_hang [450.948681] Dynamic subtest guc_hang: SUCCESS (1.693s) [450.960553] Starting dynamic subtest: late_gt_pm [452.728233] Dynamic subtest late_gt_pm: SUCCESS (1.775s) [452.729604] Subtest live: SUCCESS (150.022s) [452.959713] [165/171] (553s left) dmabuf (all-tests) [453.072556] Starting subtest: all-tests [453.074110] Starting dynamic subtest: sanitycheck [453.134787] Dynamic subtest sanitycheck: SUCCESS (0.061s) [453.157555] Starting dynamic subtest: dma_fence [453.310407] Dynamic subtest dma_fence: SUCCESS (0.172s) [453.321233] Starting dynamic subtest: dma_fence_chain [469.189360] Dynamic subtest dma_fence_chain: SUCCESS (15.867s) [469.213715] Starting dynamic subtest: dma_fence_unwrap [469.266126] Dynamic subtest dma_fence_unwrap: SUCCESS (0.082s) [469.294511] Starting dynamic subtest: dma_resv [469.339189] Dynamic subtest dma_resv: SUCCESS (0.068s) [469.340326] Subtest all-tests: SUCCESS (16.268s) [469.361461] [166/171] (537s left) i915_suspend (basic-s2idle-without-i915) [469.464727] Starting subtest: basic-s2idle-without-i915 [489.463880] Subtest basic-s2idle-without-i915: SUCCESS (4.240s) [491.848199] [167/171] (532s left) i915_suspend (basic-s3-without-i915) [491.959551] Starting subtest: basic-s3-without-i915 [512.253452] Subtest basic-s3-without-i915: SUCCESS (4.207s) [515.679382] [168/171] (527s left) gem_exec_suspend (basic-s0) [515.849675] Starting subtest: basic-S0 [515.852282] Starting dynamic subtest: smem [534.545172] Dynamic subtest smem: SUCCESS (3.236s) [534.546244] Subtest basic-S0: SUCCESS (3.238s) [537.856833] [169/171] (524s left) gem_exec_suspend (basic-s3) [538.014887] Starting subtest: basic-S3 [538.016031] Starting dynamic subtest: smem [558.606116] Dynamic subtest smem: SUCCESS (4.326s) [558.607408] Subtest basic-S3: SUCCESS (4.329s) [560.855617] [170/171] (519s left) kms_chamelium_hpd (common-hpd-after-suspend) [561.335422] Subtest common-hpd-after-suspend: SKIP (0.000s) [562.212124] [171/171] (518s left) kms_pipe_crc_basic (suspend-read-crc) [562.360833] Starting subtest: suspend-read-crc [562.364656] Starting dynamic subtest: pipe-A-eDP-1 [582.351074] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.154s) [582.363706] Starting dynamic subtest: pipe-B-eDP-1 [603.522832] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.076s) [603.539595] Starting dynamic subtest: pipe-C-eDP-1 [632.758876] Dynamic subtest pipe-C-eDP-1: SUCCESS (13.120s) [632.761609] Subtest suspend-read-crc: SUCCESS (21.364s) [634.622849] Closing watchdogs Done.