[60.561173] Initializing watchdogs [60.561237] /dev/watchdog0 [60.574780] [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] [60.578924] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [60.581125] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [60.584162] [001/132] (960s left) syncobj_timeline (wait-for-submit-snapshot) [60.660618] Starting subtest: wait-for-submit-snapshot [60.741790] Subtest wait-for-submit-snapshot: SUCCESS (0.082s) [60.838807] [002/132] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs) [60.930138] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs [60.932510] Starting dynamic subtest: pipe-A-eDP-1 [61.192470] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.228s) [61.201387] Starting dynamic subtest: pipe-B-eDP-1 [62.485268] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.292s) [62.494124] Starting dynamic subtest: pipe-C-eDP-1 [63.764319] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.283s) [63.772618] Starting dynamic subtest: pipe-D-eDP-1 [64.994303] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s) [64.996358] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.033s) [66.147777] [003/132] (955s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt) [66.245208] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt [70.355072] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.115s) [71.558409] [004/132] (949s left) kms_big_fb (linear-8bpp-rotate-270) [71.669054] Starting subtest: linear-8bpp-rotate-270 [71.690887] Subtest linear-8bpp-rotate-270: SKIP (0.022s) [71.847925] [005/132] (949s left) kms_busy (extended-modeset-hang-newfb) [71.963594] Starting subtest: extended-modeset-hang-newfb [71.965853] Starting dynamic subtest: pipe-A [106.795934] Dynamic subtest pipe-A: SUCCESS (34.834s) [106.803688] Starting dynamic subtest: pipe-D [129.904768] Dynamic subtest pipe-D: SUCCESS (23.105s) [129.907182] Subtest extended-modeset-hang-newfb: SUCCESS (57.945s) [130.722685] [006/132] (890s left) perf (oa-exponents) [130.855443] Starting subtest: oa-exponents [130.857720] Starting dynamic subtest: 0-rcs0 [132.633638] Dynamic subtest 0-rcs0: SUCCESS (1.778s) [132.641776] Starting dynamic subtest: 1-vcs0 [134.460853] Dynamic subtest 1-vcs0: SUCCESS (1.826s) [134.463649] Subtest oa-exponents: SUCCESS (3.605s) [134.507341] [007/132] (886s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [134.604169] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [135.671337] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.070s) [135.837131] [008/132] (885s left) kms_pipe_crc_basic (suspend-read-crc) [135.937037] Starting subtest: suspend-read-crc [135.938848] Starting dynamic subtest: pipe-A-eDP-1 [153.866534] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.089s) [153.876096] Starting dynamic subtest: pipe-B-eDP-1 [171.853398] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.100s) [171.862517] Starting dynamic subtest: pipe-C-eDP-1 [189.869119] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.072s) [189.878574] Starting dynamic subtest: pipe-D-eDP-1 [207.830526] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.011s) [207.833044] Subtest suspend-read-crc: SUCCESS (15.276s) [208.680987] [009/132] (812s left) perf_pmu (interrupts-sync) [208.733038] Starting subtest: interrupts-sync [209.848275] Subtest interrupts-sync: SUCCESS (1.118s) [209.994234] [FACT perf_pmu (interrupts-sync)] new: kernel.kmod_is_loaded.vgem: true [209.997769] [010/132] (811s left) gem_exec_reloc (basic-gtt-active) [210.066205] Subtest basic-gtt-active: SKIP (0.000s) [210.146945] [011/132] (811s left) kms_flip (modeset-vs-vblank-race) [210.246361] Starting subtest: modeset-vs-vblank-race [210.249857] Starting dynamic subtest: A-eDP1 [214.963632] Dynamic subtest A-eDP1: SUCCESS (4.726s) [214.974905] Starting dynamic subtest: B-eDP1 [219.660639] Dynamic subtest B-eDP1: SUCCESS (4.699s) [219.671426] Starting dynamic subtest: C-eDP1 [224.346190] Dynamic subtest C-eDP1: SUCCESS (4.688s) [224.356390] Starting dynamic subtest: D-eDP1 [228.875775] Dynamic subtest D-eDP1: SUCCESS (4.531s) [228.881526] Subtest modeset-vs-vblank-race: SUCCESS (18.655s) [230.066397] [012/132] (791s left) gem_sync (basic-each) [230.148871] Starting subtest: basic-each [232.527761] Subtest basic-each: SUCCESS (2.393s) [232.742976] [013/132] (788s left) gem_ctx_persistence (legacy-engines-mixed) [232.799285] Starting subtest: legacy-engines-mixed [232.801926] Starting dynamic subtest: render [232.921861] Dynamic subtest render: SUCCESS (0.122s) [232.936303] Starting dynamic subtest: bsd1 [233.009367] Dynamic subtest bsd1: SUCCESS (0.085s) [233.020638] Starting dynamic subtest: bsd2 [233.086385] Dynamic subtest bsd2: SUCCESS (0.075s) [233.097516] Starting dynamic subtest: blt [233.180375] Dynamic subtest blt: SUCCESS (0.092s) [233.191621] Starting dynamic subtest: vebox [233.270385] Dynamic subtest vebox: SUCCESS (0.088s) [233.273292] Subtest legacy-engines-mixed: SUCCESS (0.473s) [233.375283] [014/132] (788s left) kms_plane (plane-panning-bottom-right) [233.475979] Starting subtest: plane-panning-bottom-right [233.478444] Starting dynamic subtest: pipe-A [235.119816] Dynamic subtest pipe-A: SUCCESS (1.642s) [235.131147] Starting dynamic subtest: pipe-B [237.369106] Dynamic subtest pipe-B: SUCCESS (2.253s) [237.371825] Subtest plane-panning-bottom-right: SUCCESS (3.897s) [238.204153] [015/132] (783s left) prime_vgem (wait) [238.278446] Starting subtest: wait [238.281309] Starting dynamic subtest: rcs0 [238.329053] Dynamic subtest rcs0: SUCCESS (0.050s) [238.337502] Starting dynamic subtest: bcs0 [238.371895] Dynamic subtest bcs0: SUCCESS (0.042s) [238.380394] Starting dynamic subtest: vcs0 [238.418239] Dynamic subtest vcs0: SUCCESS (0.045s) [238.426624] Starting dynamic subtest: vcs1 [238.465198] Dynamic subtest vcs1: SUCCESS (0.046s) [238.474240] Starting dynamic subtest: vecs0 [238.512443] Dynamic subtest vecs0: SUCCESS (0.046s) [238.521170] Starting dynamic subtest: ccs0 [238.559055] Dynamic subtest ccs0: SUCCESS (0.047s) [238.562011] Subtest wait: SUCCESS (0.281s) [238.712423] [016/132] (782s left) i915_query (query-topology-matches-eu-total) [238.785137] Starting subtest: query-topology-matches-eu-total [238.787563] Subtest query-topology-matches-eu-total: SUCCESS (0.000s) [238.910759] [017/132] (782s left) syncobj_timeline (reset-signaled) [238.978898] Starting subtest: reset-signaled [238.981753] Subtest reset-signaled: SUCCESS (0.000s) [239.101801] [018/132] (782s left) syncobj_timeline (multi-wait-all-available-signaled) [239.172110] Starting subtest: multi-wait-all-available-signaled [239.174431] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s) [239.311954] [019/132] (782s left) kms_cursor_crc (cursor-random-256x256) [239.555205] Starting subtest: cursor-random-256x256 [239.557302] Starting dynamic subtest: pipe-A-eDP-1 [242.953807] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.398s) [242.983138] Starting dynamic subtest: pipe-D-eDP-1 [247.057254] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.085s) [247.059899] Subtest cursor-random-256x256: SUCCESS (7.508s) [247.951932] [020/132] (773s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs) [248.026649] Starting subtest: mixed-tiled-to-y-tiled-ccs [248.031909] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s) [248.231217] [021/132] (773s left) kms_rotation_crc (primary-y-tiled-reflect-x-180) [248.323343] Starting subtest: primary-y-tiled-reflect-x-180 [248.382915] Subtest primary-y-tiled-reflect-x-180: SKIP (0.059s) [248.515796] [022/132] (773s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs) [248.581638] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs [248.587680] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s) [248.792093] [023/132] (773s left) gem_exec_schedule (reorder-wide) [248.979658] Subtest reorder-wide: SKIP (0.000s) [249.180567] [024/132] (772s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary) [249.286131] Starting subtest: fbc-indfb-scaledprimary [252.235552] Subtest fbc-indfb-scaledprimary: SUCCESS (2.956s) [253.456849] [025/132] (768s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen) [253.569914] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen [260.061387] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.497s) [260.246060] [026/132] (761s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs) [260.346333] Starting subtest: crc-primary-suspend-yf-tiled-ccs [260.349080] Starting dynamic subtest: pipe-A-eDP-1 [260.359466] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [260.369515] Starting dynamic subtest: pipe-B-eDP-1 [260.379445] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [260.388002] Starting dynamic subtest: pipe-C-eDP-1 [260.398060] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [260.406375] Starting dynamic subtest: pipe-D-eDP-1 [260.416388] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [260.421366] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.004s) [262.554963] [027/132] (759s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf) [267.611019] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf [267.616403] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s) [268.497433] [028/132] (753s left) kms_cursor_crc (cursor-random-64x21) [268.694059] Starting subtest: cursor-random-64x21 [268.736907] Subtest cursor-random-64x21: SKIP (0.038s) [268.988974] [029/132] (753s left) prime_mmap (test_dup) [269.051737] Starting subtest: test_dup [269.054485] Starting dynamic subtest: test_dup-smem [269.078003] Dynamic subtest test_dup-smem: SUCCESS (0.029s) [269.080615] Subtest test_dup: SUCCESS (0.029s) [269.171654] [030/132] (753s left) gem_exec_schedule (u-submit-late-slice) [269.247701] Starting subtest: u-submit-late-slice [269.250367] Starting dynamic subtest: rcs0 [269.276179] Dynamic subtest rcs0: SUCCESS (0.041s) [269.287296] Starting dynamic subtest: bcs0 [269.304396] Dynamic subtest bcs0: SUCCESS (0.028s) [269.313050] Starting dynamic subtest: vcs0 [269.331348] Dynamic subtest vcs0: SUCCESS (0.026s) [269.339833] Starting dynamic subtest: vcs1 [269.362679] Dynamic subtest vcs1: SUCCESS (0.031s) [269.371416] Starting dynamic subtest: vecs0 [269.395897] Dynamic subtest vecs0: SUCCESS (0.032s) [269.410435] Starting dynamic subtest: ccs0 [269.430667] Dynamic subtest ccs0: SUCCESS (0.034s) [269.433297] Subtest u-submit-late-slice: SUCCESS (0.196s) [270.682065] [031/132] (751s left) syncobj_timeline (etime-multi-wait-all-submitted) [270.740437] Starting subtest: etime-multi-wait-all-submitted [270.841671] Subtest etime-multi-wait-all-submitted: SUCCESS (0.103s) [270.969980] [032/132] (751s left) fbdev (write) [271.028805] Starting subtest: write [271.389066] Subtest write: SUCCESS (0.358s) [271.478421] [033/132] (750s left) syncobj_wait (invalid-signal-zero-handles) [271.534691] Starting subtest: invalid-signal-zero-handles [271.537736] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [271.658673] [034/132] (750s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite) [271.759111] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite [271.764214] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [271.888968] [035/132] (750s left) gem_exec_balancer (sliced) [271.956497] Starting subtest: sliced [271.961392] Subtest sliced: SKIP (0.000s) [272.280748] [036/132] (750s left) gem_ccs (large-ctrl-surf-copy) [272.337620] Starting subtest: large-ctrl-surf-copy [272.343426] Subtest large-ctrl-surf-copy: SKIP (0.000s) [272.469897] [037/132] (750s left) gem_fenced_exec_thrash (2-spare-fences) [272.533198] Subtest 2-spare-fences: SKIP (0.000s) [272.603067] [038/132] (750s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [272.724526] Starting subtest: basic-busy-flip-before-cursor-varying-size [272.730042] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [272.890374] [039/132] (749s left) kms_big_fb (4-tiled-8bpp-rotate-0) [273.000456] Starting subtest: 4-tiled-8bpp-rotate-0 [274.169915] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.169s) [274.325017] [040/132] (748s left) gem_mmap_wc (copy) [274.376652] Subtest copy: SKIP (0.000s) [274.445647] [041/132] (748s left) kms_cursor_legacy (cursor-vs-flip-toggle) [274.575259] Starting subtest: cursor-vs-flip-toggle [305.299276] Subtest cursor-vs-flip-toggle: SUCCESS (30.728s) [306.170281] [042/132] (716s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [306.273733] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip [306.279033] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [306.405181] [043/132] (716s left) kms_addfb_basic (unused-handle) [306.462303] Starting subtest: unused-handle [306.464630] Subtest unused-handle: SUCCESS (0.000s) [306.632108] [044/132] (716s left) gem_ctx_shared (q-smoketest-all) [306.704615] Starting subtest: Q-smoketest-all [339.151436] Subtest Q-smoketest-all: SUCCESS (32.349s) [340.697213] [045/132] (682s left) kms_flip (2x-flip-vs-expired-vblank) [340.799510] Starting subtest: 2x-flip-vs-expired-vblank [340.807886] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s) [340.909631] [046/132] (682s left) kms_force_connector_basic (force-edid) [340.996113] Starting subtest: force-edid [341.362989] Subtest force-edid: SUCCESS (0.369s) [341.526597] [047/132] (681s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [341.649048] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [342.650629] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.000s) [342.804122] [048/132] (680s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5) [342.898103] Starting subtest: planes-unity-scaling-downscale-factor-0-5 [342.899934] Starting dynamic subtest: pipe-A [342.916797] Dynamic subtest pipe-A: SKIP (0.015s) [342.925647] Starting dynamic subtest: pipe-B [342.945287] Dynamic subtest pipe-B: SKIP (0.027s) [342.953647] Starting dynamic subtest: pipe-C [342.973753] Dynamic subtest pipe-C: SKIP (0.028s) [342.981869] Starting dynamic subtest: pipe-D [343.000599] Dynamic subtest pipe-D: SKIP (0.028s) [343.003995] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.101s) [343.132202] [049/132] (679s left) kms_chamelium_audio (hdmi-audio) [343.504328] Subtest hdmi-audio: SKIP (0.000s) [344.344981] [050/132] (678s left) gem_set_tiling_vs_gtt [344.479373] [051/132] (678s left) gem_lmem_swapping (massive) [344.523834] Subtest massive: SKIP (0.000s) [344.568908] [052/132] (678s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render) [344.671363] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render [351.268266] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.599s) [351.397161] [053/132] (671s left) prime_busy (after) [351.464724] Starting subtest: after [351.469478] Starting dynamic subtest: rcs0 [351.512175] Dynamic subtest rcs0: SUCCESS (0.044s) [351.522161] Starting dynamic subtest: bcs0 [351.587671] Dynamic subtest bcs0: SUCCESS (0.075s) [351.597064] Starting dynamic subtest: vcs0 [351.629408] Dynamic subtest vcs0: SUCCESS (0.041s) [351.637872] Starting dynamic subtest: vcs1 [351.672057] Dynamic subtest vcs1: SUCCESS (0.042s) [351.680475] Starting dynamic subtest: vecs0 [351.713451] Dynamic subtest vecs0: SUCCESS (0.040s) [351.722398] Starting dynamic subtest: ccs0 [351.754832] Dynamic subtest ccs0: SUCCESS (0.040s) [351.863181] Subtest after: SUCCESS (0.397s) [351.958132] [054/132] (671s left) syncobj_wait (invalid-signal-illegal-handle) [352.000877] Starting subtest: invalid-signal-illegal-handle [352.003452] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [352.107026] [055/132] (671s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [352.200042] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [352.202323] Starting dynamic subtest: pipe-A-default-mode [352.494113] Dynamic subtest pipe-A-default-mode: SKIP (0.291s) [352.499131] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.293s) [353.374966] [056/132] (670s left) gem_gtt_cpu_tlb [353.506967] [057/132] (669s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [353.612477] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt [353.617463] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [353.740873] [058/132] (669s left) gen9_exec_parse (batch-zero-length) [353.795413] Subtest batch-zero-length: SKIP (0.000s) [353.874427] [059/132] (669s left) gem_softpin (evict-snoop-interruptible) [353.937622] Starting subtest: evict-snoop-interruptible [353.943103] Subtest evict-snoop-interruptible: SKIP (0.000s) [354.104025] [060/132] (669s left) dmabuf_sync_file (export-basic) [354.152303] Starting subtest: export-basic [354.154180] Subtest export-basic: SUCCESS (0.001s) [354.231000] [061/132] (669s left) kms_dither (fb-8bpc-vs-panel-6bpc) [354.331048] Starting subtest: fb-8bpc-vs-panel-6bpc [354.333045] Starting dynamic subtest: pipe-A-eDP-1 [355.712769] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.382s) [355.715664] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.384s) [356.589723] [062/132] (667s left) syncobj_timeline (host-signal-ordered) [356.654350] Starting subtest: host-signal-ordered [356.656772] Subtest host-signal-ordered: SUCCESS (0.000s) [356.774227] [063/132] (666s left) kms_chamelium_hpd (common-hpd-after-suspend) [357.162102] Subtest common-hpd-after-suspend: SKIP (0.000s) [357.938506] [064/132] (665s left) kms_flip (2x-flip-vs-fences) [358.040940] Starting subtest: 2x-flip-vs-fences [358.045381] Subtest 2x-flip-vs-fences: SKIP (0.000s) [358.171134] [065/132] (665s left) perf (mi-rpc) [358.275465] Starting subtest: mi-rpc [358.280771] Subtest mi-rpc: SKIP (0.000s) [358.320010] [066/132] (665s left) sw_sync (sync_random_merge) [358.360965] Starting subtest: sync_random_merge [358.395164] Subtest sync_random_merge: SUCCESS (0.037s) [358.482196] [067/132] (665s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite) [358.598244] Starting subtest: psr-rgb101010-draw-pwrite [366.140904] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.545s) [366.282181] [068/132] (657s left) kms_getfb (getfb2-handle-closed) [366.386011] Starting subtest: getfb2-handle-closed [366.388429] Subtest getfb2-handle-closed: SUCCESS (0.000s) [366.512998] [069/132] (657s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt) [366.610368] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt [366.614914] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [366.748964] [070/132] (657s left) kms_addfb_basic (invalid-smem-bo-on-discrete) [366.812422] Starting subtest: invalid-smem-bo-on-discrete [366.817522] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s) [366.981923] [071/132] (657s left) perf (gen12-invalid-class-instance) [367.087343] Starting subtest: gen12-invalid-class-instance [367.099020] Subtest gen12-invalid-class-instance: SUCCESS (0.009s) [367.143837] [072/132] (656s left) gem_exec_schedule (implicit-read-write) [367.215169] Starting subtest: implicit-read-write [367.217151] Starting dynamic subtest: rcs0 [367.319292] Dynamic subtest rcs0: SUCCESS (0.115s) [367.330432] Starting dynamic subtest: vcs0 [367.400330] Dynamic subtest vcs0: SUCCESS (0.080s) [367.411526] Starting dynamic subtest: vcs1 [367.477799] Dynamic subtest vcs1: SUCCESS (0.077s) [367.488558] Starting dynamic subtest: vecs0 [367.553637] Dynamic subtest vecs0: SUCCESS (0.075s) [367.565015] Starting dynamic subtest: ccs0 [367.633602] Dynamic subtest ccs0: SUCCESS (0.079s) [367.636262] Subtest implicit-read-write: SUCCESS (0.430s) [368.648927] [073/132] (655s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [368.744635] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s) [368.880136] [074/132] (655s left) kms_atomic (plane-cursor-legacy) [368.975370] Starting subtest: plane-cursor-legacy [368.978312] Starting dynamic subtest: pipe-A-eDP-1 [369.340490] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.366s) [369.343455] Subtest plane-cursor-legacy: SUCCESS (0.368s) [370.196731] [075/132] (653s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc) [370.285605] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc [370.288414] Starting dynamic subtest: pipe-A-eDP-1 [370.315325] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [370.325480] Starting dynamic subtest: pipe-B-eDP-1 [370.352158] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [370.360600] Starting dynamic subtest: pipe-C-eDP-1 [370.387468] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [370.396204] Starting dynamic subtest: pipe-D-eDP-1 [370.422120] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [370.426813] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s) [372.597281] [076/132] (651s left) gem_exec_balancer (bonded-pair) [372.664444] Starting subtest: bonded-pair [372.669873] Subtest bonded-pair: SKIP (0.000s) [372.998433] [077/132] (651s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu) [373.101790] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu [373.107275] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [373.216497] [078/132] (651s left) gem_ctx_exec (basic-invalid-context) [373.282936] Starting subtest: basic-invalid-context [373.285370] Subtest basic-invalid-context: SUCCESS (0.005s) [373.428555] [079/132] (650s left) kms_content_protection (lic-type-0-hdcp14) [373.576814] Starting subtest: lic-type-0-hdcp14 [373.582476] Subtest lic-type-0-hdcp14: SKIP (0.000s) [373.740548] [080/132] (650s left) gem_eio (wait-immediate) [373.974790] Starting subtest: wait-immediate [374.356396] Subtest wait-immediate: SUCCESS (0.381s) [374.519475] [081/132] (649s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible) [374.629538] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible [374.640177] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s) [374.786805] [082/132] (649s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling) [374.886199] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling [374.888090] Starting dynamic subtest: pipe-A-default-mode [376.096289] Dynamic subtest pipe-A-default-mode: SKIP (1.206s) [376.100650] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.208s) [376.271192] [083/132] (648s left) kms_psr (psr2-basic) [376.376603] Starting subtest: psr2-basic [376.379074] Starting dynamic subtest: eDP-1 [378.001532] Dynamic subtest eDP-1: SUCCESS (1.622s) [378.004069] Subtest psr2-basic: SUCCESS (1.623s) [378.884002] [084/132] (645s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs) [378.977190] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs [378.979646] Starting dynamic subtest: pipe-A-eDP-1 [379.014829] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [379.025241] Starting dynamic subtest: pipe-B-eDP-1 [379.049540] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [379.059026] Starting dynamic subtest: pipe-C-eDP-1 [379.086433] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [379.096629] Starting dynamic subtest: pipe-D-eDP-1 [379.123031] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [379.127784] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s) [381.295982] [085/132] (643s left) kms_vblank (query-forked-busy) [381.400381] Starting subtest: query-forked-busy [381.402972] Starting dynamic subtest: pipe-A-eDP-1 [383.276567] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.876s) [383.297534] Starting dynamic subtest: pipe-D-eDP-1 [385.811374] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.531s) [385.814258] Subtest query-forked-busy: SUCCESS (4.412s) [386.681135] [086/132] (637s left) kms_pm_dc (dc9-dpms) [387.784140] Starting subtest: dc9-dpms [389.205512] Subtest dc9-dpms: SUCCESS (1.422s) [389.302951] [087/132] (635s left) gem_userptr_blits (set-cache-level) [389.368382] Starting subtest: set-cache-level [389.373619] Subtest set-cache-level: SKIP (0.000s) [389.526162] [088/132] (635s left) gem_exec_fence (keep-in-fence) [389.720851] Starting subtest: keep-in-fence [389.723477] Starting dynamic subtest: rcs0 [389.777882] Dynamic subtest rcs0: SUCCESS (0.057s) [389.789875] Starting dynamic subtest: bcs0 [389.832865] Dynamic subtest bcs0: SUCCESS (0.053s) [389.844667] Starting dynamic subtest: vcs0 [391.569360] Dynamic subtest vcs0: SUCCESS (1.733s) [391.580885] Starting dynamic subtest: vcs1 [393.551791] Dynamic subtest vcs1: SUCCESS (1.980s) [393.564220] Starting dynamic subtest: vecs0 [395.536082] Dynamic subtest vecs0: SUCCESS (1.982s) [395.547795] Starting dynamic subtest: ccs0 [395.772672] Dynamic subtest ccs0: SUCCESS (0.235s) [395.775631] Subtest keep-in-fence: SUCCESS (6.053s) [397.650490] [089/132] (627s left) kms_pm_rpm (modeset-pc8-residency-stress) [399.691647] Starting subtest: modeset-pc8-residency-stress [399.697464] Subtest modeset-pc8-residency-stress: SKIP (0.000s) [400.062120] [090/132] (624s left) kms_psr (fbc-psr2-sprite-plane-move) [400.168166] Starting subtest: fbc-psr2-sprite-plane-move [400.170645] Starting dynamic subtest: eDP-1 [400.175524] Dynamic subtest eDP-1: SKIP (0.000s) [400.180412] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s) [400.268658] [091/132] (624s left) gem_exec_create (forked) [400.329620] Starting subtest: forked [400.332387] Starting dynamic subtest: smem [420.542678] Dynamic subtest smem: SUCCESS (20.212s) [420.545672] Subtest forked: SUCCESS (20.213s) [420.755035] [092/132] (604s left) kms_psr (pr-cursor-mmap-cpu) [420.849530] Starting subtest: pr-cursor-mmap-cpu [420.854661] Subtest pr-cursor-mmap-cpu: SKIP (0.000s) [420.945112] [093/132] (603s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [421.049504] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [421.054392] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [421.180248] [094/132] (603s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb) [426.239129] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb [426.244466] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s) [427.082885] [095/132] (597s left) gem_exec_reloc (basic-wc-read) [427.159478] Subtest basic-wc-read: SKIP (0.000s) [427.243876] [096/132] (597s left) kms_psr (psr-cursor-plane-move) [427.338373] Starting subtest: psr-cursor-plane-move [427.340857] Starting dynamic subtest: eDP-1 [429.016212] Dynamic subtest eDP-1: SUCCESS (1.679s) [429.018634] Subtest psr-cursor-plane-move: SUCCESS (1.680s) [429.886201] [097/132] (595s left) kms_flip (basic-plain-flip) [429.992205] Starting subtest: basic-plain-flip [429.994415] Starting dynamic subtest: A-eDP1 [431.647570] Dynamic subtest A-eDP1: SUCCESS (1.656s) [431.659891] Starting dynamic subtest: B-eDP1 [433.322645] Dynamic subtest B-eDP1: SUCCESS (1.674s) [433.336494] Starting dynamic subtest: C-eDP1 [435.018609] Dynamic subtest C-eDP1: SUCCESS (1.694s) [435.029535] Starting dynamic subtest: D-eDP1 [436.636671] Dynamic subtest D-eDP1: SUCCESS (1.617s) [436.639560] Subtest basic-plain-flip: SUCCESS (6.651s) [437.886905] [098/132] (587s left) kms_flip (plain-flip-fb-recreate) [437.981098] Starting subtest: plain-flip-fb-recreate [437.983637] Starting dynamic subtest: A-eDP1 [446.808284] Dynamic subtest A-eDP1: SUCCESS (8.827s) [446.818335] Starting dynamic subtest: B-eDP1 [455.708877] Dynamic subtest B-eDP1: SUCCESS (8.900s) [455.718676] Starting dynamic subtest: C-eDP1 [464.513387] Dynamic subtest C-eDP1: SUCCESS (8.803s) [464.524422] Starting dynamic subtest: D-eDP1 [473.406871] Dynamic subtest D-eDP1: SUCCESS (8.892s) [473.412014] Subtest plain-flip-fb-recreate: SUCCESS (35.432s) [474.689020] [099/132] (550s left) i915_suspend (debugfs-reader) [474.750326] Starting subtest: debugfs-reader [492.947236] Subtest debugfs-reader: SUCCESS (3.913s) [493.126437] [100/132] (532s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu) [493.254311] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu [493.259167] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [493.382538] [101/132] (532s left) gem_exec_nop (basic-sequential) [493.459114] Starting subtest: basic-sequential [508.309608] Subtest basic-sequential: SUCCESS (14.868s) [508.577901] [102/132] (516s left) gem_pxp (create-regular-buffer) [508.706294] Starting subtest: create-regular-buffer [508.709203] Subtest create-regular-buffer: SUCCESS (0.000s) [508.854688] [103/132] (516s left) syncobj_basic (test-valid-cycle) [508.916346] Starting subtest: test-valid-cycle [508.918819] Subtest test-valid-cycle: SUCCESS (0.000s) [509.035433] [104/132] (516s left) gem_exec_suspend (basic-s3-devices) [509.099742] Starting subtest: basic-S3-devices [509.102415] Starting dynamic subtest: smem [520.859672] Dynamic subtest smem: SUCCESS (11.761s) [520.862672] Subtest basic-S3-devices: SUCCESS (11.763s) [521.067890] [105/132] (504s left) template (a) [521.122264] Starting subtest: A [521.125107] Subtest A: SUCCESS (0.000s) [521.254656] [106/132] (504s left) gem_mmap_gtt (bad-object) [521.308792] Subtest bad-object: SKIP (0.000s) [521.412639] [107/132] (504s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt) [521.524084] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt [521.528926] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [521.658888] [108/132] (504s left) api_intel_bb (intel-bb-blit-y) [521.724862] Starting subtest: intel-bb-blit-y [525.328843] Subtest intel-bb-blit-y: SUCCESS (3.604s) [525.479638] [109/132] (500s left) kms_selftest (drm_format_helper) [525.516931] Starting subtest: drm_format_helper [525.562322] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8 [525.743079] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.176s) [525.752735] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332 [525.898153] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.156s) [525.907989] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565 [526.027530] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.130s) [526.037155] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555 [526.168332] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.138s) [526.178466] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555 [526.314337] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.146s) [526.324655] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551 [526.402322] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.086s) [526.412309] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888 [526.543111] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.141s) [526.553374] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888 [526.692618] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.147s) [526.708624] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888 [526.837797] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.145s) [526.846889] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010 [526.992215] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.153s) [527.001710] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010 [527.093351] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.101s) [527.101831] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono [527.243887] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.151s) [527.253309] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab [527.398045] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.153s) [527.407451] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888 [527.543468] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.145s) [527.553194] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888 [527.646765] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.100s) [527.666277] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset [527.749648] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.102s) [527.759883] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy [527.864895] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.113s) [527.867139] Subtest drm_format_helper: SUCCESS (2.347s) [528.052533] [110/132] (497s left) gem_mmap_offset (oob-read) [528.107318] Starting subtest: oob-read [528.110145] Subtest oob-read: SUCCESS (0.000s) [528.224329] [111/132] (497s left) gem_spin_batch (resubmit-all) [528.282045] Starting subtest: resubmit-all [528.284858] Starting dynamic subtest: rcs0 [528.310137] Dynamic subtest rcs0: SUCCESS (0.028s) [528.319379] Starting dynamic subtest: bcs0 [528.322018] Dynamic subtest bcs0: SUCCESS (0.003s) [528.331048] Starting dynamic subtest: vcs0 [528.333006] Dynamic subtest vcs0: SUCCESS (0.003s) [528.342292] Starting dynamic subtest: vcs1 [528.344885] Dynamic subtest vcs1: SUCCESS (0.003s) [528.353605] Starting dynamic subtest: vecs0 [528.355954] Dynamic subtest vecs0: SUCCESS (0.002s) [528.365728] Starting dynamic subtest: ccs0 [528.368104] Dynamic subtest ccs0: SUCCESS (0.003s) [528.370419] Subtest resubmit-all: SUCCESS (0.045s) [529.884155] [112/132] (496s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [530.018313] Starting subtest: cursorB-vs-flipA-toggle [530.023136] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s) [530.189310] [113/132] (495s left) gen9_exec_parse (valid-registers) [530.256164] Subtest valid-registers: SKIP (0.000s) [530.318806] [114/132] (495s left) kms_pipe_crc_basic (disable-crc-after-crtc) [530.413314] Starting subtest: disable-crc-after-crtc [530.415918] Starting dynamic subtest: pipe-A-eDP-1 [532.062054] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.646s) [532.072428] Starting dynamic subtest: pipe-B-eDP-1 [534.378780] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.318s) [534.389360] Starting dynamic subtest: pipe-C-eDP-1 [536.749180] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.367s) [536.759828] Starting dynamic subtest: pipe-D-eDP-1 [538.943914] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.195s) [538.947056] Subtest disable-crc-after-crtc: SUCCESS (8.533s) [539.768852] [115/132] (486s left) gem_exec_fence (syncobj-unused-fence) [540.181209] Starting subtest: syncobj-unused-fence [540.188277] Subtest syncobj-unused-fence: SUCCESS (0.005s) [541.935509] [116/132] (484s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move) [542.042740] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move [542.048079] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [542.176657] [117/132] (483s left) gem_set_tiling_vs_pwrite [542.314354] [118/132] (483s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [542.412206] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [542.418082] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [542.546510] [119/132] (483s left) vgem_basic (busy-fence) [542.586802] Starting subtest: busy-fence [542.589450] Subtest busy-fence: SUCCESS (0.000s) [542.672843] [120/132] (483s left) i915_pm_rpm (debugfs-read) [544.910296] Starting subtest: debugfs-read [546.075557] Subtest debugfs-read: SUCCESS (1.165s) [546.444862] [121/132] (479s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt) [546.551135] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt [551.117613] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.572s) [552.266517] [122/132] (474s left) perf_pmu (busy-start) [552.319082] Starting subtest: busy-start [552.321741] Starting dynamic subtest: rcs0 [554.868839] Dynamic subtest rcs0: SUCCESS (2.551s) [554.881505] Starting dynamic subtest: bcs0 [557.421226] Dynamic subtest bcs0: SUCCESS (2.549s) [557.431343] Starting dynamic subtest: vcs0 [560.859609] Dynamic subtest vcs0: SUCCESS (3.435s) [560.873291] Starting dynamic subtest: vcs1 [564.828082] Dynamic subtest vcs1: SUCCESS (3.965s) [564.840309] Starting dynamic subtest: vecs0 [568.797956] Dynamic subtest vecs0: SUCCESS (3.967s) [568.810588] Starting dynamic subtest: ccs0 [571.347219] Dynamic subtest ccs0: SUCCESS (2.548s) [571.350164] Subtest busy-start: SUCCESS (19.030s) [571.491936] [123/132] (454s left) kms_addfb_basic (size-max) [571.601064] Starting subtest: size-max [571.603142] Subtest size-max: SUCCESS (0.000s) [571.707871] [124/132] (454s left) kms_psr (fbc-pr-primary-blt) [571.796238] Starting subtest: fbc-pr-primary-blt [571.801097] Subtest fbc-pr-primary-blt: SKIP (0.000s) [571.892543] [125/132] (454s left) kms_color_pipeline (plane-ctm3x4) [571.996678] Starting subtest: plane-ctm3x4 [572.013081] Starting dynamic subtest: pipe-A-eDP-1 [572.700570] Dynamic subtest pipe-A-eDP-1: FAIL (0.291s) [573.390014] Starting dynamic subtest: pipe-B-eDP-1 [574.248280] Dynamic subtest pipe-B-eDP-1: FAIL (0.268s) [574.691407] Starting dynamic subtest: pipe-C-eDP-1 [575.590169] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s) [575.991683] Starting dynamic subtest: pipe-D-eDP-1 [576.890102] Dynamic subtest pipe-D-eDP-1: FAIL (0.224s) [576.893049] Subtest plane-ctm3x4: FAIL (4.500s) [577.368787] [126/132] (449s left) kms_chamelium_hpd (vga-hpd-without-ddc) [577.766098] Subtest vga-hpd-without-ddc: SKIP (0.000s) [578.538643] [127/132] (447s left) api_intel_allocator (standalone) [578.602307] Starting subtest: standalone [578.618700] Subtest standalone: SUCCESS (0.019s) [578.754272] [128/132] (447s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [578.851490] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [585.189110] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.339s) [585.335464] [129/132] (441s left) kms_big_fb (y-tiled-addfb) [585.452333] Starting subtest: y-tiled-addfb [585.457935] Subtest y-tiled-addfb: SKIP (0.000s) [585.592128] [130/132] (441s left) kms_prime (basic-crc-vgem) [585.680367] Starting subtest: basic-crc-vgem [585.682797] Starting dynamic subtest: second-to-first [586.614918] Dynamic subtest second-to-first: SUCCESS (0.939s) [586.617124] Subtest basic-crc-vgem: SUCCESS (0.940s) [586.733912] [131/132] (439s left) i915_hangman (engine-error-state-capture) [586.831087] Starting subtest: engine-error-state-capture [586.833786] Starting dynamic subtest: rcs0 [590.716017] Dynamic subtest rcs0: SUCCESS (3.884s) [590.732016] Starting dynamic subtest: bcs0 [596.794342] Dynamic subtest bcs0: SUCCESS (6.076s) [596.807331] Starting dynamic subtest: vcs0 [602.746633] Dynamic subtest vcs0: SUCCESS (5.950s) [602.759463] Starting dynamic subtest: vcs1 [607.739201] Dynamic subtest vcs1: SUCCESS (4.990s) [607.752142] Starting dynamic subtest: vecs0 [612.732822] Dynamic subtest vecs0: SUCCESS (4.991s) [612.745563] Starting dynamic subtest: ccs0 [617.785140] Dynamic subtest ccs0: SUCCESS (5.050s) [617.788279] Subtest engine-error-state-capture: SUCCESS (30.957s) [617.896753] [132/132] (408s left) kms_invalid_mode (bad-vsync-end) [617.996678] Starting subtest: bad-vsync-end [617.999155] Starting dynamic subtest: pipe-A-eDP-1 [618.293336] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s) [618.303296] Starting dynamic subtest: pipe-B-eDP-1 [618.305730] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [618.314983] Starting dynamic subtest: pipe-C-eDP-1 [618.317579] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [618.326571] Starting dynamic subtest: pipe-D-eDP-1 [618.329306] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [618.332009] Subtest bad-vsync-end: SUCCESS (0.301s) [619.158990] 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.