[41.161524] Initializing watchdogs [41.161646] /dev/watchdog0 [41.168389] [001/171] (960s left) i915_module_load (load) [41.274402] Starting subtest: load [42.349569] Subtest load: SUCCESS (1.075s) [42.387734] [002/171] (959s left) core_auth (basic-auth) [42.560656] Starting subtest: basic-auth [42.561745] Subtest basic-auth: SUCCESS (0.001s) [42.664976] [003/171] (959s left) debugfs_test (read_all_entries) [42.786223] Starting subtest: read_all_entries [42.870189] Subtest read_all_entries: SUCCESS (0.084s) [42.980063] [004/171] (958s left) debugfs_test (basic-hwmon) [43.083822] Starting subtest: basic-hwmon [43.085493] Subtest basic-hwmon: SKIP (0.000s) [43.204480] [005/171] (958s left) fbdev (eof) [43.318094] Starting subtest: eof [43.319095] Subtest eof: SUCCESS (0.001s) [43.378324] [006/171] (958s left) fbdev (info) [43.471138] Starting subtest: info [43.472121] Subtest info: SUCCESS (0.000s) [43.540066] [007/171] (958s left) fbdev (nullptr) [43.650847] Starting subtest: nullptr [43.651848] Subtest nullptr: SUCCESS (0.000s) [43.706814] [008/171] (958s left) fbdev (read) [43.817784] Starting subtest: read [44.665700] Subtest read: SUCCESS (0.839s) [44.708424] [009/171] (957s left) fbdev (write) [44.818480] Starting subtest: write [45.240858] Subtest write: SUCCESS (0.414s) [45.327062] [010/171] (956s left) gem_basic (bad-close) [45.426166] Starting subtest: bad-close [45.428076] Subtest bad-close: SUCCESS (0.000s) [45.485133] [011/171] (956s left) gem_basic (create-close) [45.590540] Starting subtest: create-close [45.592200] Subtest create-close: SUCCESS (0.000s) [45.676238] [012/171] (956s left) gem_basic (create-fd-close) [45.784063] Starting subtest: create-fd-close [45.785723] Subtest create-fd-close: SUCCESS (0.000s) [45.897723] [013/171] (956s left) gem_busy (busy@all-engines) [46.020256] Starting subtest: busy [46.023529] Starting dynamic subtest: all-engines [46.078301] Dynamic subtest all-engines: SUCCESS (0.058s) [46.079347] Subtest busy: SUCCESS (0.058s) [46.542373] [014/171] (955s left) gem_close_race (basic-process) [46.663954] Starting subtest: basic-process [46.725362] Subtest basic-process: SUCCESS (0.062s) [46.941375] [015/171] (955s left) gem_close_race (basic-threads) [47.060476] Starting subtest: basic-threads [48.211795] Subtest basic-threads: SUCCESS (1.151s) [48.445534] [016/171] (953s left) gem_ctx_create (basic) [48.563474] Starting subtest: basic [48.564447] Subtest basic: SUCCESS (0.000s) [48.772526] [017/171] (953s left) gem_ctx_create (basic-files) [48.893097] Starting subtest: basic-files [50.915523] Subtest basic-files: SUCCESS (2.023s) [51.226106] [018/171] (950s left) gem_ctx_exec (basic) [51.344440] Starting subtest: basic [51.350290] Subtest basic: SUCCESS (0.006s) [51.472056] [019/171] (950s left) gem_exec_basic (basic) [51.589144] Starting subtest: basic [51.590276] Starting dynamic subtest: rcs0-smem [51.591290] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [51.599263] Starting dynamic subtest: bcs0-smem [51.600214] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [51.607041] Starting dynamic subtest: vcs0-smem [51.608057] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [51.614873] Starting dynamic subtest: vecs0-smem [51.615895] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [51.616934] Subtest basic: SUCCESS (0.007s) [51.852122] [020/171] (950s left) gem_exec_create (basic) [51.968120] Starting subtest: basic [51.969438] Starting dynamic subtest: smem [54.019567] Dynamic subtest smem: SUCCESS (2.051s) [54.020616] Subtest basic: SUCCESS (2.052s) [54.267257] [021/171] (947s left) gem_exec_fence (basic-busy) [54.546586] Starting subtest: basic-busy [54.547853] Starting dynamic subtest: rcs0 [54.576283] Dynamic subtest rcs0: SUCCESS (0.030s) [54.584462] Starting dynamic subtest: bcs0 [54.596490] Dynamic subtest bcs0: SUCCESS (0.020s) [54.603857] Starting dynamic subtest: vcs0 [54.611219] Dynamic subtest vcs0: SUCCESS (0.015s) [54.619567] Starting dynamic subtest: vecs0 [54.632245] Dynamic subtest vecs0: SUCCESS (0.021s) [54.633251] Subtest basic-busy: SUCCESS (0.086s) [55.347982] [022/171] (946s left) gem_exec_fence (basic-wait) [55.615636] Starting subtest: basic-wait [55.618500] Starting dynamic subtest: rcs0 [55.647572] Dynamic subtest rcs0: SUCCESS (0.032s) [55.662461] Starting dynamic subtest: bcs0 [55.665621] Dynamic subtest bcs0: SUCCESS (0.017s) [55.675672] Starting dynamic subtest: vcs0 [55.691356] Dynamic subtest vcs0: SUCCESS (0.027s) [55.702237] Starting dynamic subtest: vecs0 [55.711364] Dynamic subtest vecs0: SUCCESS (0.020s) [55.717978] Subtest basic-wait: SUCCESS (0.097s) [56.363454] [023/171] (945s left) gem_exec_fence (basic-await) [56.629341] Starting subtest: basic-await [56.631097] Starting dynamic subtest: rcs0 [56.733239] Dynamic subtest rcs0: SUCCESS (0.104s) [56.741476] Starting dynamic subtest: bcs0 [56.830635] Dynamic subtest bcs0: SUCCESS (0.097s) [56.842579] Starting dynamic subtest: vcs0 [56.932922] Dynamic subtest vcs0: SUCCESS (0.102s) [56.942349] Starting dynamic subtest: vecs0 [57.032319] Dynamic subtest vecs0: SUCCESS (0.099s) [57.033482] Subtest basic-await: SUCCESS (0.404s) [57.779955] [024/171] (944s left) gem_exec_fence (nb-await) [58.072476] Starting subtest: nb-await [58.073565] Starting dynamic subtest: rcs0 [58.140180] Dynamic subtest rcs0: SUCCESS (0.066s) [58.163059] Starting dynamic subtest: bcs0 [58.224542] Dynamic subtest bcs0: SUCCESS (0.081s) [58.273675] Starting dynamic subtest: vcs0 [58.346330] Dynamic subtest vcs0: SUCCESS (0.119s) [58.406254] Starting dynamic subtest: vecs0 [58.471423] Dynamic subtest vecs0: SUCCESS (0.124s) [58.474574] Subtest nb-await: SUCCESS (0.395s) [59.206335] [025/171] (942s left) gem_exec_gttfill (basic) [59.328020] Starting subtest: basic [69.019345] Subtest basic: SUCCESS (9.683s) [69.208256] [026/171] (933s left) gem_exec_parallel (engines) [69.385278] Starting subtest: engines [69.386785] Starting dynamic subtest: basic [71.722059] Dynamic subtest basic: SUCCESS (2.338s) [71.730746] Starting dynamic subtest: contexts [74.628154] Dynamic subtest contexts: SUCCESS (2.897s) [74.636782] Starting dynamic subtest: fds [78.355181] Dynamic subtest fds: SUCCESS (3.729s) [78.366174] Starting dynamic subtest: userptr [80.684258] Dynamic subtest userptr: SUCCESS (2.335s) [80.685827] Subtest engines: SUCCESS (11.300s) [80.916274] [027/171] (921s left) gem_exec_store (basic) [81.039645] Starting subtest: basic [81.085412] Subtest basic: SUCCESS (0.046s) [81.301099] [028/171] (920s left) gem_flink_basic (bad-flink) [81.407334] Starting subtest: bad-flink [81.408343] Subtest bad-flink: SUCCESS (0.000s) [81.504440] [029/171] (920s left) gem_flink_basic (bad-open) [81.611193] Starting subtest: bad-open [81.612161] Subtest bad-open: SUCCESS (0.000s) [81.709983] [030/171] (920s left) gem_flink_basic (basic) [81.816595] Starting subtest: basic [81.817735] Subtest basic: SUCCESS (0.000s) [81.917633] [031/171] (920s left) gem_flink_basic (double-flink) [82.024406] Starting subtest: double-flink [82.025456] Subtest double-flink: SUCCESS (0.000s) [82.130824] [032/171] (920s left) gem_flink_basic (flink-lifetime) [82.236578] Starting subtest: flink-lifetime [82.237704] Subtest flink-lifetime: SUCCESS (0.002s) [82.336654] [033/171] (920s left) gem_huc_copy (huc-copy) [82.453630] Starting subtest: huc-copy [82.455438] Subtest huc-copy: SKIP (0.000s) [82.597922] [034/171] (919s left) gem_linear_blits (basic) [82.852018] Starting subtest: basic [82.861143] Subtest basic: SUCCESS (0.009s) [83.009577] [035/171] (919s left) gem_mmap (basic) [83.169414] Starting subtest: basic [83.174232] Subtest basic: SUCCESS (0.001s) [83.261838] [036/171] (919s left) gem_mmap_gtt (basic) [83.369616] Starting subtest: basic [83.370760] Subtest basic: SUCCESS (0.001s) [83.473587] [037/171] (918s left) gem_render_linear_blits (basic) [83.590054] Starting subtest: basic [83.630139] Subtest basic: SUCCESS (0.040s) [83.668846] [038/171] (918s left) gem_render_tiled_blits (basic) [83.782190] Starting subtest: basic [83.826441] Subtest basic: SUCCESS (0.044s) [83.868645] [039/171] (918s left) gem_ringfill (basic-all) [84.178241] Starting subtest: basic-all [85.354901] Subtest basic-all: SUCCESS (1.168s) [85.490884] [040/171] (917s left) gem_softpin (allocator-basic) [85.602989] Starting subtest: allocator-basic [85.741593] Subtest allocator-basic: SUCCESS (0.130s) [85.875403] [041/171] (916s left) gem_softpin (allocator-basic-reserve) [85.992499] Starting subtest: allocator-basic-reserve [86.133405] Subtest allocator-basic-reserve: SUCCESS (0.132s) [86.271781] [042/171] (916s left) gem_softpin (safe-alignment) [86.383770] Starting subtest: safe-alignment [86.399097] Subtest safe-alignment: SUCCESS (0.015s) [86.535669] [043/171] (916s left) gem_sync (basic-all) [86.658311] Starting subtest: basic-all [88.845450] Subtest basic-all: SUCCESS (2.187s) [89.071628] [044/171] (913s left) gem_sync (basic-each) [89.195328] Starting subtest: basic-each [91.413413] Subtest basic-each: SUCCESS (2.218s) [91.670161] [045/171] (910s left) gem_tiled_blits (basic) [91.926044] Starting subtest: basic [91.950067] Subtest basic: SUCCESS (0.024s) [92.102429] [046/171] (910s left) gem_tiled_fence_blits (basic) [92.412749] Starting subtest: basic [92.425636] Subtest basic: SUCCESS (0.013s) [92.574583] [047/171] (910s left) gem_tiled_pread_basic [92.950331] [048/171] (909s left) gem_wait (busy@all-engines) [93.085383] Starting subtest: busy [93.086466] Starting dynamic subtest: all-engines [93.619614] Dynamic subtest all-engines: SUCCESS (0.534s) [93.620928] Subtest busy: SUCCESS (0.534s) [93.863523] [049/171] (908s left) gem_wait (wait@all-engines) [93.993817] Starting subtest: wait [93.994911] Starting dynamic subtest: all-engines [95.053676] Dynamic subtest all-engines: SUCCESS (1.058s) [95.057055] Subtest wait: SUCCESS (1.059s) [95.339218] [050/171] (907s left) i915_getparams_basic (basic-eu-total) [95.445520] Starting subtest: basic-eu-total [95.447592] Subtest basic-eu-total: SUCCESS (0.000s) [95.542980] [051/171] (907s left) i915_getparams_basic (basic-subslice-total) [95.648829] Starting subtest: basic-subslice-total [95.650704] Subtest basic-subslice-total: SUCCESS (0.000s) [95.749447] [052/171] (907s left) i915_hangman (error-state-basic) [95.868465] Starting subtest: error-state-basic [95.879934] Subtest error-state-basic: SUCCESS (0.010s) [95.962428] [053/171] (906s left) i915_pciid [96.131974] [054/171] (906s left) kms_addfb_basic (addfb25-bad-modifier) [96.240895] Starting subtest: addfb25-bad-modifier [96.242052] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [96.398138] [055/171] (906s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [96.506821] Starting subtest: addfb25-framebuffer-vs-set-tiling [96.507776] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [96.660293] [056/171] (906s left) kms_addfb_basic (addfb25-modifier-no-flag) [96.768953] Starting subtest: addfb25-modifier-no-flag [96.769922] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [96.908698] [057/171] (905s left) kms_addfb_basic (addfb25-x-tiled-legacy) [97.014122] Starting subtest: addfb25-x-tiled-legacy [97.015149] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [97.153158] [058/171] (905s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [97.258277] Starting subtest: addfb25-x-tiled-mismatch-legacy [97.259207] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [97.406048] [059/171] (905s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [97.546117] Starting subtest: addfb25-yf-tiled-legacy [97.547062] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [97.645311] [060/171] (905s left) kms_addfb_basic (addfb25-y-tiled-legacy) [97.784472] Starting subtest: addfb25-y-tiled-legacy [97.785411] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [97.880184] [061/171] (905s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [98.016739] Starting subtest: addfb25-y-tiled-small-legacy [98.017757] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [98.120043] [062/171] (904s left) kms_addfb_basic (bad-pitch-0) [98.218331] Starting subtest: bad-pitch-0 [98.219495] Subtest bad-pitch-0: SUCCESS (0.000s) [98.372811] [063/171] (904s left) kms_addfb_basic (bad-pitch-1024) [98.474150] Starting subtest: bad-pitch-1024 [98.475179] Subtest bad-pitch-1024: SUCCESS (0.000s) [98.611633] [064/171] (904s left) kms_addfb_basic (bad-pitch-128) [98.710480] Starting subtest: bad-pitch-128 [98.711476] Subtest bad-pitch-128: SUCCESS (0.000s) [98.842120] [065/171] (904s left) kms_addfb_basic (bad-pitch-256) [98.939460] Starting subtest: bad-pitch-256 [98.940455] Subtest bad-pitch-256: SUCCESS (0.000s) [99.069296] [066/171] (903s left) kms_addfb_basic (bad-pitch-32) [99.166446] Starting subtest: bad-pitch-32 [99.167421] Subtest bad-pitch-32: SUCCESS (0.000s) [99.288821] [067/171] (903s left) kms_addfb_basic (bad-pitch-63) [99.385376] Starting subtest: bad-pitch-63 [99.386393] Subtest bad-pitch-63: SUCCESS (0.000s) [99.515388] [068/171] (903s left) kms_addfb_basic (bad-pitch-65536) [99.612236] Starting subtest: bad-pitch-65536 [99.613156] Subtest bad-pitch-65536: SUCCESS (0.000s) [99.767769] [069/171] (903s left) kms_addfb_basic (bad-pitch-999) [99.862160] Starting subtest: bad-pitch-999 [99.863019] Subtest bad-pitch-999: SUCCESS (0.000s) [100.000736] [070/171] (903s left) kms_addfb_basic (basic) [100.107848] Starting subtest: basic [100.109175] Subtest basic: SUCCESS (0.000s) [100.258874] [071/171] (902s left) kms_addfb_basic (basic-x-tiled-legacy) [100.356612] Starting subtest: basic-x-tiled-legacy [100.357655] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [100.500812] [072/171] (902s left) kms_addfb_basic (basic-y-tiled-legacy) [100.598667] Starting subtest: basic-y-tiled-legacy [100.599699] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [100.730457] [073/171] (902s left) kms_addfb_basic (bo-too-small) [100.864560] Starting subtest: bo-too-small [100.865453] Subtest bo-too-small: SUCCESS (0.000s) [100.967500] [074/171] (902s left) kms_addfb_basic (bo-too-small-due-to-tiling) [101.109519] Starting subtest: bo-too-small-due-to-tiling [101.110610] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [101.217827] [075/171] (902s left) kms_addfb_basic (clobberred-modifier) [101.331374] Starting subtest: clobberred-modifier [101.332456] Subtest clobberred-modifier: SUCCESS (0.000s) [101.491402] [076/171] (901s left) kms_addfb_basic (framebuffer-vs-set-tiling) [101.606875] Starting subtest: framebuffer-vs-set-tiling [101.608114] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [101.742173] [077/171] (901s left) kms_addfb_basic (invalid-get-prop) [101.841360] Starting subtest: invalid-get-prop [101.842294] Subtest invalid-get-prop: SUCCESS (0.000s) [101.989158] [078/171] (901s left) kms_addfb_basic (invalid-get-prop-any) [102.085429] Starting subtest: invalid-get-prop-any [102.086396] Subtest invalid-get-prop-any: SUCCESS (0.000s) [102.219898] [079/171] (901s left) kms_addfb_basic (invalid-set-prop) [102.316986] Starting subtest: invalid-set-prop [102.317978] Subtest invalid-set-prop: SUCCESS (0.000s) [102.460305] [080/171] (900s left) kms_addfb_basic (invalid-set-prop-any) [102.557461] Starting subtest: invalid-set-prop-any [102.558367] Subtest invalid-set-prop-any: SUCCESS (0.000s) [102.711731] [081/171] (900s left) kms_addfb_basic (no-handle) [102.809396] Starting subtest: no-handle [102.810431] Subtest no-handle: SUCCESS (0.000s) [102.946839] [082/171] (900s left) kms_addfb_basic (size-max) [103.076528] Starting subtest: size-max [103.077558] Subtest size-max: SUCCESS (0.000s) [103.171792] [083/171] (900s left) kms_addfb_basic (small-bo) [103.311122] Starting subtest: small-bo [103.312163] Subtest small-bo: SUCCESS (0.000s) [103.407877] [084/171] (900s left) kms_addfb_basic (tile-pitch-mismatch) [103.503456] Starting subtest: tile-pitch-mismatch [103.504490] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [103.630770] [085/171] (899s left) kms_addfb_basic (too-high) [103.764124] Starting subtest: too-high [103.764976] Subtest too-high: SUCCESS (0.001s) [103.861526] [086/171] (899s left) kms_addfb_basic (too-wide) [103.994868] Starting subtest: too-wide [103.995828] Subtest too-wide: SUCCESS (0.000s) [104.103258] [087/171] (899s left) kms_addfb_basic (unused-handle) [104.197931] Starting subtest: unused-handle [104.198922] Subtest unused-handle: SUCCESS (0.000s) [104.348138] [088/171] (899s left) kms_addfb_basic (unused-modifier) [104.445197] Starting subtest: unused-modifier [104.446189] Subtest unused-modifier: SUCCESS (0.000s) [104.582799] [089/171] (898s left) kms_addfb_basic (unused-offsets) [104.678203] Starting subtest: unused-offsets [104.679102] Subtest unused-offsets: SUCCESS (0.000s) [104.813090] [090/171] (898s left) kms_addfb_basic (unused-pitches) [104.909429] Starting subtest: unused-pitches [104.910342] Subtest unused-pitches: SUCCESS (0.000s) [105.051246] [091/171] (898s left) kms_busy (basic) [105.186392] Starting subtest: basic [105.187411] Starting dynamic subtest: flip [106.219630] Dynamic subtest flip: SUCCESS (1.027s) [106.234593] Starting dynamic subtest: modeset [109.997414] Dynamic subtest modeset: SUCCESS (3.782s) [110.000473] Subtest basic: SUCCESS (4.811s) [110.905155] [092/171] (892s left) kms_chamelium_hpd (dp-hpd-fast) [111.357408] Subtest dp-hpd-fast: SKIP (0.000s) [112.266150] [093/171] (891s left) kms_chamelium_edid (dp-edid-read) [112.718599] Subtest dp-edid-read: SKIP (0.000s) [113.619626] [094/171] (890s left) kms_chamelium_frames (dp-crc-fast) [114.063356] Subtest dp-crc-fast: SKIP (0.000s) [114.969082] [095/171] (888s left) kms_chamelium_hpd (hdmi-hpd-fast) [115.413202] Subtest hdmi-hpd-fast: SKIP (0.000s) [116.307233] [096/171] (887s left) kms_chamelium_edid (hdmi-edid-read) [116.741335] Subtest hdmi-edid-read: SKIP (0.000s) [117.646753] [097/171] (886s left) kms_chamelium_frames (hdmi-crc-fast) [118.085764] Subtest hdmi-crc-fast: SKIP (0.000s) [118.993085] [098/171] (884s left) kms_chamelium_hpd (vga-hpd-fast) [119.447445] Subtest vga-hpd-fast: SKIP (0.000s) [120.338624] [099/171] (883s left) kms_chamelium_edid (vga-edid-read) [120.781222] Subtest vga-edid-read: SKIP (0.000s) [121.683927] [100/171] (882s left) kms_prop_blob (basic) [121.792865] Starting subtest: basic [121.794022] Subtest basic: SUCCESS (0.000s) [121.860207] [101/171] (881s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [122.004399] Starting subtest: basic-busy-flip-before-cursor-atomic [122.006420] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [122.090682] [102/171] (881s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [122.236884] Starting subtest: basic-busy-flip-before-cursor-legacy [122.238993] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [122.326599] [103/171] (881s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [122.471126] Starting subtest: basic-flip-after-cursor-atomic [123.734768] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.261s) [124.680727] [104/171] (879s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [124.825364] Starting subtest: basic-flip-after-cursor-legacy [126.097866] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.269s) [127.036469] [105/171] (876s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [127.184083] Starting subtest: basic-flip-after-cursor-varying-size [128.523243] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.339s) [129.477561] [106/171] (874s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [129.624493] Starting subtest: basic-flip-before-cursor-atomic [130.932575] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.306s) [131.820323] [107/171] (872s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [131.963766] Starting subtest: basic-flip-before-cursor-legacy [133.227561] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.264s) [134.150018] [108/171] (869s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [134.297502] Starting subtest: basic-flip-before-cursor-varying-size [135.568598] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.270s) [136.511745] [109/171] (867s left) kms_flip (basic-flip-vs-dpms) [136.636758] Starting subtest: basic-flip-vs-dpms [136.639503] Starting dynamic subtest: A-eDP1 [139.521551] Dynamic subtest A-eDP1: SUCCESS (2.881s) [139.534071] Starting dynamic subtest: B-eDP1 [141.893157] Dynamic subtest B-eDP1: SUCCESS (2.370s) [141.907362] Starting dynamic subtest: C-eDP1 [144.268520] Dynamic subtest C-eDP1: SUCCESS (2.373s) [144.273430] Subtest basic-flip-vs-dpms: SUCCESS (7.638s) [145.149587] [110/171] (858s left) kms_flip (basic-flip-vs-modeset) [145.273135] Starting subtest: basic-flip-vs-modeset [145.277478] Starting dynamic subtest: A-eDP1 [148.125237] Dynamic subtest A-eDP1: SUCCESS (2.844s) [148.136241] Starting dynamic subtest: B-eDP1 [150.531810] Dynamic subtest B-eDP1: SUCCESS (2.403s) [150.545140] Starting dynamic subtest: C-eDP1 [152.880173] Dynamic subtest C-eDP1: SUCCESS (2.349s) [152.882972] Subtest basic-flip-vs-modeset: SUCCESS (7.610s) [153.794088] [111/171] (850s left) kms_flip (basic-flip-vs-wf_vblank) [153.917065] Starting subtest: basic-flip-vs-wf_vblank [153.922407] Starting dynamic subtest: A-eDP1 [156.246969] Dynamic subtest A-eDP1: SUCCESS (2.325s) [156.263353] Starting dynamic subtest: B-eDP1 [158.494409] Dynamic subtest B-eDP1: SUCCESS (2.246s) [158.507401] Starting dynamic subtest: C-eDP1 [160.792598] Dynamic subtest C-eDP1: SUCCESS (2.297s) [160.796878] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.881s) [162.110087] [112/171] (841s left) kms_flip (basic-plain-flip) [162.232925] Starting subtest: basic-plain-flip [162.236242] Starting dynamic subtest: A-eDP1 [164.219617] Dynamic subtest A-eDP1: SUCCESS (1.983s) [164.230323] Starting dynamic subtest: B-eDP1 [166.219587] Dynamic subtest B-eDP1: SUCCESS (1.990s) [166.238024] Starting dynamic subtest: C-eDP1 [168.194438] Dynamic subtest C-eDP1: SUCCESS (1.972s) [168.212956] Subtest basic-plain-flip: SUCCESS (5.981s) [169.532016] [113/171] (834s left) kms_force_connector_basic (force-connector-state) [169.668799] Starting subtest: force-connector-state [170.272899] Subtest force-connector-state: SUCCESS (0.605s) [171.284804] [114/171] (832s left) kms_force_connector_basic (force-edid) [171.421699] Starting subtest: force-edid [171.454451] Subtest force-edid: SUCCESS (0.033s) [171.610887] [115/171] (832s left) kms_force_connector_basic (force-load-detect) [171.743802] Starting subtest: force-load-detect [171.745714] Subtest force-load-detect: SKIP (0.000s) [171.889185] [116/171] (832s left) kms_force_connector_basic (prune-stale-modes) [172.020779] Starting subtest: prune-stale-modes [172.053131] Subtest prune-stale-modes: SUCCESS (0.033s) [172.225799] [117/171] (831s left) kms_frontbuffer_tracking (basic) [172.381503] Starting subtest: basic [175.278598] Subtest basic: SUCCESS (2.897s) [175.393806] [118/171] (828s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [175.533463] Starting subtest: compare-crc-sanitycheck-xr24 [175.536556] Starting dynamic subtest: pipe-A-eDP-1 [176.164219] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.628s) [176.176587] Starting dynamic subtest: pipe-B-eDP-1 [177.483156] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.316s) [177.495827] Starting dynamic subtest: pipe-C-eDP-1 [178.803751] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.316s) [178.804668] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.271s) [179.746028] [119/171] (824s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [179.877919] Starting subtest: compare-crc-sanitycheck-nv12 [179.878937] Starting dynamic subtest: pipe-A-eDP-1 [179.880857] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [179.891685] Starting dynamic subtest: pipe-B-eDP-1 [179.893695] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [179.904313] Starting dynamic subtest: pipe-C-eDP-1 [179.906239] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [179.908186] Subtest compare-crc-sanitycheck-nv12: SKIP (0.009s) [179.971311] [120/171] (824s left) kms_pipe_crc_basic (hang-read-crc) [180.107054] Starting subtest: hang-read-crc [180.108180] Starting dynamic subtest: pipe-A-eDP-1 [182.317598] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.210s) [182.331442] Starting dynamic subtest: pipe-B-eDP-1 [185.241191] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.919s) [185.253193] Starting dynamic subtest: pipe-C-eDP-1 [188.187739] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.941s) [188.188868] Subtest hang-read-crc: SUCCESS (8.081s) [189.115023] [121/171] (815s left) kms_pipe_crc_basic (nonblocking-crc) [189.268513] Starting subtest: nonblocking-crc [189.269592] Starting dynamic subtest: pipe-A-eDP-1 [190.080343] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.811s) [190.097599] Starting dynamic subtest: pipe-B-eDP-1 [191.619248] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.532s) [191.642801] Starting dynamic subtest: pipe-C-eDP-1 [193.146276] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.522s) [193.147374] Subtest nonblocking-crc: SUCCESS (3.879s) [194.113946] [122/171] (810s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [194.261433] Starting subtest: nonblocking-crc-frame-sequence [194.262615] Starting dynamic subtest: pipe-A-eDP-1 [195.059956] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.796s) [195.081686] Starting dynamic subtest: pipe-B-eDP-1 [196.589248] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.520s) [196.614165] Starting dynamic subtest: pipe-C-eDP-1 [198.131836] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.536s) [198.136908] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.873s) [199.040635] [123/171] (805s left) kms_pipe_crc_basic (read-crc) [199.188909] Starting subtest: read-crc [199.191475] Starting dynamic subtest: pipe-A-eDP-1 [199.921650] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.729s) [199.935700] Starting dynamic subtest: pipe-B-eDP-1 [201.434476] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.509s) [201.453389] Starting dynamic subtest: pipe-C-eDP-1 [202.884525] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.438s) [202.886388] Subtest read-crc: SUCCESS (3.694s) [203.845180] [124/171] (800s left) kms_pipe_crc_basic (read-crc-frame-sequence) [203.993703] Starting subtest: read-crc-frame-sequence [203.994783] Starting dynamic subtest: pipe-A-eDP-1 [204.717469] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.723s) [204.737107] Starting dynamic subtest: pipe-B-eDP-1 [206.198365] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.475s) [206.214245] Starting dynamic subtest: pipe-C-eDP-1 [207.643993] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.442s) [207.645090] Subtest read-crc-frame-sequence: SUCCESS (3.652s) [208.577128] [125/171] (795s left) kms_psr (primary_page_flip) [208.731560] Starting subtest: primary_page_flip [210.689413] Subtest primary_page_flip: SUCCESS (1.958s) [211.643499] [126/171] (792s left) kms_psr (cursor_plane_move) [211.791442] Starting subtest: cursor_plane_move [213.724216] Subtest cursor_plane_move: SUCCESS (1.932s) [214.650407] [127/171] (789s left) kms_psr (sprite_plane_onoff) [214.797371] Starting subtest: sprite_plane_onoff [216.794349] Subtest sprite_plane_onoff: SUCCESS (1.996s) [217.728511] [128/171] (786s left) kms_psr (primary_mmap_gtt) [217.879597] Starting subtest: primary_mmap_gtt [219.837667] Subtest primary_mmap_gtt: SUCCESS (1.957s) [220.794376] [129/171] (783s left) kms_setmode (basic-clone-single-crtc) [220.903561] Starting subtest: basic-clone-single-crtc [220.906689] Subtest basic-clone-single-crtc: SKIP (0.000s) [221.031233] [130/171] (783s left) i915_pm_backlight (basic-brightness) [221.183493] Starting subtest: basic-brightness [221.287202] Starting dynamic subtest: eDP-1 [221.906657] Dynamic subtest eDP-1: SUCCESS (0.618s) [221.907960] Subtest basic-brightness: SUCCESS (0.725s) [222.849896] [131/171] (781s left) i915_pm_rpm (basic-pci-d3-state) [224.607495] Starting subtest: basic-pci-d3-state [229.098367] Subtest basic-pci-d3-state: SUCCESS (4.490s) [229.254134] [132/171] (775s left) i915_pm_rpm (basic-rte) [229.358046] Starting subtest: basic-rte [235.574441] Subtest basic-rte: SUCCESS (6.216s) [240.962369] [133/171] (763s left) i915_pm_rps (basic-api) [241.075705] Starting subtest: basic-api [241.080738] Subtest basic-api: SUCCESS (0.005s) [241.209321] [134/171] (763s left) prime_self_import (basic-llseek-bad) [241.315706] Starting subtest: basic-llseek-bad [241.320247] Subtest basic-llseek-bad: SUCCESS (0.002s) [241.441232] [135/171] (763s left) prime_self_import (basic-llseek-size) [241.546309] Starting subtest: basic-llseek-size [241.550911] Subtest basic-llseek-size: SUCCESS (0.003s) [241.665565] [136/171] (762s left) prime_self_import (basic-with_fd_dup) [241.772735] Starting subtest: basic-with_fd_dup [241.774990] Subtest basic-with_fd_dup: SUCCESS (0.003s) [241.913685] [137/171] (762s left) prime_self_import (basic-with_one_bo) [242.017471] Starting subtest: basic-with_one_bo [242.022240] Subtest basic-with_one_bo: SUCCESS (0.004s) [242.096271] [138/171] (762s left) prime_self_import (basic-with_one_bo_two_files) [242.203233] Starting subtest: basic-with_one_bo_two_files [242.208069] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s) [242.299973] [139/171] (762s left) prime_self_import (basic-with_two_bos) [242.412509] Starting subtest: basic-with_two_bos [242.417217] Subtest basic-with_two_bos: SUCCESS (0.004s) [242.535593] [140/171] (762s left) prime_vgem (basic-fence-flip) [242.673261] Starting subtest: basic-fence-flip [243.182398] Subtest basic-fence-flip: SUCCESS (0.509s) [243.293197] [141/171] (761s left) prime_vgem (basic-fence-mmap) [243.446435] Starting subtest: basic-fence-mmap [243.484991] Subtest basic-fence-mmap: SUCCESS (0.038s) [243.578540] [142/171] (761s left) prime_vgem (basic-fence-read) [243.722624] Starting subtest: basic-fence-read [243.780618] Subtest basic-fence-read: SUCCESS (0.058s) [243.872640] [143/171] (760s left) prime_vgem (basic-gtt) [244.017135] Starting subtest: basic-gtt [244.033264] Subtest basic-gtt: SUCCESS (0.016s) [244.142042] [144/171] (760s left) prime_vgem (basic-read) [244.285720] Starting subtest: basic-read [244.311739] Subtest basic-read: SUCCESS (0.026s) [244.438953] [145/171] (760s left) prime_vgem (basic-write) [244.579791] Starting subtest: basic-write [244.608512] Subtest basic-write: SUCCESS (0.029s) [244.681558] [146/171] (760s left) vgem_basic (setversion) [244.789087] Starting subtest: setversion [244.791053] Subtest setversion: SUCCESS (0.000s) [244.848334] [147/171] (760s left) vgem_basic (create) [244.955117] Starting subtest: create [244.956241] Subtest create: SUCCESS (0.000s) [245.015512] [148/171] (759s left) vgem_basic (debugfs) [245.161049] Starting subtest: debugfs [245.162131] Subtest debugfs: SUCCESS (0.001s) [245.246727] [149/171] (759s left) vgem_basic (dmabuf-export) [245.375263] Starting subtest: dmabuf-export [245.377608] Subtest dmabuf-export: SUCCESS (0.002s) [245.496800] [150/171] (759s left) vgem_basic (dmabuf-fence) [245.625179] Starting subtest: dmabuf-fence [245.626214] Subtest dmabuf-fence: SUCCESS (0.001s) [245.687831] [151/171] (759s left) vgem_basic (dmabuf-fence-before) [245.830475] Starting subtest: dmabuf-fence-before [245.831600] Subtest dmabuf-fence-before: SUCCESS (0.000s) [245.896998] [152/171] (759s left) vgem_basic (dmabuf-mmap) [246.035653] Starting subtest: dmabuf-mmap [246.053970] Subtest dmabuf-mmap: SUCCESS (0.019s) [246.111457] [153/171] (758s left) vgem_basic (mmap) [246.258244] Starting subtest: mmap [246.272089] Subtest mmap: SUCCESS (0.014s) [246.338304] [154/171] (758s left) vgem_basic (second-client) [246.482991] Starting subtest: second-client [246.516109] Subtest second-client: SUCCESS (0.032s) [246.574505] [155/171] (758s left) vgem_basic (sysfs) [246.721731] Starting subtest: sysfs [246.722934] Subtest sysfs: SUCCESS (0.001s) [246.779468] [156/171] (758s left) core_hotunplug (unbind-rebind) [246.943259] Starting subtest: unbind-rebind [249.236750] Subtest unbind-rebind: SUCCESS (2.291s) [249.290740] [157/171] (755s left) vgem_basic (unload) [249.463127] Starting subtest: unload [278.540442] Subtest unload: SUCCESS (29.042s) [278.613489] [158/171] (726s left) i915_module_load (reload) [278.718520] Starting subtest: reload [282.095334] Subtest reload: SUCCESS (3.376s) [282.129062] [159/171] (723s left) gem_lmem_swapping (basic) [282.346515] Subtest basic: SKIP (0.000s) [282.386638] [160/171] (722s left) gem_lmem_swapping (parallel-random-engines) [282.499274] Subtest parallel-random-engines: SKIP (0.000s) [282.537072] [161/171] (722s left) gem_lmem_swapping (random-engines) [282.649678] Subtest random-engines: SKIP (0.000s) [282.683040] [162/171] (722s left) gem_lmem_swapping (verify-random) [282.790360] Subtest verify-random: SKIP (0.000s) [282.833845] [163/171] (722s left) i915_pm_rpm (module-reload) [285.510783] Starting subtest: module-reload [302.157423] Subtest module-reload: SUCCESS (16.635s) [302.198866] [164/171] (703s left) i915_selftest (live) [302.315987] Starting subtest: live [302.317144] Starting dynamic subtest: sanitycheck [304.045726] Dynamic subtest sanitycheck: SUCCESS (1.720s) [304.053240] Starting dynamic subtest: uncore [305.721848] Dynamic subtest uncore: SUCCESS (1.674s) [305.731087] Starting dynamic subtest: workarounds [307.716441] Dynamic subtest workarounds: SUCCESS (1.990s) [307.724493] Starting dynamic subtest: gt_engines [309.830036] Dynamic subtest gt_engines: SUCCESS (2.113s) [309.839299] Starting dynamic subtest: gt_timelines [320.640950] Dynamic subtest gt_timelines: SUCCESS (10.809s) [320.652403] Starting dynamic subtest: gt_contexts [322.436963] Dynamic subtest gt_contexts: SUCCESS (1.794s) [322.446347] Starting dynamic subtest: gt_lrc [326.636662] Dynamic subtest gt_lrc: SUCCESS (4.198s) [326.644228] Starting dynamic subtest: gt_mocs [328.446831] Dynamic subtest gt_mocs: SUCCESS (1.808s) [328.455424] Starting dynamic subtest: gt_pm [334.365836] Dynamic subtest gt_pm: SUCCESS (5.917s) [334.373819] Starting dynamic subtest: gt_heartbeat [336.199134] Dynamic subtest gt_heartbeat: SUCCESS (1.831s) [336.211414] Starting dynamic subtest: gt_tlb [338.699877] Dynamic subtest gt_tlb: SUCCESS (2.498s) [338.708456] Starting dynamic subtest: requests [346.263440] Dynamic subtest requests: SUCCESS (7.561s) [346.275814] Starting dynamic subtest: migrate [350.783935] Dynamic subtest migrate: SUCCESS (4.518s) [350.793664] Starting dynamic subtest: active [352.506745] Dynamic subtest active: SUCCESS (1.721s) [352.515066] Starting dynamic subtest: objects [355.323654] Dynamic subtest objects: SUCCESS (2.815s) [355.330935] Starting dynamic subtest: mman [360.409740] Dynamic subtest mman: SUCCESS (5.084s) [360.416997] Starting dynamic subtest: dmabuf [362.122745] Dynamic subtest dmabuf: SUCCESS (1.711s) [362.130403] Starting dynamic subtest: vma [363.833722] Dynamic subtest vma: SUCCESS (1.709s) [363.845216] Starting dynamic subtest: coherency [366.712673] Dynamic subtest coherency: SUCCESS (2.877s) [366.723189] Starting dynamic subtest: gtt [375.259774] Dynamic subtest gtt: SUCCESS (8.545s) [375.271351] Starting dynamic subtest: gem [377.210049] Dynamic subtest gem: SUCCESS (1.948s) [377.219888] Starting dynamic subtest: evict [380.460730] Dynamic subtest evict: SUCCESS (3.249s) [380.468477] Starting dynamic subtest: hugepages [387.461777] Dynamic subtest hugepages: SUCCESS (6.999s) [387.469539] Starting dynamic subtest: gem_contexts [402.079755] Dynamic subtest gem_contexts: SUCCESS (14.616s) [402.087527] Starting dynamic subtest: client [403.848976] Dynamic subtest client: SUCCESS (1.768s) [403.860234] Starting dynamic subtest: gem_migrate [405.505609] Dynamic subtest gem_migrate: SUCCESS (1.655s) [405.517246] Starting dynamic subtest: reset [407.867701] Dynamic subtest reset: SUCCESS (2.360s) [407.876245] Starting dynamic subtest: memory_region [409.534751] Dynamic subtest memory_region: SUCCESS (1.665s) [409.543286] Starting dynamic subtest: hangcheck [433.721804] Dynamic subtest hangcheck: SUCCESS (24.185s) [433.733359] Starting dynamic subtest: execlists [443.322842] Dynamic subtest execlists: SUCCESS (9.599s) [443.334894] Starting dynamic subtest: ring_submission [444.986835] Dynamic subtest ring_submission: SUCCESS (1.662s) [444.995342] Starting dynamic subtest: perf [446.871737] Dynamic subtest perf: SUCCESS (1.883s) [446.880227] Starting dynamic subtest: slpc [448.488818] Dynamic subtest slpc: SUCCESS (1.615s) [448.497348] Starting dynamic subtest: guc [450.168711] Dynamic subtest guc: SUCCESS (1.678s) [450.176633] Starting dynamic subtest: guc_multi_lrc [451.838793] Dynamic subtest guc_multi_lrc: SUCCESS (1.668s) [451.852360] Starting dynamic subtest: guc_hang [453.500709] Dynamic subtest guc_hang: SUCCESS (1.660s) [453.508452] Starting dynamic subtest: late_gt_pm [455.224250] Dynamic subtest late_gt_pm: SUCCESS (1.722s) [455.225642] Subtest live: SUCCESS (152.900s) [455.485248] [165/171] (549s left) dmabuf (all-tests) [455.599317] Starting subtest: all-tests [455.600521] Starting dynamic subtest: sanitycheck [455.661619] Dynamic subtest sanitycheck: SUCCESS (0.061s) [455.684214] Starting dynamic subtest: dma_fence [455.851585] Dynamic subtest dma_fence: SUCCESS (0.186s) [455.865296] Starting dynamic subtest: dma_fence_chain [471.669390] Dynamic subtest dma_fence_chain: SUCCESS (15.805s) [471.703480] Starting dynamic subtest: dma_fence_unwrap [471.746621] Dynamic subtest dma_fence_unwrap: SUCCESS (0.081s) [471.761765] Starting dynamic subtest: dma_resv [471.827827] Dynamic subtest dma_resv: SUCCESS (0.078s) [471.829536] Subtest all-tests: SUCCESS (16.230s) [471.871796] [166/171] (533s left) i915_suspend (basic-s2idle-without-i915) [471.977032] Starting subtest: basic-s2idle-without-i915 [491.355492] Subtest basic-s2idle-without-i915: SUCCESS (4.138s) [493.753768] [167/171] (528s left) i915_suspend (basic-s3-without-i915) [493.885774] Starting subtest: basic-s3-without-i915 [514.385156] Subtest basic-s3-without-i915: SUCCESS (4.392s) [517.786704] [168/171] (524s left) gem_exec_suspend (basic-s0) [517.941210] Starting subtest: basic-S0 [517.942306] Starting dynamic subtest: smem [536.449288] Dynamic subtest smem: SUCCESS (3.279s) [536.450288] Subtest basic-S0: SUCCESS (3.281s) [538.714235] [169/171] (520s left) gem_exec_suspend (basic-s3) [538.878561] Starting subtest: basic-S3 [538.879651] Starting dynamic subtest: smem [559.201925] Dynamic subtest smem: SUCCESS (4.072s) [559.202881] Subtest basic-S3: SUCCESS (4.074s) [562.389632] [170/171] (516s left) kms_chamelium_hpd (common-hpd-after-suspend) [562.868746] Subtest common-hpd-after-suspend: SKIP (0.000s) [563.766063] [171/171] (514s left) kms_pipe_crc_basic (suspend-read-crc) [563.915004] Starting subtest: suspend-read-crc [563.916063] Starting dynamic subtest: pipe-A-eDP-1 [583.307056] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.107s) [583.320306] Starting dynamic subtest: pipe-B-eDP-1 [603.299235] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.828s) [603.316170] Starting dynamic subtest: pipe-C-eDP-1 [623.536235] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.043s) [623.537086] Subtest suspend-read-crc: SUCCESS (10.988s) [627.421828] Closing watchdogs Done.