[57.152959] Initializing watchdogs [57.153019] /dev/watchdog0 [57.167380] [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.172096] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [57.174861] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [57.179166] [001/132] (960s left) syncobj_timeline (wait-for-submit-snapshot) [57.268092] Starting subtest: wait-for-submit-snapshot [57.350767] Subtest wait-for-submit-snapshot: SUCCESS (0.081s) [57.450089] [002/132] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs) [57.552816] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs [57.555244] Starting dynamic subtest: pipe-A-eDP-1 [57.820469] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.234s) [57.830058] Starting dynamic subtest: pipe-B-eDP-1 [59.045712] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.214s) [59.054713] Starting dynamic subtest: pipe-C-eDP-1 [60.267827] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.216s) [60.275739] Starting dynamic subtest: pipe-D-eDP-1 [61.524443] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.250s) [61.526749] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.916s) [62.741301] [003/132] (955s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt) [62.847711] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt [67.155556] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.298s) [68.357199] [004/132] (949s left) kms_big_fb (linear-8bpp-rotate-270) [68.466991] Starting subtest: linear-8bpp-rotate-270 [68.487695] Subtest linear-8bpp-rotate-270: SKIP (0.020s) [68.635130] [005/132] (949s left) kms_busy (extended-modeset-hang-newfb) [68.736773] Starting subtest: extended-modeset-hang-newfb [68.739045] Starting dynamic subtest: pipe-A [102.841655] Dynamic subtest pipe-A: SUCCESS (34.083s) [102.850805] Starting dynamic subtest: pipe-D [125.802878] Dynamic subtest pipe-D: SUCCESS (22.954s) [125.805269] Subtest extended-modeset-hang-newfb: SUCCESS (57.041s) [126.625890] [006/132] (891s left) perf (oa-exponents) [126.760622] Starting subtest: oa-exponents [126.762573] Starting dynamic subtest: 0-rcs0 [128.540875] Dynamic subtest 0-rcs0: SUCCESS (1.779s) [128.547818] Starting dynamic subtest: 1-vcs0 [130.372291] Dynamic subtest 1-vcs0: SUCCESS (1.830s) [130.375063] Subtest oa-exponents: SUCCESS (3.610s) [130.416887] [007/132] (887s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [130.523852] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [131.657404] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.134s) [131.812585] [008/132] (886s left) kms_pipe_crc_basic (suspend-read-crc) [131.904394] Starting subtest: suspend-read-crc [131.906161] Starting dynamic subtest: pipe-A-eDP-1 [148.901883] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.265s) [148.911859] Starting dynamic subtest: pipe-B-eDP-1 [166.912783] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.124s) [166.923333] Starting dynamic subtest: pipe-C-eDP-1 [184.907573] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.019s) [184.916218] Starting dynamic subtest: pipe-D-eDP-1 [202.894004] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.030s) [202.896479] Subtest suspend-read-crc: SUCCESS (15.442s) [203.808593] [009/132] (814s left) perf_pmu (interrupts-sync) [203.863288] Starting subtest: interrupts-sync [205.011913] Subtest interrupts-sync: SUCCESS (1.119s) [205.157928] [FACT perf_pmu (interrupts-sync)] new: kernel.kmod_is_loaded.vgem: true [205.161540] [010/132] (812s left) gem_exec_reloc (basic-gtt-active) [205.219856] Subtest basic-gtt-active: SKIP (0.000s) [205.306278] [011/132] (812s left) kms_flip (modeset-vs-vblank-race) [205.417236] Starting subtest: modeset-vs-vblank-race [205.419694] Starting dynamic subtest: A-eDP1 [210.189752] Dynamic subtest A-eDP1: SUCCESS (4.695s) [210.200881] Starting dynamic subtest: B-eDP1 [214.956499] Dynamic subtest B-eDP1: SUCCESS (4.721s) [214.971309] Starting dynamic subtest: C-eDP1 [219.585212] Dynamic subtest C-eDP1: SUCCESS (4.603s) [219.595259] Starting dynamic subtest: D-eDP1 [224.142874] Dynamic subtest D-eDP1: SUCCESS (4.543s) [224.145114] Subtest modeset-vs-vblank-race: SUCCESS (18.571s) [225.334409] [012/132] (792s left) gem_sync (basic-each) [225.405625] Starting subtest: basic-each [227.666751] Subtest basic-each: SUCCESS (2.267s) [227.874079] [013/132] (790s left) gem_ctx_persistence (legacy-engines-mixed) [227.952713] Starting subtest: legacy-engines-mixed [227.955070] Starting dynamic subtest: render [228.079356] Dynamic subtest render: SUCCESS (0.126s) [228.090640] Starting dynamic subtest: bsd1 [228.159918] Dynamic subtest bsd1: SUCCESS (0.078s) [228.172731] Starting dynamic subtest: bsd2 [228.238897] Dynamic subtest bsd2: SUCCESS (0.076s) [228.249777] Starting dynamic subtest: blt [228.279277] Dynamic subtest blt: SUCCESS (0.039s) [228.287859] Starting dynamic subtest: vebox [228.417716] Dynamic subtest vebox: SUCCESS (0.137s) [228.420228] Subtest legacy-engines-mixed: SUCCESS (0.466s) [228.526488] [014/132] (789s left) kms_plane (plane-panning-bottom-right) [228.637399] Starting subtest: plane-panning-bottom-right [228.639749] Starting dynamic subtest: pipe-A [230.388422] Dynamic subtest pipe-A: SUCCESS (1.747s) [230.397779] Starting dynamic subtest: pipe-B [232.767019] Dynamic subtest pipe-B: SUCCESS (2.373s) [232.769396] Subtest plane-panning-bottom-right: SUCCESS (4.125s) [233.587601] [015/132] (784s left) prime_vgem (wait) [233.657953] Starting subtest: wait [233.660451] Starting dynamic subtest: rcs0 [233.696320] Dynamic subtest rcs0: SUCCESS (0.038s) [233.704934] Starting dynamic subtest: bcs0 [233.791971] Dynamic subtest bcs0: SUCCESS (0.095s) [233.800742] Starting dynamic subtest: vcs0 [233.837171] Dynamic subtest vcs0: SUCCESS (0.044s) [233.845431] Starting dynamic subtest: vcs1 [233.880205] Dynamic subtest vcs1: SUCCESS (0.042s) [233.888629] Starting dynamic subtest: vecs0 [233.928616] Dynamic subtest vecs0: SUCCESS (0.047s) [233.937244] Starting dynamic subtest: ccs0 [233.975917] Dynamic subtest ccs0: SUCCESS (0.047s) [233.978217] Subtest wait: SUCCESS (0.318s) [234.148726] [016/132] (784s left) i915_query (query-topology-matches-eu-total) [234.221458] Starting subtest: query-topology-matches-eu-total [234.223535] Subtest query-topology-matches-eu-total: SUCCESS (0.000s) [234.360900] [017/132] (783s left) syncobj_timeline (reset-signaled) [234.423090] Starting subtest: reset-signaled [234.425353] Subtest reset-signaled: SUCCESS (0.000s) [234.564812] [018/132] (783s left) syncobj_timeline (multi-wait-all-available-signaled) [234.620024] Starting subtest: multi-wait-all-available-signaled [234.622008] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s) [234.730234] [019/132] (783s left) kms_cursor_crc (cursor-random-256x256) [234.953013] Starting subtest: cursor-random-256x256 [234.957070] Starting dynamic subtest: pipe-A-eDP-1 [238.314695] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.358s) [238.343579] Starting dynamic subtest: pipe-D-eDP-1 [242.379543] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.042s) [242.381939] Subtest cursor-random-256x256: SUCCESS (7.426s) [243.254392] [020/132] (775s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs) [243.306728] Starting subtest: mixed-tiled-to-y-tiled-ccs [243.310927] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s) [243.515681] [021/132] (774s left) kms_rotation_crc (primary-y-tiled-reflect-x-180) [243.609177] Starting subtest: primary-y-tiled-reflect-x-180 [243.670484] Subtest primary-y-tiled-reflect-x-180: SKIP (0.061s) [243.794625] [022/132] (774s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs) [243.863055] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs [243.867127] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s) [244.064090] [023/132] (774s left) gem_exec_schedule (reorder-wide) [244.259925] Subtest reorder-wide: SKIP (0.000s) [244.461058] [024/132] (774s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary) [244.567449] Starting subtest: fbc-indfb-scaledprimary [247.521088] Subtest fbc-indfb-scaledprimary: SUCCESS (2.956s) [248.676616] [025/132] (769s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen) [248.782771] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen [255.392541] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.612s) [255.567617] [026/132] (763s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs) [255.669104] Starting subtest: crc-primary-suspend-yf-tiled-ccs [255.671481] Starting dynamic subtest: pipe-A-eDP-1 [255.680426] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [255.690995] Starting dynamic subtest: pipe-B-eDP-1 [255.699482] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [255.708005] Starting dynamic subtest: pipe-C-eDP-1 [255.716126] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [255.724119] Starting dynamic subtest: pipe-D-eDP-1 [255.732192] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [255.736092] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.007s) [257.932717] [027/132] (760s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf) [262.997486] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf [263.002002] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s) [263.869932] [028/132] (754s left) kms_cursor_crc (cursor-random-64x21) [264.069887] Starting subtest: cursor-random-64x21 [264.117357] Subtest cursor-random-64x21: SKIP (0.042s) [264.404498] [029/132] (754s left) prime_mmap (test_dup) [264.464453] Starting subtest: test_dup [264.466912] Starting dynamic subtest: test_dup-smem [264.492617] Dynamic subtest test_dup-smem: SUCCESS (0.031s) [264.495000] Subtest test_dup: SUCCESS (0.032s) [264.593564] [030/132] (754s left) gem_exec_schedule (u-submit-late-slice) [264.670243] Starting subtest: u-submit-late-slice [264.673002] Starting dynamic subtest: rcs0 [264.695701] Dynamic subtest rcs0: SUCCESS (0.034s) [264.706750] Starting dynamic subtest: bcs0 [264.728616] Dynamic subtest bcs0: SUCCESS (0.032s) [264.738361] Starting dynamic subtest: vcs0 [264.758596] Dynamic subtest vcs0: SUCCESS (0.029s) [264.770328] Starting dynamic subtest: vcs1 [264.787099] Dynamic subtest vcs1: SUCCESS (0.028s) [264.795432] Starting dynamic subtest: vecs0 [264.817357] Dynamic subtest vecs0: SUCCESS (0.029s) [264.825632] Starting dynamic subtest: ccs0 [264.846636] Dynamic subtest ccs0: SUCCESS (0.029s) [264.848592] Subtest u-submit-late-slice: SUCCESS (0.186s) [266.278543] [031/132] (752s left) syncobj_timeline (etime-multi-wait-all-submitted) [266.346829] Starting subtest: etime-multi-wait-all-submitted [266.448726] Subtest etime-multi-wait-all-submitted: SUCCESS (0.103s) [266.548046] [032/132] (752s left) fbdev (write) [266.599145] Starting subtest: write [266.964265] Subtest write: SUCCESS (0.366s) [267.051818] [033/132] (751s left) syncobj_wait (invalid-signal-zero-handles) [267.116263] Starting subtest: invalid-signal-zero-handles [267.118654] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [267.272336] [034/132] (751s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite) [267.380666] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite [267.384752] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [267.505817] [035/132] (751s left) gem_exec_balancer (sliced) [267.576840] Starting subtest: sliced [267.581565] Subtest sliced: SKIP (0.000s) [267.891220] [036/132] (751s left) gem_ccs (large-ctrl-surf-copy) [267.945578] Starting subtest: large-ctrl-surf-copy [267.950303] Subtest large-ctrl-surf-copy: SKIP (0.000s) [268.078325] [037/132] (751s left) gem_fenced_exec_thrash (2-spare-fences) [268.130386] Subtest 2-spare-fences: SKIP (0.000s) [268.206236] [038/132] (751s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [268.328609] Starting subtest: basic-busy-flip-before-cursor-varying-size [268.333086] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [268.496095] [039/132] (750s left) kms_big_fb (4-tiled-8bpp-rotate-0) [268.601710] Starting subtest: 4-tiled-8bpp-rotate-0 [269.781709] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.179s) [269.964431] [040/132] (749s left) gem_mmap_wc (copy) [270.036056] Subtest copy: SKIP (0.000s) [270.097791] [041/132] (749s left) kms_cursor_legacy (cursor-vs-flip-toggle) [270.238176] Starting subtest: cursor-vs-flip-toggle [301.026600] Subtest cursor-vs-flip-toggle: SUCCESS (30.778s) [301.909842] [042/132] (717s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [302.029026] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip [302.033403] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [302.168986] [043/132] (717s left) kms_addfb_basic (unused-handle) [302.220059] Starting subtest: unused-handle [302.222369] Subtest unused-handle: SUCCESS (0.000s) [302.374781] [044/132] (717s left) gem_ctx_shared (q-smoketest-all) [302.449713] Starting subtest: Q-smoketest-all [334.834488] Subtest Q-smoketest-all: SUCCESS (32.341s) [336.333884] [045/132] (683s left) kms_flip (2x-flip-vs-expired-vblank) [336.433531] Starting subtest: 2x-flip-vs-expired-vblank [336.444585] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s) [336.543533] [046/132] (683s left) kms_force_connector_basic (force-edid) [336.626356] Starting subtest: force-edid [336.992315] Subtest force-edid: SUCCESS (0.366s) [337.152828] [047/132] (682s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [337.251762] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [338.374677] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.123s) [338.544965] [048/132] (681s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5) [338.640720] Starting subtest: planes-unity-scaling-downscale-factor-0-5 [338.642684] Starting dynamic subtest: pipe-A [338.659822] Dynamic subtest pipe-A: SKIP (0.017s) [338.668769] Starting dynamic subtest: pipe-B [338.692512] Dynamic subtest pipe-B: SKIP (0.031s) [338.700941] Starting dynamic subtest: pipe-C [338.724156] Dynamic subtest pipe-C: SKIP (0.031s) [338.732453] Starting dynamic subtest: pipe-D [338.755747] Dynamic subtest pipe-D: SKIP (0.030s) [338.759283] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.112s) [338.893135] [049/132] (680s left) kms_chamelium_audio (hdmi-audio) [339.287891] Subtest hdmi-audio: SKIP (0.000s) [340.092894] [050/132] (679s left) gem_set_tiling_vs_gtt [340.242967] [051/132] (679s left) gem_lmem_swapping (massive) [340.291052] Subtest massive: SKIP (0.000s) [340.336386] [052/132] (679s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render) [340.438596] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render [347.103960] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.666s) [347.264170] [053/132] (672s left) prime_busy (after) [347.320235] Starting subtest: after [347.325437] Starting dynamic subtest: rcs0 [347.369239] Dynamic subtest rcs0: SUCCESS (0.046s) [347.380339] Starting dynamic subtest: bcs0 [347.435228] Dynamic subtest bcs0: SUCCESS (0.064s) [347.445949] Starting dynamic subtest: vcs0 [347.474532] Dynamic subtest vcs0: SUCCESS (0.040s) [347.484443] Starting dynamic subtest: vcs1 [347.514546] Dynamic subtest vcs1: SUCCESS (0.039s) [347.523988] Starting dynamic subtest: vecs0 [347.555375] Dynamic subtest vecs0: SUCCESS (0.040s) [347.564338] Starting dynamic subtest: ccs0 [347.597156] Dynamic subtest ccs0: SUCCESS (0.041s) [347.706071] Subtest after: SUCCESS (0.384s) [347.805304] [054/132] (672s left) syncobj_wait (invalid-signal-illegal-handle) [347.868966] Starting subtest: invalid-signal-illegal-handle [347.871015] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [347.989046] [055/132] (671s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [348.084988] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [348.087336] Starting dynamic subtest: pipe-A-default-mode [348.375996] Dynamic subtest pipe-A-default-mode: SKIP (0.287s) [348.380440] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.289s) [349.218129] [056/132] (670s left) gem_gtt_cpu_tlb [349.341981] [057/132] (670s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [349.439613] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt [349.443910] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [349.564574] [058/132] (670s left) gen9_exec_parse (batch-zero-length) [349.619626] Subtest batch-zero-length: SKIP (0.000s) [349.704460] [059/132] (670s left) gem_softpin (evict-snoop-interruptible) [349.770495] Starting subtest: evict-snoop-interruptible [349.774856] Subtest evict-snoop-interruptible: SKIP (0.000s) [349.942488] [060/132] (670s left) dmabuf_sync_file (export-basic) [349.996910] Starting subtest: export-basic [349.999324] Subtest export-basic: SUCCESS (0.001s) [350.078729] [061/132] (670s left) kms_dither (fb-8bpc-vs-panel-6bpc) [350.184522] Starting subtest: fb-8bpc-vs-panel-6bpc [350.186366] Starting dynamic subtest: pipe-A-eDP-1 [351.531281] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.345s) [351.533537] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.347s) [352.426624] [062/132] (667s left) syncobj_timeline (host-signal-ordered) [352.481868] Starting subtest: host-signal-ordered [352.484036] Subtest host-signal-ordered: SUCCESS (0.000s) [352.606716] [063/132] (667s left) kms_chamelium_hpd (common-hpd-after-suspend) [352.998332] Subtest common-hpd-after-suspend: SKIP (0.000s) [353.776066] [064/132] (666s left) kms_flip (2x-flip-vs-fences) [353.884357] Starting subtest: 2x-flip-vs-fences [353.888582] Subtest 2x-flip-vs-fences: SKIP (0.000s) [354.036867] [065/132] (666s left) perf (mi-rpc) [354.134504] Starting subtest: mi-rpc [354.139058] Subtest mi-rpc: SKIP (0.000s) [354.180582] [066/132] (666s left) sw_sync (sync_random_merge) [354.218111] Starting subtest: sync_random_merge [354.257398] Subtest sync_random_merge: SUCCESS (0.039s) [354.341158] [067/132] (666s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite) [354.435496] Starting subtest: psr-rgb101010-draw-pwrite [362.044894] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.610s) [362.182668] [068/132] (658s left) kms_getfb (getfb2-handle-closed) [362.282871] Starting subtest: getfb2-handle-closed [362.284989] Subtest getfb2-handle-closed: SUCCESS (0.000s) [362.411845] [069/132] (658s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt) [362.520201] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt [362.524519] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [362.657712] [070/132] (657s left) kms_addfb_basic (invalid-smem-bo-on-discrete) [362.707891] Starting subtest: invalid-smem-bo-on-discrete [362.712498] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s) [362.866697] [071/132] (657s left) perf (gen12-invalid-class-instance) [362.965687] Starting subtest: gen12-invalid-class-instance [362.975338] Subtest gen12-invalid-class-instance: SUCCESS (0.007s) [363.020349] [072/132] (657s left) gem_exec_schedule (implicit-read-write) [363.090647] Starting subtest: implicit-read-write [363.092440] Starting dynamic subtest: rcs0 [363.192551] Dynamic subtest rcs0: SUCCESS (0.108s) [363.203784] Starting dynamic subtest: vcs0 [363.275044] Dynamic subtest vcs0: SUCCESS (0.082s) [363.286050] Starting dynamic subtest: vcs1 [363.355688] Dynamic subtest vcs1: SUCCESS (0.080s) [363.366159] Starting dynamic subtest: vecs0 [363.432220] Dynamic subtest vecs0: SUCCESS (0.075s) [363.444527] Starting dynamic subtest: ccs0 [363.512258] Dynamic subtest ccs0: SUCCESS (0.079s) [363.514511] Subtest implicit-read-write: SUCCESS (0.428s) [365.337319] [073/132] (655s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [365.466793] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s) [365.598855] [074/132] (655s left) kms_atomic (plane-cursor-legacy) [365.686236] Starting subtest: plane-cursor-legacy [365.688576] Starting dynamic subtest: pipe-A-eDP-1 [366.053496] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.368s) [366.055907] Subtest plane-cursor-legacy: SUCCESS (0.369s) [366.929981] [075/132] (653s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc) [367.025866] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc [367.028004] Starting dynamic subtest: pipe-A-eDP-1 [367.049673] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [367.060752] Starting dynamic subtest: pipe-B-eDP-1 [367.081408] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [367.089943] Starting dynamic subtest: pipe-C-eDP-1 [367.110717] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [367.118931] Starting dynamic subtest: pipe-D-eDP-1 [367.139832] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [367.143700] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s) [369.326286] [076/132] (651s left) gem_exec_balancer (bonded-pair) [369.400852] Starting subtest: bonded-pair [369.405591] Subtest bonded-pair: SKIP (0.000s) [369.716149] [077/132] (651s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu) [369.814088] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu [369.818505] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [369.957297] [078/132] (650s left) gem_ctx_exec (basic-invalid-context) [370.023419] Starting subtest: basic-invalid-context [370.028265] Subtest basic-invalid-context: SUCCESS (0.004s) [370.210097] [079/132] (650s left) kms_content_protection (lic-type-0-hdcp14) [370.351724] Starting subtest: lic-type-0-hdcp14 [370.356163] Subtest lic-type-0-hdcp14: SKIP (0.000s) [370.519243] [080/132] (650s left) gem_eio (wait-immediate) [370.768975] Starting subtest: wait-immediate [371.145706] Subtest wait-immediate: SUCCESS (0.379s) [371.282626] [081/132] (649s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible) [371.387941] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible [371.400718] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s) [371.498610] [082/132] (649s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling) [371.602926] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling [371.604875] Starting dynamic subtest: pipe-A-default-mode [372.733527] Dynamic subtest pipe-A-default-mode: SKIP (1.126s) [372.737893] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.128s) [372.913747] [083/132] (648s left) kms_psr (psr2-basic) [373.022430] Starting subtest: psr2-basic [373.024724] Starting dynamic subtest: eDP-1 [374.644818] Dynamic subtest eDP-1: SUCCESS (1.623s) [374.647034] Subtest psr2-basic: SUCCESS (1.624s) [375.522308] [084/132] (645s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs) [375.624314] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs [375.626584] Starting dynamic subtest: pipe-A-eDP-1 [375.649228] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [375.660696] Starting dynamic subtest: pipe-B-eDP-1 [375.681434] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [375.689732] Starting dynamic subtest: pipe-C-eDP-1 [375.711213] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [375.722339] Starting dynamic subtest: pipe-D-eDP-1 [375.744005] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [375.748318] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s) [377.930574] [085/132] (643s left) kms_vblank (query-forked-busy) [378.036356] Starting subtest: query-forked-busy [378.038466] Starting dynamic subtest: pipe-A-eDP-1 [379.912341] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.876s) [379.927410] Starting dynamic subtest: pipe-D-eDP-1 [382.436090] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.519s) [382.438379] Subtest query-forked-busy: SUCCESS (4.399s) [383.331170] [086/132] (637s left) kms_pm_dc (dc9-dpms) [384.443813] Starting subtest: dc9-dpms [385.879715] Subtest dc9-dpms: SUCCESS (1.434s) [385.971083] [087/132] (635s left) gem_userptr_blits (set-cache-level) [386.039798] Starting subtest: set-cache-level [386.044073] Subtest set-cache-level: SKIP (0.000s) [386.205229] [088/132] (635s left) gem_exec_fence (keep-in-fence) [386.382573] Starting subtest: keep-in-fence [386.384573] Starting dynamic subtest: rcs0 [386.449169] Dynamic subtest rcs0: SUCCESS (0.069s) [386.460975] Starting dynamic subtest: bcs0 [386.501699] Dynamic subtest bcs0: SUCCESS (0.051s) [386.512636] Starting dynamic subtest: vcs0 [388.156831] Dynamic subtest vcs0: SUCCESS (1.650s) [388.168380] Starting dynamic subtest: vcs1 [390.205806] Dynamic subtest vcs1: SUCCESS (2.046s) [390.217359] Starting dynamic subtest: vecs0 [392.188594] Dynamic subtest vecs0: SUCCESS (1.980s) [392.200193] Starting dynamic subtest: ccs0 [392.409344] Dynamic subtest ccs0: SUCCESS (0.220s) [392.412358] Subtest keep-in-fence: SUCCESS (6.028s) [394.293755] [089/132] (627s left) kms_pm_rpm (modeset-pc8-residency-stress) [396.339946] Starting subtest: modeset-pc8-residency-stress [396.344902] Subtest modeset-pc8-residency-stress: SKIP (0.000s) [396.730059] [090/132] (624s left) kms_psr (fbc-psr2-sprite-plane-move) [396.834449] Starting subtest: fbc-psr2-sprite-plane-move [396.836784] Starting dynamic subtest: eDP-1 [396.841296] Dynamic subtest eDP-1: SKIP (0.000s) [396.845888] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s) [396.931336] [091/132] (624s left) gem_exec_create (forked) [396.991483] Starting subtest: forked [396.993778] Starting dynamic subtest: smem [417.185573] Dynamic subtest smem: SUCCESS (20.187s) [417.188026] Subtest forked: SUCCESS (20.188s) [417.407061] [092/132] (604s left) kms_psr (pr-cursor-mmap-cpu) [417.505050] Starting subtest: pr-cursor-mmap-cpu [417.509353] Subtest pr-cursor-mmap-cpu: SKIP (0.000s) [417.601174] [093/132] (603s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [417.703946] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [417.708082] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [417.835262] [094/132] (603s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb) [422.802921] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb [422.807508] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s) [423.632316] [095/132] (597s left) gem_exec_reloc (basic-wc-read) [423.702906] Subtest basic-wc-read: SKIP (0.000s) [423.801047] [096/132] (597s left) kms_psr (psr-cursor-plane-move) [423.893065] Starting subtest: psr-cursor-plane-move [423.895351] Starting dynamic subtest: eDP-1 [425.573073] Dynamic subtest eDP-1: SUCCESS (1.679s) [425.575526] Subtest psr-cursor-plane-move: SUCCESS (1.680s) [426.435527] [097/132] (595s left) kms_flip (basic-plain-flip) [426.534093] Starting subtest: basic-plain-flip [426.536430] Starting dynamic subtest: A-eDP1 [428.191751] Dynamic subtest A-eDP1: SUCCESS (1.659s) [428.205908] Starting dynamic subtest: B-eDP1 [429.901056] Dynamic subtest B-eDP1: SUCCESS (1.707s) [429.914527] Starting dynamic subtest: C-eDP1 [431.580596] Dynamic subtest C-eDP1: SUCCESS (1.678s) [431.593397] Starting dynamic subtest: D-eDP1 [433.282811] Dynamic subtest D-eDP1: SUCCESS (1.700s) [433.287765] Subtest basic-plain-flip: SUCCESS (6.759s) [434.540245] [098/132] (587s left) kms_flip (plain-flip-fb-recreate) [434.640494] Starting subtest: plain-flip-fb-recreate [434.642623] Starting dynamic subtest: A-eDP1 [443.474589] Dynamic subtest A-eDP1: SUCCESS (8.837s) [443.485437] Starting dynamic subtest: B-eDP1 [452.381810] Dynamic subtest B-eDP1: SUCCESS (8.907s) [452.398051] Starting dynamic subtest: C-eDP1 [461.259734] Dynamic subtest C-eDP1: SUCCESS (8.877s) [461.272220] Starting dynamic subtest: D-eDP1 [470.161000] Dynamic subtest D-eDP1: SUCCESS (8.899s) [470.165451] Subtest plain-flip-fb-recreate: SUCCESS (35.531s) [471.414587] [099/132] (550s left) i915_suspend (debugfs-reader) [471.468778] Starting subtest: debugfs-reader [488.990371] Subtest debugfs-reader: SUCCESS (3.964s) [489.231915] [100/132] (532s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu) [489.346791] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu [489.350849] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [489.458275] [101/132] (532s left) gem_exec_nop (basic-sequential) [489.543675] Starting subtest: basic-sequential [505.819932] Subtest basic-sequential: SUCCESS (16.049s) [506.065428] [102/132] (516s left) gem_pxp (create-regular-buffer) [506.195964] Starting subtest: create-regular-buffer [506.198309] Subtest create-regular-buffer: SUCCESS (0.000s) [506.348354] [103/132] (515s left) syncobj_basic (test-valid-cycle) [506.412500] Starting subtest: test-valid-cycle [506.414450] Subtest test-valid-cycle: SUCCESS (0.000s) [506.547172] [104/132] (515s left) gem_exec_suspend (basic-s3-devices) [506.619320] Starting subtest: basic-S3-devices [506.621482] Starting dynamic subtest: smem [518.771487] Dynamic subtest smem: SUCCESS (12.116s) [518.774143] Subtest basic-S3-devices: SUCCESS (12.118s) [518.983594] [105/132] (503s left) template (a) [519.049403] Starting subtest: A [519.051317] Subtest A: SUCCESS (0.000s) [519.188357] [106/132] (503s left) gem_mmap_gtt (bad-object) [519.250641] Subtest bad-object: SKIP (0.000s) [519.319040] [107/132] (502s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt) [519.425978] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt [519.430364] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [519.550666] [108/132] (502s left) api_intel_bb (intel-bb-blit-y) [519.603033] Starting subtest: intel-bb-blit-y [523.270145] Subtest intel-bb-blit-y: SUCCESS (3.664s) [523.430172] [109/132] (498s left) kms_selftest (drm_format_helper) [523.469800] Starting subtest: drm_format_helper [523.522458] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8 [523.673565] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.152s) [523.682930] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332 [523.816320] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.140s) [523.825541] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565 [523.962555] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.146s) [523.971279] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555 [524.066896] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.104s) [524.077534] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555 [524.174309] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.107s) [524.183328] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551 [524.303963] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.128s) [524.313237] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888 [524.433067] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.126s) [524.444048] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888 [524.553019] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.119s) [524.564120] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888 [524.676359] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.123s) [524.685524] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010 [524.802087] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.124s) [524.812504] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010 [524.953167] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.150s) [524.963875] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono [525.091419] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.139s) [525.100492] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab [525.244212] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.151s) [525.255199] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888 [525.391475] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.147s) [525.400708] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888 [525.540944] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.148s) [525.551505] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset [525.658449] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.118s) [525.667298] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy [525.768211] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.106s) [525.770175] Subtest drm_format_helper: SUCCESS (2.298s) [525.957204] [110/132] (496s left) gem_mmap_offset (oob-read) [526.020658] Starting subtest: oob-read [526.022674] Subtest oob-read: SUCCESS (0.000s) [526.148023] [111/132] (496s left) gem_spin_batch (resubmit-all) [526.212048] Starting subtest: resubmit-all [526.214473] Starting dynamic subtest: rcs0 [526.231394] Dynamic subtest rcs0: SUCCESS (0.019s) [526.243748] Starting dynamic subtest: bcs0 [526.245973] Dynamic subtest bcs0: SUCCESS (0.003s) [526.256637] Starting dynamic subtest: vcs0 [526.258789] Dynamic subtest vcs0: SUCCESS (0.002s) [526.267942] Starting dynamic subtest: vcs1 [526.270159] Dynamic subtest vcs1: SUCCESS (0.003s) [526.279077] Starting dynamic subtest: vecs0 [526.281243] Dynamic subtest vecs0: SUCCESS (0.002s) [526.290100] Starting dynamic subtest: ccs0 [526.292290] Dynamic subtest ccs0: SUCCESS (0.003s) [526.294460] Subtest resubmit-all: SUCCESS (0.037s) [527.800126] [112/132] (494s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [527.925513] Starting subtest: cursorB-vs-flipA-toggle [527.929980] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s) [528.111091] [113/132] (494s left) gen9_exec_parse (valid-registers) [528.180954] Subtest valid-registers: SKIP (0.000s) [528.268039] [114/132] (494s left) kms_pipe_crc_basic (disable-crc-after-crtc) [528.368820] Starting subtest: disable-crc-after-crtc [528.370929] Starting dynamic subtest: pipe-A-eDP-1 [529.984839] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.616s) [529.995156] Starting dynamic subtest: pipe-B-eDP-1 [532.288782] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.300s) [532.298523] Starting dynamic subtest: pipe-C-eDP-1 [534.568174] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.277s) [534.578730] Starting dynamic subtest: pipe-D-eDP-1 [536.769192] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.196s) [536.771141] Subtest disable-crc-after-crtc: SUCCESS (8.395s) [537.597474] [115/132] (485s left) gem_exec_fence (syncobj-unused-fence) [538.006191] Starting subtest: syncobj-unused-fence [538.011701] Subtest syncobj-unused-fence: SUCCESS (0.005s) [539.856274] [116/132] (482s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move) [539.956587] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move [539.960767] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [540.087639] [117/132] (482s left) gem_set_tiling_vs_pwrite [540.209263] [118/132] (482s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [540.310583] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [540.314780] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [540.453289] [119/132] (482s left) vgem_basic (busy-fence) [540.503840] Starting subtest: busy-fence [540.506017] Subtest busy-fence: SUCCESS (0.000s) [540.585898] [120/132] (482s left) i915_pm_rpm (debugfs-read) [542.812851] Starting subtest: debugfs-read [543.976865] Subtest debugfs-read: SUCCESS (1.164s) [544.361637] [121/132] (478s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt) [544.476519] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt [549.029522] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.555s) [550.205643] [122/132] (472s left) perf_pmu (busy-start) [550.250826] Starting subtest: busy-start [550.252970] Starting dynamic subtest: rcs0 [552.799587] Dynamic subtest rcs0: SUCCESS (2.550s) [552.811873] Starting dynamic subtest: bcs0 [555.352401] Dynamic subtest bcs0: SUCCESS (2.551s) [555.364622] Starting dynamic subtest: vcs0 [558.707449] Dynamic subtest vcs0: SUCCESS (3.351s) [558.718290] Starting dynamic subtest: vcs1 [562.739164] Dynamic subtest vcs1: SUCCESS (4.029s) [562.749949] Starting dynamic subtest: vecs0 [566.707541] Dynamic subtest vecs0: SUCCESS (3.965s) [566.718786] Starting dynamic subtest: ccs0 [569.256187] Dynamic subtest ccs0: SUCCESS (2.548s) [569.258673] Subtest busy-start: SUCCESS (19.007s) [569.398948] [123/132] (453s left) kms_addfb_basic (size-max) [569.503031] Starting subtest: size-max [569.505332] Subtest size-max: SUCCESS (0.000s) [569.647027] [124/132] (453s left) kms_psr (fbc-pr-primary-blt) [569.744914] Starting subtest: fbc-pr-primary-blt [569.749358] Subtest fbc-pr-primary-blt: SKIP (0.000s) [569.842193] [125/132] (453s left) kms_color_pipeline (plane-ctm3x4) [569.938178] Starting subtest: plane-ctm3x4 [569.950882] Starting dynamic subtest: pipe-A-eDP-1 [570.575386] Dynamic subtest pipe-A-eDP-1: FAIL (0.304s) [571.322313] Starting dynamic subtest: pipe-B-eDP-1 [572.095676] Dynamic subtest pipe-B-eDP-1: FAIL (0.269s) [572.629414] Starting dynamic subtest: pipe-C-eDP-1 [573.471156] Dynamic subtest pipe-C-eDP-1: FAIL (0.247s) [573.931931] Starting dynamic subtest: pipe-D-eDP-1 [574.736782] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s) [574.738993] Subtest plane-ctm3x4: FAIL (4.512s) [575.311450] [126/132] (447s left) kms_chamelium_hpd (vga-hpd-without-ddc) [575.697851] Subtest vga-hpd-without-ddc: SKIP (0.000s) [576.474835] [127/132] (446s left) api_intel_allocator (standalone) [576.537779] Starting subtest: standalone [576.553269] Subtest standalone: SUCCESS (0.018s) [576.727381] [128/132] (446s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [576.845812] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [583.311510] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.469s) [583.449932] [129/132] (439s left) kms_big_fb (y-tiled-addfb) [583.565536] Starting subtest: y-tiled-addfb [583.569589] Subtest y-tiled-addfb: SKIP (0.000s) [583.689453] [130/132] (439s left) kms_prime (basic-crc-vgem) [583.787751] Starting subtest: basic-crc-vgem [583.790012] Starting dynamic subtest: second-to-first [584.687372] Dynamic subtest second-to-first: SUCCESS (0.903s) [584.689632] Subtest basic-crc-vgem: SUCCESS (0.904s) [584.806314] [131/132] (438s left) i915_hangman (engine-error-state-capture) [584.906137] Starting subtest: engine-error-state-capture [584.907886] Starting dynamic subtest: rcs0 [588.690056] Dynamic subtest rcs0: SUCCESS (3.782s) [588.701371] Starting dynamic subtest: bcs0 [594.706545] Dynamic subtest bcs0: SUCCESS (6.012s) [594.719172] Starting dynamic subtest: vcs0 [600.658800] Dynamic subtest vcs0: SUCCESS (5.947s) [600.671108] Starting dynamic subtest: vcs1 [605.649680] Dynamic subtest vcs1: SUCCESS (4.986s) [605.662121] Starting dynamic subtest: vecs0 [610.644622] Dynamic subtest vecs0: SUCCESS (4.990s) [610.657008] Starting dynamic subtest: ccs0 [615.698721] Dynamic subtest ccs0: SUCCESS (5.049s) [615.701395] Subtest engine-error-state-capture: SUCCESS (30.783s) [615.809631] [132/132] (407s left) kms_invalid_mode (bad-vsync-end) [615.912396] Starting subtest: bad-vsync-end [615.914893] Starting dynamic subtest: pipe-A-eDP-1 [616.191249] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s) [616.201003] Starting dynamic subtest: pipe-B-eDP-1 [616.203000] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [616.211572] Starting dynamic subtest: pipe-C-eDP-1 [616.213549] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [616.222523] Starting dynamic subtest: pipe-D-eDP-1 [616.224799] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [616.227122] Subtest bad-vsync-end: SUCCESS (0.291s) [617.013379] 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.