[57.494206] Initializing watchdogs [57.494252] /dev/watchdog0 [57.506858] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [57.511364] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [57.513443] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [57.516903] [001/132] (960s left) syncobj_timeline (wait-for-submit-snapshot) [57.589856] Starting subtest: wait-for-submit-snapshot [57.671588] Subtest wait-for-submit-snapshot: SUCCESS (0.082s) [57.771546] [002/132] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs) [57.865852] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs [57.868271] Starting dynamic subtest: pipe-A-eDP-1 [58.118501] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.221s) [58.127802] Starting dynamic subtest: pipe-B-eDP-1 [59.333390] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.215s) [59.344208] Starting dynamic subtest: pipe-C-eDP-1 [60.536659] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.204s) [60.545874] Starting dynamic subtest: pipe-D-eDP-1 [61.773853] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.242s) [61.776007] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.885s) [63.002951] [003/132] (955s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt) [63.115758] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt [67.811598] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.702s) [69.041201] [004/132] (949s left) kms_big_fb (linear-8bpp-rotate-270) [69.147946] Starting subtest: linear-8bpp-rotate-270 [69.168251] Subtest linear-8bpp-rotate-270: SKIP (0.020s) [69.324737] [005/132] (948s left) kms_busy (extended-modeset-hang-newfb) [69.427840] Starting subtest: extended-modeset-hang-newfb [69.430206] Starting dynamic subtest: pipe-A [103.773702] Dynamic subtest pipe-A: SUCCESS (34.345s) [103.780610] Starting dynamic subtest: pipe-D [126.807587] Dynamic subtest pipe-D: SUCCESS (23.032s) [126.809837] Subtest extended-modeset-hang-newfb: SUCCESS (57.382s) [127.629395] [006/132] (890s left) perf (oa-exponents) [127.777079] Starting subtest: oa-exponents [127.779219] Starting dynamic subtest: 0-rcs0 [129.551334] Dynamic subtest 0-rcs0: SUCCESS (1.775s) [129.562523] Starting dynamic subtest: 1-vcs1 [131.382774] Dynamic subtest 1-vcs1: SUCCESS (1.830s) [131.385273] Subtest oa-exponents: SUCCESS (3.606s) [131.424462] [007/132] (886s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [131.530071] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [132.597272] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.068s) [132.752281] [008/132] (885s left) kms_pipe_crc_basic (suspend-read-crc) [132.853692] Starting subtest: suspend-read-crc [132.855763] Starting dynamic subtest: pipe-A-eDP-1 [151.064832] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.225s) [151.072397] Starting dynamic subtest: pipe-B-eDP-1 [169.042814] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.214s) [169.055738] Starting dynamic subtest: pipe-C-eDP-1 [186.999089] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.112s) [187.010476] Starting dynamic subtest: pipe-D-eDP-1 [205.021999] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.097s) [205.024542] Subtest suspend-read-crc: SUCCESS (15.653s) [205.869203] [009/132] (812s left) perf_pmu (interrupts-sync) [205.920429] Starting subtest: interrupts-sync [207.055484] Subtest interrupts-sync: SUCCESS (1.141s) [207.204996] [FACT perf_pmu (interrupts-sync)] new: kernel.kmod_is_loaded.vgem: true [207.208731] [010/132] (811s left) gem_exec_reloc (basic-gtt-active) [207.275289] Subtest basic-gtt-active: SKIP (0.000s) [207.363374] [011/132] (811s left) kms_flip (modeset-vs-vblank-race) [207.467300] Starting subtest: modeset-vs-vblank-race [207.469860] Starting dynamic subtest: A-eDP1 [212.172057] Dynamic subtest A-eDP1: SUCCESS (4.720s) [212.183141] Starting dynamic subtest: B-eDP1 [216.884826] Dynamic subtest B-eDP1: SUCCESS (4.720s) [216.894994] Starting dynamic subtest: C-eDP1 [221.577078] Dynamic subtest C-eDP1: SUCCESS (4.697s) [221.587797] Starting dynamic subtest: D-eDP1 [226.141628] Dynamic subtest D-eDP1: SUCCESS (4.568s) [226.144347] Subtest modeset-vs-vblank-race: SUCCESS (18.712s) [227.340526] [012/132] (791s left) gem_sync (basic-each) [227.430950] Starting subtest: basic-each [229.827707] Subtest basic-each: SUCCESS (2.410s) [230.040247] [013/132] (788s left) gem_ctx_persistence (legacy-engines-mixed) [230.107757] Starting subtest: legacy-engines-mixed [230.110315] Starting dynamic subtest: render [230.234414] Dynamic subtest render: SUCCESS (0.126s) [230.246095] Starting dynamic subtest: bsd1 [230.318376] Dynamic subtest bsd1: SUCCESS (0.082s) [230.329856] Starting dynamic subtest: bsd2 [230.406361] Dynamic subtest bsd2: SUCCESS (0.086s) [230.417350] Starting dynamic subtest: blt [230.489335] Dynamic subtest blt: SUCCESS (0.081s) [230.500438] Starting dynamic subtest: vebox [230.573358] Dynamic subtest vebox: SUCCESS (0.082s) [230.576243] Subtest legacy-engines-mixed: SUCCESS (0.467s) [230.682356] [014/132] (787s left) kms_plane (plane-panning-bottom-right) [230.787585] Starting subtest: plane-panning-bottom-right [230.790081] Starting dynamic subtest: pipe-A [232.478980] Dynamic subtest pipe-A: SUCCESS (1.693s) [232.489960] Starting dynamic subtest: pipe-B [234.828921] Dynamic subtest pipe-B: SUCCESS (2.349s) [234.831935] Subtest plane-panning-bottom-right: SUCCESS (4.046s) [235.670059] [015/132] (782s left) prime_vgem (wait) [235.746294] Starting subtest: wait [235.748940] Starting dynamic subtest: rcs0 [235.796108] Dynamic subtest rcs0: SUCCESS (0.049s) [235.806116] Starting dynamic subtest: bcs0 [235.871346] Dynamic subtest bcs0: SUCCESS (0.074s) [235.880083] Starting dynamic subtest: vcs0 [235.919637] Dynamic subtest vcs0: SUCCESS (0.047s) [235.928636] Starting dynamic subtest: vcs1 [235.965138] Dynamic subtest vcs1: SUCCESS (0.045s) [235.973892] Starting dynamic subtest: vecs0 [236.012846] Dynamic subtest vecs0: SUCCESS (0.047s) [236.021539] Starting dynamic subtest: ccs0 [236.060078] Dynamic subtest ccs0: SUCCESS (0.046s) [236.062877] Subtest wait: SUCCESS (0.314s) [236.227253] [016/132] (782s left) i915_query (query-topology-matches-eu-total) [236.289325] Starting subtest: query-topology-matches-eu-total [236.291757] Subtest query-topology-matches-eu-total: SUCCESS (0.000s) [236.416456] [017/132] (782s left) syncobj_timeline (reset-signaled) [236.478679] Starting subtest: reset-signaled [236.481760] Subtest reset-signaled: SUCCESS (0.000s) [236.614201] [018/132] (782s left) syncobj_timeline (multi-wait-all-available-signaled) [236.679829] Starting subtest: multi-wait-all-available-signaled [236.682769] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s) [236.814912] [019/132] (781s left) kms_cursor_crc (cursor-random-256x256) [237.052903] Starting subtest: cursor-random-256x256 [237.057925] Starting dynamic subtest: pipe-A-eDP-1 [240.402873] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.344s) [240.430785] Starting dynamic subtest: pipe-D-eDP-1 [244.418659] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.992s) [244.421392] Subtest cursor-random-256x256: SUCCESS (7.363s) [245.264288] [020/132] (773s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs) [245.324712] Starting subtest: mixed-tiled-to-y-tiled-ccs [245.329793] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s) [245.532958] [021/132] (773s left) kms_rotation_crc (primary-y-tiled-reflect-x-180) [245.628901] Starting subtest: primary-y-tiled-reflect-x-180 [245.691241] Subtest primary-y-tiled-reflect-x-180: SKIP (0.060s) [245.816406] [022/132] (773s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs) [245.888765] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs [245.894381] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s) [246.098591] [023/132] (772s left) gem_exec_schedule (reorder-wide) [246.279223] Subtest reorder-wide: SKIP (0.000s) [246.487244] [024/132] (772s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary) [246.586939] Starting subtest: fbc-indfb-scaledprimary [249.558059] Subtest fbc-indfb-scaledprimary: SUCCESS (2.976s) [250.778546] [025/132] (768s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen) [250.871525] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen [257.383938] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.514s) [257.538301] [026/132] (761s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs) [257.634691] Starting subtest: crc-primary-suspend-yf-tiled-ccs [257.637223] Starting dynamic subtest: pipe-A-eDP-1 [257.647440] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [257.656853] Starting dynamic subtest: pipe-B-eDP-1 [257.666665] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [257.674904] Starting dynamic subtest: pipe-C-eDP-1 [257.685079] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [257.693029] Starting dynamic subtest: pipe-D-eDP-1 [257.703086] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [257.708073] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.004s) [259.852414] [027/132] (759s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf) [264.902955] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf [264.908712] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s) [265.779434] [028/132] (753s left) kms_cursor_crc (cursor-random-64x21) [265.982617] Starting subtest: cursor-random-64x21 [266.028482] Subtest cursor-random-64x21: SKIP (0.039s) [266.311659] [029/132] (752s left) prime_mmap (test_dup) [266.372800] Starting subtest: test_dup [266.375091] Starting dynamic subtest: test_dup-smem [266.399623] Dynamic subtest test_dup-smem: SUCCESS (0.029s) [266.402610] Subtest test_dup: SUCCESS (0.030s) [266.501705] [030/132] (752s left) gem_exec_schedule (u-submit-late-slice) [266.596525] Starting subtest: u-submit-late-slice [266.598801] Starting dynamic subtest: rcs0 [266.626474] Dynamic subtest rcs0: SUCCESS (0.039s) [266.636589] Starting dynamic subtest: bcs0 [266.655721] Dynamic subtest bcs0: SUCCESS (0.028s) [266.664294] Starting dynamic subtest: vcs0 [266.683071] Dynamic subtest vcs0: SUCCESS (0.027s) [266.691584] Starting dynamic subtest: vcs1 [266.712000] Dynamic subtest vcs1: SUCCESS (0.029s) [266.720598] Starting dynamic subtest: vecs0 [266.741073] Dynamic subtest vecs0: SUCCESS (0.028s) [266.749750] Starting dynamic subtest: ccs0 [266.772080] Dynamic subtest ccs0: SUCCESS (0.030s) [266.774595] Subtest u-submit-late-slice: SUCCESS (0.185s) [268.617124] [031/132] (750s left) syncobj_timeline (etime-multi-wait-all-submitted) [268.675895] Starting subtest: etime-multi-wait-all-submitted [268.780865] Subtest etime-multi-wait-all-submitted: SUCCESS (0.106s) [268.886554] [032/132] (750s left) fbdev (write) [268.932732] Starting subtest: write [269.296543] Subtest write: SUCCESS (0.362s) [269.386281] [033/132] (749s left) syncobj_wait (invalid-signal-zero-handles) [269.446430] Starting subtest: invalid-signal-zero-handles [269.448988] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [269.597549] [034/132] (749s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite) [269.703537] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite [269.708408] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [269.838320] [035/132] (749s left) gem_exec_balancer (sliced) [269.907933] Starting subtest: sliced [269.912723] Subtest sliced: SKIP (0.000s) [270.223154] [036/132] (749s left) gem_ccs (large-ctrl-surf-copy) [270.281907] Starting subtest: large-ctrl-surf-copy [270.287929] Subtest large-ctrl-surf-copy: SKIP (0.000s) [270.406594] [037/132] (749s left) gem_fenced_exec_thrash (2-spare-fences) [270.468945] Subtest 2-spare-fences: SKIP (0.000s) [270.554540] [038/132] (748s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [270.671506] Starting subtest: basic-busy-flip-before-cursor-varying-size [270.676920] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [270.839515] [039/132] (748s left) kms_big_fb (4-tiled-8bpp-rotate-0) [270.943225] Starting subtest: 4-tiled-8bpp-rotate-0 [272.069311] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.124s) [272.181998] [040/132] (747s left) gem_mmap_wc (copy) [272.239911] Subtest copy: SKIP (0.000s) [272.312917] [041/132] (747s left) kms_cursor_legacy (cursor-vs-flip-toggle) [272.429838] Starting subtest: cursor-vs-flip-toggle [303.166273] Subtest cursor-vs-flip-toggle: SUCCESS (30.740s) [304.007487] [042/132] (715s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [304.106692] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip [304.113000] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [304.242585] [043/132] (715s left) kms_addfb_basic (unused-handle) [304.298927] Starting subtest: unused-handle [304.301792] Subtest unused-handle: SUCCESS (0.000s) [304.453459] [044/132] (715s left) gem_ctx_shared (q-smoketest-all) [304.523581] Starting subtest: Q-smoketest-all [336.944674] Subtest Q-smoketest-all: SUCCESS (32.353s) [338.506571] [045/132] (681s left) kms_flip (2x-flip-vs-expired-vblank) [338.599783] Starting subtest: 2x-flip-vs-expired-vblank [338.607740] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s) [338.749567] [046/132] (681s left) kms_force_connector_basic (force-edid) [338.832763] Starting subtest: force-edid [339.198912] Subtest force-edid: SUCCESS (0.369s) [339.363563] [047/132] (680s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [339.463756] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [340.576362] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.112s) [340.743454] [048/132] (679s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5) [340.828752] Starting subtest: planes-unity-scaling-downscale-factor-0-5 [340.830774] Starting dynamic subtest: pipe-A [340.847946] Dynamic subtest pipe-A: SKIP (0.015s) [340.859137] Starting dynamic subtest: pipe-B [340.876711] Dynamic subtest pipe-B: SKIP (0.029s) [340.885605] Starting dynamic subtest: pipe-C [340.905885] Dynamic subtest pipe-C: SKIP (0.028s) [340.914949] Starting dynamic subtest: pipe-D [340.932878] Dynamic subtest pipe-D: SKIP (0.028s) [340.936111] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.102s) [341.060011] [049/132] (678s left) kms_chamelium_audio (hdmi-audio) [341.437973] Subtest hdmi-audio: SKIP (0.000s) [342.272529] [050/132] (677s left) gem_set_tiling_vs_gtt [342.376165] [051/132] (677s left) gem_lmem_swapping (massive) [342.420940] Subtest massive: SKIP (0.000s) [342.469884] [052/132] (677s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render) [342.567416] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render [349.223840] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.660s) [349.394989] [053/132] (670s left) prime_busy (after) [349.461464] Starting subtest: after [349.468428] Starting dynamic subtest: rcs0 [349.513358] Dynamic subtest rcs0: SUCCESS (0.049s) [349.524839] Starting dynamic subtest: bcs0 [349.578985] Dynamic subtest bcs0: SUCCESS (0.065s) [349.587616] Starting dynamic subtest: vcs0 [349.618692] Dynamic subtest vcs0: SUCCESS (0.039s) [349.627649] Starting dynamic subtest: vcs1 [349.658220] Dynamic subtest vcs1: SUCCESS (0.039s) [349.667096] Starting dynamic subtest: vecs0 [349.697777] Dynamic subtest vecs0: SUCCESS (0.039s) [349.706667] Starting dynamic subtest: ccs0 [349.737438] Dynamic subtest ccs0: SUCCESS (0.039s) [349.848679] Subtest after: SUCCESS (0.383s) [349.944884] [054/132] (670s left) syncobj_wait (invalid-signal-illegal-handle) [350.009003] Starting subtest: invalid-signal-illegal-handle [350.012089] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [350.156961] [055/132] (670s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [350.256558] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [350.259018] Starting dynamic subtest: pipe-A-default-mode [350.557849] Dynamic subtest pipe-A-default-mode: SKIP (0.299s) [350.562517] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.301s) [351.425526] [056/132] (668s left) gem_gtt_cpu_tlb [351.562641] [057/132] (668s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [351.671842] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt [351.676061] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [351.790081] [058/132] (668s left) gen9_exec_parse (batch-zero-length) [351.848839] Subtest batch-zero-length: SKIP (0.000s) [351.928999] [059/132] (668s left) gem_softpin (evict-snoop-interruptible) [351.997950] Starting subtest: evict-snoop-interruptible [352.003675] Subtest evict-snoop-interruptible: SKIP (0.000s) [352.162174] [060/132] (668s left) dmabuf_sync_file (export-basic) [352.225880] Starting subtest: export-basic [352.228645] Subtest export-basic: SUCCESS (0.002s) [352.310072] [061/132] (668s left) kms_dither (fb-8bpc-vs-panel-6bpc) [352.401504] Starting subtest: fb-8bpc-vs-panel-6bpc [352.403412] Starting dynamic subtest: pipe-A-eDP-1 [353.765864] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.364s) [353.768658] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.366s) [354.619058] [062/132] (665s left) syncobj_timeline (host-signal-ordered) [354.674206] Starting subtest: host-signal-ordered [354.676777] Subtest host-signal-ordered: SUCCESS (0.000s) [354.801384] [063/132] (665s left) kms_chamelium_hpd (common-hpd-after-suspend) [355.186620] Subtest common-hpd-after-suspend: SKIP (0.000s) [355.980059] [064/132] (664s left) kms_flip (2x-flip-vs-fences) [356.088547] Starting subtest: 2x-flip-vs-fences [356.093250] Subtest 2x-flip-vs-fences: SKIP (0.000s) [356.238859] [065/132] (664s left) perf (mi-rpc) [356.345729] Starting subtest: mi-rpc [356.350785] Subtest mi-rpc: SKIP (0.000s) [356.390392] [066/132] (664s left) sw_sync (sync_random_merge) [356.424093] Starting subtest: sync_random_merge [356.461629] Subtest sync_random_merge: SUCCESS (0.038s) [356.548272] [067/132] (664s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite) [356.654113] Starting subtest: psr-rgb101010-draw-pwrite [364.140492] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.490s) [364.280411] [068/132] (656s left) kms_getfb (getfb2-handle-closed) [364.385886] Starting subtest: getfb2-handle-closed [364.388641] Subtest getfb2-handle-closed: SUCCESS (0.000s) [364.524448] [069/132] (656s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt) [364.626873] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt [364.631935] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [364.761907] [070/132] (656s left) kms_addfb_basic (invalid-smem-bo-on-discrete) [364.814899] Starting subtest: invalid-smem-bo-on-discrete [364.819937] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s) [364.990441] [071/132] (655s left) perf (gen12-invalid-class-instance) [365.096462] Starting subtest: gen12-invalid-class-instance [365.106898] Subtest gen12-invalid-class-instance: SUCCESS (0.010s) [365.151638] [072/132] (655s left) gem_exec_schedule (implicit-read-write) [365.227522] Starting subtest: implicit-read-write [365.229777] Starting dynamic subtest: rcs0 [365.333102] Dynamic subtest rcs0: SUCCESS (0.117s) [365.344593] Starting dynamic subtest: vcs0 [365.412244] Dynamic subtest vcs0: SUCCESS (0.078s) [365.423376] Starting dynamic subtest: vcs1 [365.486377] Dynamic subtest vcs1: SUCCESS (0.073s) [365.497323] Starting dynamic subtest: vecs0 [365.559990] Dynamic subtest vecs0: SUCCESS (0.073s) [365.570375] Starting dynamic subtest: ccs0 [365.637831] Dynamic subtest ccs0: SUCCESS (0.077s) [365.640376] Subtest implicit-read-write: SUCCESS (0.422s) [366.613135] [073/132] (654s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [366.714601] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s) [366.846310] [074/132] (654s left) kms_atomic (plane-cursor-legacy) [366.954175] Starting subtest: plane-cursor-legacy [366.956867] Starting dynamic subtest: pipe-A-eDP-1 [367.324804] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.368s) [367.327674] Subtest plane-cursor-legacy: SUCCESS (0.370s) [368.213449] [075/132] (652s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc) [368.317100] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc [368.320079] Starting dynamic subtest: pipe-A-eDP-1 [368.347296] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [368.357710] Starting dynamic subtest: pipe-B-eDP-1 [368.383712] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [368.392644] Starting dynamic subtest: pipe-C-eDP-1 [368.418333] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [368.427998] Starting dynamic subtest: pipe-D-eDP-1 [368.455947] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [368.461662] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s) [370.611198] [076/132] (650s left) gem_exec_balancer (bonded-pair) [370.675015] Starting subtest: bonded-pair [370.680390] Subtest bonded-pair: SKIP (0.000s) [371.009980] [077/132] (650s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu) [371.111126] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu [371.116890] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [371.245302] [078/132] (649s left) gem_ctx_exec (basic-invalid-context) [371.312141] Starting subtest: basic-invalid-context [371.317760] Subtest basic-invalid-context: SUCCESS (0.006s) [371.490289] [079/132] (649s left) kms_content_protection (lic-type-0-hdcp14) [371.634917] Starting subtest: lic-type-0-hdcp14 [371.640314] Subtest lic-type-0-hdcp14: SKIP (0.000s) [371.798124] [080/132] (649s left) gem_eio (wait-immediate) [372.028047] Starting subtest: wait-immediate [372.396591] Subtest wait-immediate: SUCCESS (0.371s) [372.569418] [081/132] (648s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible) [372.679852] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible [372.690890] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.010s) [372.830450] [082/132] (648s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling) [372.930332] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling [372.932237] Starting dynamic subtest: pipe-A-default-mode [374.122917] Dynamic subtest pipe-A-default-mode: SKIP (1.189s) [374.128405] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.191s) [374.307201] [083/132] (647s left) kms_psr (psr2-basic) [374.411816] Starting subtest: psr2-basic [374.414514] Starting dynamic subtest: eDP-1 [376.019257] Dynamic subtest eDP-1: SUCCESS (1.608s) [376.022082] Subtest psr2-basic: SUCCESS (1.609s) [376.906126] [084/132] (644s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs) [377.002718] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs [377.005110] Starting dynamic subtest: pipe-A-eDP-1 [377.028857] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [377.037979] Starting dynamic subtest: pipe-B-eDP-1 [377.059537] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [377.067824] Starting dynamic subtest: pipe-C-eDP-1 [377.090048] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [377.098262] Starting dynamic subtest: pipe-D-eDP-1 [377.120626] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [377.125848] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s) [379.306934] [085/132] (642s left) kms_vblank (query-forked-busy) [379.409369] Starting subtest: query-forked-busy [379.412026] Starting dynamic subtest: pipe-A-eDP-1 [381.298958] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.891s) [381.315638] Starting dynamic subtest: pipe-D-eDP-1 [383.834002] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.533s) [383.836835] Subtest query-forked-busy: SUCCESS (4.427s) [384.704303] [086/132] (636s left) kms_pm_dc (dc9-dpms) [385.812862] Starting subtest: dc9-dpms [387.228764] Subtest dc9-dpms: SUCCESS (1.417s) [387.318585] [087/132] (634s left) gem_userptr_blits (set-cache-level) [387.383079] Starting subtest: set-cache-level [387.388097] Subtest set-cache-level: SKIP (0.000s) [387.553551] [088/132] (634s left) gem_exec_fence (keep-in-fence) [387.738109] Starting subtest: keep-in-fence [387.741087] Starting dynamic subtest: rcs0 [387.794112] Dynamic subtest rcs0: SUCCESS (0.056s) [387.806163] Starting dynamic subtest: bcs0 [387.848063] Dynamic subtest bcs0: SUCCESS (0.052s) [387.859620] Starting dynamic subtest: vcs0 [389.450456] Dynamic subtest vcs0: SUCCESS (1.599s) [389.463105] Starting dynamic subtest: vcs1 [391.497624] Dynamic subtest vcs1: SUCCESS (2.045s) [391.509370] Starting dynamic subtest: vecs0 [393.481428] Dynamic subtest vecs0: SUCCESS (1.982s) [393.493739] Starting dynamic subtest: ccs0 [393.717160] Dynamic subtest ccs0: SUCCESS (0.234s) [393.720105] Subtest keep-in-fence: SUCCESS (5.982s) [395.589773] [089/132] (626s left) kms_pm_rpm (modeset-pc8-residency-stress) [397.618799] Starting subtest: modeset-pc8-residency-stress [397.624632] Subtest modeset-pc8-residency-stress: SKIP (0.000s) [398.015471] [090/132] (623s left) kms_psr (fbc-psr2-sprite-plane-move) [398.107876] Starting subtest: fbc-psr2-sprite-plane-move [398.110984] Starting dynamic subtest: eDP-1 [398.116113] Dynamic subtest eDP-1: SKIP (0.000s) [398.121232] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s) [398.204362] [091/132] (623s left) gem_exec_create (forked) [398.261755] Starting subtest: forked [398.264076] Starting dynamic subtest: smem [419.401392] Dynamic subtest smem: SUCCESS (21.142s) [419.404415] Subtest forked: SUCCESS (21.145s) [419.609631] [092/132] (602s left) kms_psr (pr-cursor-mmap-cpu) [419.719125] Starting subtest: pr-cursor-mmap-cpu [419.724356] Subtest pr-cursor-mmap-cpu: SKIP (0.000s) [419.813919] [093/132] (602s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [419.912193] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [419.916979] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [420.038120] [094/132] (601s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb) [425.048228] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb [425.053596] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s) [425.890944] [095/132] (596s left) gem_exec_reloc (basic-wc-read) [425.953135] Subtest basic-wc-read: SKIP (0.000s) [426.010582] [096/132] (595s left) kms_psr (psr-cursor-plane-move) [426.108423] Starting subtest: psr-cursor-plane-move [426.110739] Starting dynamic subtest: eDP-1 [427.833081] Dynamic subtest eDP-1: SUCCESS (1.723s) [427.835491] Subtest psr-cursor-plane-move: SUCCESS (1.724s) [428.684025] [097/132] (593s left) kms_flip (basic-plain-flip) [428.772483] Starting subtest: basic-plain-flip [428.774804] Starting dynamic subtest: A-eDP1 [430.435856] Dynamic subtest A-eDP1: SUCCESS (1.661s) [430.446017] Starting dynamic subtest: B-eDP1 [432.143088] Dynamic subtest B-eDP1: SUCCESS (1.705s) [432.157876] Starting dynamic subtest: C-eDP1 [433.833009] Dynamic subtest C-eDP1: SUCCESS (1.686s) [433.843883] Starting dynamic subtest: D-eDP1 [435.536885] Dynamic subtest D-eDP1: SUCCESS (1.701s) [435.539937] Subtest basic-plain-flip: SUCCESS (6.765s) [436.700062] [098/132] (585s left) kms_flip (plain-flip-fb-recreate) [436.809726] Starting subtest: plain-flip-fb-recreate [436.812133] Starting dynamic subtest: A-eDP1 [445.628133] Dynamic subtest A-eDP1: SUCCESS (8.817s) [445.637566] Starting dynamic subtest: B-eDP1 [454.534096] Dynamic subtest B-eDP1: SUCCESS (8.902s) [454.544739] Starting dynamic subtest: C-eDP1 [463.428850] Dynamic subtest C-eDP1: SUCCESS (8.890s) [463.439146] Starting dynamic subtest: D-eDP1 [472.332215] Dynamic subtest D-eDP1: SUCCESS (8.901s) [472.336948] Subtest plain-flip-fb-recreate: SUCCESS (35.521s) [473.601277] [099/132] (548s left) i915_suspend (debugfs-reader) [473.665593] Starting subtest: debugfs-reader [492.130186] Subtest debugfs-reader: SUCCESS (3.920s) [492.297148] [100/132] (529s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu) [492.411806] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu [492.418177] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [492.545163] [101/132] (529s left) gem_exec_nop (basic-sequential) [492.614912] Starting subtest: basic-sequential [507.482656] Subtest basic-sequential: SUCCESS (14.915s) [507.733473] [102/132] (514s left) gem_pxp (create-regular-buffer) [507.856183] Starting subtest: create-regular-buffer [507.858939] Subtest create-regular-buffer: SUCCESS (0.000s) [507.995149] [103/132] (514s left) syncobj_basic (test-valid-cycle) [508.055391] Starting subtest: test-valid-cycle [508.057718] Subtest test-valid-cycle: SUCCESS (0.000s) [508.192318] [104/132] (514s left) gem_exec_suspend (basic-s3-devices) [508.245521] Starting subtest: basic-S3-devices [508.247841] Starting dynamic subtest: smem [519.967671] Dynamic subtest smem: SUCCESS (11.727s) [519.970766] Subtest basic-S3-devices: SUCCESS (11.729s) [520.179977] [105/132] (502s left) template (a) [520.249867] Starting subtest: A [520.252324] Subtest A: SUCCESS (0.000s) [520.364194] [106/132] (502s left) gem_mmap_gtt (bad-object) [520.422629] Subtest bad-object: SKIP (0.000s) [520.492942] [107/132] (502s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt) [520.595112] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt [520.599122] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [520.710560] [108/132] (501s left) api_intel_bb (intel-bb-blit-y) [520.777682] Starting subtest: intel-bb-blit-y [524.624202] Subtest intel-bb-blit-y: SUCCESS (3.845s) [524.776725] [109/132] (497s left) kms_selftest (drm_format_helper) [524.814487] Starting subtest: drm_format_helper [524.859004] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8 [525.008869] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.149s) [525.019769] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332 [525.142273] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.132s) [525.152858] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565 [525.265419] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.122s) [525.276295] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555 [525.361491] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.097s) [525.372329] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555 [525.465200] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.101s) [525.476136] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551 [525.614269] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.147s) [525.625486] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888 [525.746428] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.131s) [525.757286] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888 [525.873104] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.126s) [525.881748] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888 [526.022525] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.148s) [526.038305] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010 [526.170083] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.148s) [526.180846] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010 [526.315545] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.142s) [526.325704] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono [526.471098] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.155s) [526.479961] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab [526.611775] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.140s) [526.622666] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888 [526.760248] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.149s) [526.770703] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888 [526.872958] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.110s) [526.882056] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset [527.028520] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.155s) [527.037269] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy [527.167278] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.137s) [527.169444] Subtest drm_format_helper: SUCCESS (2.352s) [527.352833] [110/132] (495s left) gem_mmap_offset (oob-read) [527.406598] Starting subtest: oob-read [527.408794] Subtest oob-read: SUCCESS (0.000s) [527.530808] [111/132] (495s left) gem_spin_batch (resubmit-all) [527.595439] Starting subtest: resubmit-all [527.597822] Starting dynamic subtest: rcs0 [527.617769] Dynamic subtest rcs0: SUCCESS (0.022s) [527.630207] Starting dynamic subtest: bcs0 [527.632500] Dynamic subtest bcs0: SUCCESS (0.003s) [527.641273] Starting dynamic subtest: vcs0 [527.643130] Dynamic subtest vcs0: SUCCESS (0.002s) [527.651521] Starting dynamic subtest: vcs1 [527.653371] Dynamic subtest vcs1: SUCCESS (0.002s) [527.661859] Starting dynamic subtest: vecs0 [527.663765] Dynamic subtest vecs0: SUCCESS (0.003s) [527.672332] Starting dynamic subtest: ccs0 [527.674252] Dynamic subtest ccs0: SUCCESS (0.003s) [527.676157] Subtest resubmit-all: SUCCESS (0.038s) [529.054509] [112/132] (493s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [529.184701] Starting subtest: cursorB-vs-flipA-toggle [529.189113] Subtest cursorB-vs-flipA-toggle: SKIP (0.002s) [529.352388] [113/132] (493s left) gen9_exec_parse (valid-registers) [529.424280] Subtest valid-registers: SKIP (0.000s) [529.503623] [114/132] (493s left) kms_pipe_crc_basic (disable-crc-after-crtc) [529.593545] Starting subtest: disable-crc-after-crtc [529.595874] Starting dynamic subtest: pipe-A-eDP-1 [531.210900] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.616s) [531.220938] Starting dynamic subtest: pipe-B-eDP-1 [533.598184] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.387s) [533.608389] Starting dynamic subtest: pipe-C-eDP-1 [535.913565] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.313s) [535.923624] Starting dynamic subtest: pipe-D-eDP-1 [538.287265] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.374s) [538.289783] Subtest disable-crc-after-crtc: SUCCESS (8.697s) [539.118928] [115/132] (483s left) gem_exec_fence (syncobj-unused-fence) [539.534305] Starting subtest: syncobj-unused-fence [539.540358] Subtest syncobj-unused-fence: SUCCESS (0.005s) [541.110523] [116/132] (481s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move) [541.222767] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move [541.226836] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [541.368173] [117/132] (481s left) gem_set_tiling_vs_pwrite [541.512478] [118/132] (481s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [541.611961] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [541.616399] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [541.760295] [119/132] (481s left) vgem_basic (busy-fence) [541.806805] Starting subtest: busy-fence [541.809019] Subtest busy-fence: SUCCESS (0.000s) [541.889275] [120/132] (481s left) i915_pm_rpm (debugfs-read) [544.231309] Starting subtest: debugfs-read [545.381255] Subtest debugfs-read: SUCCESS (1.154s) [545.770247] [121/132] (477s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt) [545.868723] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt [550.491470] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.627s) [551.718070] [122/132] (471s left) perf_pmu (busy-start) [551.767919] Starting subtest: busy-start [551.769804] Starting dynamic subtest: rcs0 [554.318024] Dynamic subtest rcs0: SUCCESS (2.551s) [554.329986] Starting dynamic subtest: bcs0 [556.870608] Dynamic subtest bcs0: SUCCESS (2.550s) [556.882686] Starting dynamic subtest: vcs0 [560.030427] Dynamic subtest vcs0: SUCCESS (3.156s) [560.042470] Starting dynamic subtest: vcs1 [564.011601] Dynamic subtest vcs1: SUCCESS (3.965s) [564.022945] Starting dynamic subtest: vecs0 [568.030330] Dynamic subtest vecs0: SUCCESS (4.030s) [568.042370] Starting dynamic subtest: ccs0 [570.577508] Dynamic subtest ccs0: SUCCESS (2.546s) [570.580008] Subtest busy-start: SUCCESS (18.813s) [570.729417] [123/132] (452s left) kms_addfb_basic (size-max) [570.831234] Starting subtest: size-max [570.833459] Subtest size-max: SUCCESS (0.000s) [570.947325] [124/132] (452s left) kms_psr (fbc-pr-primary-blt) [571.043086] Starting subtest: fbc-pr-primary-blt [571.047456] Subtest fbc-pr-primary-blt: SKIP (0.000s) [571.140649] [125/132] (452s left) kms_color_pipeline (plane-ctm3x4) [571.234848] Starting subtest: plane-ctm3x4 [571.249463] Starting dynamic subtest: pipe-A-eDP-1 [571.854889] Dynamic subtest pipe-A-eDP-1: FAIL (0.283s) [572.626617] Starting dynamic subtest: pipe-B-eDP-1 [573.393497] Dynamic subtest pipe-B-eDP-1: FAIL (0.268s) [573.924082] Starting dynamic subtest: pipe-C-eDP-1 [574.715384] Dynamic subtest pipe-C-eDP-1: FAIL (0.224s) [575.230940] Starting dynamic subtest: pipe-D-eDP-1 [576.065192] Dynamic subtest pipe-D-eDP-1: FAIL (0.257s) [576.067638] Subtest plane-ctm3x4: FAIL (4.547s) [576.609589] [126/132] (446s left) kms_chamelium_hpd (vga-hpd-without-ddc) [576.993105] Subtest vga-hpd-without-ddc: SKIP (0.000s) [577.765591] [127/132] (445s left) api_intel_allocator (standalone) [577.824812] Starting subtest: standalone [577.839879] Subtest standalone: SUCCESS (0.018s) [577.983771] [128/132] (445s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [578.078313] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [584.446523] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.371s) [584.584634] [129/132] (438s left) kms_big_fb (y-tiled-addfb) [584.698716] Starting subtest: y-tiled-addfb [584.703170] Subtest y-tiled-addfb: SKIP (0.000s) [584.827222] [130/132] (438s left) kms_prime (basic-crc-vgem) [584.922325] Starting subtest: basic-crc-vgem [584.924332] Starting dynamic subtest: second-to-first [585.867395] Dynamic subtest second-to-first: SUCCESS (0.948s) [585.869315] Subtest basic-crc-vgem: SUCCESS (0.949s) [585.985801] [131/132] (437s left) i915_hangman (engine-error-state-capture) [586.102814] Starting subtest: engine-error-state-capture [586.104581] Starting dynamic subtest: rcs0 [590.012827] Dynamic subtest rcs0: SUCCESS (3.909s) [590.028962] Starting dynamic subtest: bcs0 [595.965227] Dynamic subtest bcs0: SUCCESS (5.952s) [595.977711] Starting dynamic subtest: vcs0 [601.981661] Dynamic subtest vcs0: SUCCESS (6.015s) [601.994250] Starting dynamic subtest: vcs1 [607.933420] Dynamic subtest vcs1: SUCCESS (5.951s) [607.946127] Starting dynamic subtest: vecs0 [612.925494] Dynamic subtest vecs0: SUCCESS (4.991s) [612.940276] Starting dynamic subtest: ccs0 [617.981813] Dynamic subtest ccs0: SUCCESS (5.055s) [617.985966] Subtest engine-error-state-capture: SUCCESS (31.889s) [618.084410] [132/132] (405s left) kms_invalid_mode (bad-vsync-end) [618.191020] Starting subtest: bad-vsync-end [618.193533] Starting dynamic subtest: pipe-A-eDP-1 [618.472108] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s) [618.482207] Starting dynamic subtest: pipe-B-eDP-1 [618.484107] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [618.493020] Starting dynamic subtest: pipe-C-eDP-1 [618.495424] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [618.504480] Starting dynamic subtest: pipe-D-eDP-1 [618.506335] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [618.508193] Subtest bad-vsync-end: SUCCESS (0.289s) [619.286322] Closing watchdogs results: parsing output: 0/ for test: syncobj_timeline results: parsing output: 1/ for test: kms_ccs results: parsing output: 2/ for test: kms_frontbuffer_tracking results: parsing output: 3/ for test: kms_big_fb results: parsing output: 4/ for test: kms_busy results: parsing output: 5/ for test: perf results: parsing output: 6/ for test: kms_big_fb results: parsing output: 7/ for test: kms_pipe_crc_basic results: parsing output: 8/ for test: perf_pmu results: parsing output: 9/ for test: gem_exec_reloc results: parsing output: 10/ for test: kms_flip results: parsing output: 11/ for test: gem_sync results: parsing output: 12/ for test: gem_ctx_persistence results: parsing output: 13/ for test: kms_plane results: parsing output: 14/ for test: prime_vgem results: parsing output: 15/ for test: i915_query results: parsing output: 16/ for test: syncobj_timeline results: parsing output: 17/ for test: syncobj_timeline results: parsing output: 18/ for test: kms_cursor_crc results: parsing output: 19/ for test: gem_render_copy results: parsing output: 20/ for test: kms_rotation_crc results: parsing output: 21/ for test: gem_render_copy results: parsing output: 22/ for test: gem_exec_schedule results: parsing output: 23/ for test: kms_frontbuffer_tracking results: parsing output: 24/ for test: kms_frontbuffer_tracking results: parsing output: 25/ for test: kms_ccs results: parsing output: 26/ for test: kms_psr2_sf results: parsing output: 27/ for test: kms_cursor_crc results: parsing output: 28/ for test: prime_mmap results: parsing output: 29/ for test: gem_exec_schedule results: parsing output: 30/ for test: syncobj_timeline results: parsing output: 31/ for test: fbdev results: parsing output: 32/ for test: syncobj_wait results: parsing output: 33/ for test: kms_frontbuffer_tracking results: parsing output: 34/ for test: gem_exec_balancer results: parsing output: 35/ for test: gem_ccs results: parsing output: 36/ for test: gem_fenced_exec_thrash results: parsing output: 37/ for test: kms_cursor_legacy results: parsing output: 38/ for test: kms_big_fb results: parsing output: 39/ for test: gem_mmap_wc results: parsing output: 40/ for test: kms_cursor_legacy results: parsing output: 41/ for test: kms_big_fb results: parsing output: 42/ for test: kms_addfb_basic results: parsing output: 43/ for test: gem_ctx_shared results: parsing output: 44/ for test: kms_flip results: parsing output: 45/ for test: kms_force_connector_basic results: parsing output: 46/ for test: kms_big_fb results: parsing output: 47/ for test: kms_plane_scaling results: parsing output: 48/ for test: kms_chamelium_audio results: parsing output: 49/ for test: gem_set_tiling_vs_gtt results: parsing output: 50/ for test: gem_lmem_swapping results: parsing output: 51/ for test: kms_frontbuffer_tracking results: parsing output: 52/ for test: prime_busy results: parsing output: 53/ for test: syncobj_wait results: parsing output: 54/ for test: kms_flip_scaled_crc results: parsing output: 55/ for test: gem_gtt_cpu_tlb results: parsing output: 56/ for test: kms_frontbuffer_tracking results: parsing output: 57/ for test: gen9_exec_parse results: parsing output: 58/ for test: gem_softpin results: parsing output: 59/ for test: dmabuf_sync_file results: parsing output: 60/ for test: kms_dither results: parsing output: 61/ for test: syncobj_timeline results: parsing output: 62/ for test: kms_chamelium_hpd results: parsing output: 63/ for test: kms_flip results: parsing output: 64/ for test: perf results: parsing output: 65/ for test: sw_sync results: parsing output: 66/ for test: kms_frontbuffer_tracking results: parsing output: 67/ for test: kms_getfb results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: kms_addfb_basic results: parsing output: 70/ for test: perf results: parsing output: 71/ for test: gem_exec_schedule results: parsing output: 72/ for test: kms_addfb_basic results: parsing output: 73/ for test: kms_atomic results: parsing output: 74/ for test: kms_ccs results: parsing output: 75/ for test: gem_exec_balancer results: parsing output: 76/ for test: kms_frontbuffer_tracking results: parsing output: 77/ for test: gem_ctx_exec results: parsing output: 78/ for test: kms_content_protection results: parsing output: 79/ for test: gem_eio results: parsing output: 80/ for test: kms_flip results: parsing output: 81/ for test: kms_flip_scaled_crc results: parsing output: 82/ for test: kms_psr results: parsing output: 83/ for test: kms_ccs results: parsing output: 84/ for test: kms_vblank results: parsing output: 85/ for test: kms_pm_dc results: parsing output: 86/ for test: gem_userptr_blits results: parsing output: 87/ for test: gem_exec_fence results: parsing output: 88/ for test: kms_pm_rpm results: parsing output: 89/ for test: kms_psr results: parsing output: 90/ for test: gem_exec_create results: parsing output: 91/ for test: kms_psr results: parsing output: 92/ for test: kms_frontbuffer_tracking results: parsing output: 93/ for test: kms_psr2_sf results: parsing output: 94/ for test: gem_exec_reloc results: parsing output: 95/ for test: kms_psr results: parsing output: 96/ for test: kms_flip results: parsing output: 97/ for test: kms_flip results: parsing output: 98/ for test: i915_suspend results: parsing output: 99/ for test: kms_frontbuffer_tracking results: parsing output: 100/ for test: gem_exec_nop results: parsing output: 101/ for test: gem_pxp results: parsing output: 102/ for test: syncobj_basic results: parsing output: 103/ for test: gem_exec_suspend results: parsing output: 104/ for test: template results: parsing output: 105/ for test: gem_mmap_gtt results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: api_intel_bb results: parsing output: 108/ for test: kms_selftest results: parsing output: 109/ for test: gem_mmap_offset results: parsing output: 110/ for test: gem_spin_batch results: parsing output: 111/ for test: kms_cursor_legacy results: parsing output: 112/ for test: gen9_exec_parse results: parsing output: 113/ for test: kms_pipe_crc_basic results: parsing output: 114/ for test: gem_exec_fence results: parsing output: 115/ for test: kms_frontbuffer_tracking results: parsing output: 116/ for test: gem_set_tiling_vs_pwrite results: parsing output: 117/ for test: kms_frontbuffer_tracking results: parsing output: 118/ for test: vgem_basic results: parsing output: 119/ for test: i915_pm_rpm results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: perf_pmu results: parsing output: 122/ for test: kms_addfb_basic results: parsing output: 123/ for test: kms_psr results: parsing output: 124/ for test: kms_color_pipeline results: parsing output: 125/ for test: kms_chamelium_hpd results: parsing output: 126/ for test: api_intel_allocator results: parsing output: 127/ for test: kms_frontbuffer_tracking results: parsing output: 128/ for test: kms_big_fb results: parsing output: 129/ for test: kms_prime results: parsing output: 130/ for test: i915_hangman results: parsing output: 131/ for test: kms_invalid_mode Done.