[56.635316] Initializing watchdogs
[56.635359]   /dev/watchdog0
[56.650244] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[56.654195] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.656248] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.659964] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[56.778272] Starting subtest: fbc-tiling-linear
[60.770791] Subtest fbc-tiling-linear: SUCCESS (3.939s)
[61.983125] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[62.051368] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[62.101893] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[62.175382] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[62.179617] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[62.265712] [004/156] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[62.350381] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[62.354508] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[62.446154] [005/156] (954s left) kms_force_connector_basic (prune-stale-modes)
[62.500623] Subtest prune-stale-modes: SKIP (0.000s)
[62.550725] [006/156] (954s left) perf_pmu (most-busy-check-all)
[62.591294] Starting subtest: most-busy-check-all
[62.593563] Starting dynamic subtest: rcs0
[64.253221] Dynamic subtest rcs0: SUCCESS (1.649s)
[64.264086] Starting dynamic subtest: bcs0
[66.238766] Dynamic subtest bcs0: SUCCESS (1.971s)
[66.250957] Starting dynamic subtest: vcs0
[68.222282] Dynamic subtest vcs0: SUCCESS (1.973s)
[68.233714] Starting dynamic subtest: vcs1
[70.206062] Dynamic subtest vcs1: SUCCESS (1.975s)
[70.217120] Starting dynamic subtest: vecs0
[72.254008] Dynamic subtest vecs0: SUCCESS (2.041s)
[72.263534] Starting dynamic subtest: ccs0
[74.237082] Dynamic subtest ccs0: SUCCESS (1.978s)
[74.239916] Subtest most-busy-check-all: SUCCESS (11.593s)
[74.341732] [007/156] (943s left) gem_ccs (large-ctrl-surf-copy)
[74.403647] Starting subtest: large-ctrl-surf-copy
[74.408305] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[74.525311] [008/156] (942s left) kms_plane_alpha_blend (coverage-7efc)
[74.607996] Starting subtest: coverage-7efc
[74.893505] Starting dynamic subtest: pipe-A-eDP-1
[75.495058] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.599s)
[75.523464] Starting dynamic subtest: pipe-D-eDP-1
[76.846193] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[76.848395] Subtest coverage-7efc: SUCCESS (2.235s)
[77.691558] [009/156] (939s left) gem_mmap_gtt (fault-concurrent)
[77.751323] Subtest fault-concurrent: SKIP (0.000s)
[77.810104] [010/156] (939s left) api_intel_allocator (gem-pool)
[77.864026] Starting subtest: gem-pool
[77.869308] Subtest gem-pool: SUCCESS (0.007s)
[77.983906] [011/156] (939s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[78.059458] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[78.063604] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[78.152890] [012/156] (939s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[78.222703] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[78.227183] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[78.292623] [013/156] (939s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[78.368668] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[78.370961] Starting dynamic subtest: pipe-A-eDP-1
[78.393088] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[78.402073] Starting dynamic subtest: pipe-B-eDP-1
[78.422719] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[78.430866] Starting dynamic subtest: pipe-C-eDP-1
[78.451959] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[78.459646] Starting dynamic subtest: pipe-D-eDP-1
[78.480857] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[78.485145] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[80.685363] [014/156] (936s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[80.773793] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[80.776141] Starting dynamic subtest: pipe-A-eDP-1
[80.798599] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[80.807987] Starting dynamic subtest: pipe-B-eDP-1
[80.829316] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[80.837107] Starting dynamic subtest: pipe-C-eDP-1
[80.859080] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[80.866498] Starting dynamic subtest: pipe-D-eDP-1
[80.889344] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[80.893927] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[83.027418] [015/156] (934s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[83.117666] Starting subtest: hdr-rgb565-draw-blt
[83.121944] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[83.237324] [016/156] (934s left) kms_pm_rpm (legacy-planes-dpms)
[85.366059] Starting subtest: legacy-planes-dpms
[85.368431] Starting dynamic subtest: plane-65
[93.675650] Dynamic subtest plane-65: SUCCESS (8.309s)
[93.687040] Starting dynamic subtest: plane-95
[101.966250] Dynamic subtest plane-95: SUCCESS (8.288s)
[101.977827] Starting dynamic subtest: plane-125
[110.171448] Dynamic subtest plane-125: SUCCESS (8.201s)
[110.182617] Starting dynamic subtest: plane-135
[118.469605] Dynamic subtest plane-135: SUCCESS (8.297s)
[118.472511] Subtest legacy-planes-dpms: SUCCESS (33.100s)
[118.858231] [017/156] (898s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[118.922497] Starting subtest: x-tiled-to-vebox-y-tiled
[118.927221] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[119.114063] [018/156] (898s left) sysfs_heartbeat_interval (invalid)
[119.166648] Starting subtest: invalid
[119.170630] Starting dynamic subtest: vecs0
[119.173279] Dynamic subtest vecs0: SUCCESS (0.000s)
[119.182906] Starting dynamic subtest: ccs0
[119.184957] Dynamic subtest ccs0: SUCCESS (0.000s)
[119.193273] Starting dynamic subtest: vcs0
[119.195209] Dynamic subtest vcs0: SUCCESS (0.000s)
[119.202689] Starting dynamic subtest: bcs0
[119.204655] Dynamic subtest bcs0: SUCCESS (0.000s)
[119.214855] Starting dynamic subtest: rcs0
[119.216753] Dynamic subtest rcs0: SUCCESS (0.000s)
[119.224807] Starting dynamic subtest: vcs1
[119.227277] Dynamic subtest vcs1: SUCCESS (0.000s)
[119.229437] Subtest invalid: SUCCESS (0.003s)
[119.273851] [019/156] (898s left) kms_frontbuffer_tracking (fbc-stridechange)
[119.355176] Starting subtest: fbc-stridechange
[122.587484] Subtest fbc-stridechange: SUCCESS (3.238s)
[123.759243] [020/156] (894s left) gem_ctx_param (basic-default)
[123.811790] Starting subtest: basic-default
[123.814358] Subtest basic-default: SUCCESS (0.000s)
[123.882788] [021/156] (894s left) kms_psr (fbc-pr-primary-mmap-gtt)
[123.957169] Starting subtest: fbc-pr-primary-mmap-gtt
[123.961705] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[124.036196] [022/156] (893s left) i915_pm_freq_mult (media-freq)
[124.094430] Starting subtest: media-freq
[124.096760] Starting dynamic subtest: gt0
[124.101169] Dynamic subtest gt0: SKIP (0.000s)
[124.110953] Starting dynamic subtest: gt1
[124.115071] Dynamic subtest gt1: SKIP (0.000s)
[124.119301] Subtest media-freq: SKIP (0.002s)
[124.198154] [023/156] (893s left) prime_busy (before-wait)
[124.248943] Starting subtest: before-wait
[124.251380] Starting dynamic subtest: rcs0
[124.281989] Dynamic subtest rcs0: SUCCESS (0.032s)
[124.292956] Starting dynamic subtest: bcs0
[124.331478] Dynamic subtest bcs0: SUCCESS (0.049s)
[124.339730] Starting dynamic subtest: vcs0
[124.370807] Dynamic subtest vcs0: SUCCESS (0.039s)
[124.378830] Starting dynamic subtest: vcs1
[124.409860] Dynamic subtest vcs1: SUCCESS (0.039s)
[124.417906] Starting dynamic subtest: vecs0
[124.449155] Dynamic subtest vecs0: SUCCESS (0.039s)
[124.457167] Starting dynamic subtest: ccs0
[124.488206] Dynamic subtest ccs0: SUCCESS (0.039s)
[124.596573] Subtest before-wait: SUCCESS (0.348s)
[124.679148] [024/156] (893s left) gem_exec_gttfill (engines)
[124.737104] Starting subtest: engines
[124.739576] Starting dynamic subtest: rcs0
[147.475430] Dynamic subtest rcs0: SUCCESS (22.737s)
[147.485862] Starting dynamic subtest: bcs0
[170.742563] Dynamic subtest bcs0: SUCCESS (23.262s)
[170.754402] Starting dynamic subtest: vcs0
[194.034233] Dynamic subtest vcs0: SUCCESS (23.289s)
[194.044787] Starting dynamic subtest: vcs1
[217.293156] Dynamic subtest vcs1: SUCCESS (23.256s)
[217.312390] Starting dynamic subtest: vecs0
[240.554497] Dynamic subtest vecs0: SUCCESS (23.257s)
[240.564451] Starting dynamic subtest: ccs0
[263.887728] Dynamic subtest ccs0: SUCCESS (23.331s)
[263.890652] Subtest engines: SUCCESS (139.138s)
[264.079207] [025/156] (754s left) syncobj_timeline (etime-multi-wait-all-submitted)
[264.179077] Starting subtest: etime-multi-wait-all-submitted
[264.281759] Subtest etime-multi-wait-all-submitted: SUCCESS (0.103s)
[264.358715] [026/156] (753s left) gem_ctx_param (get-priority-new-ctx)
[264.413801] Starting subtest: get-priority-new-ctx
[264.416299] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[264.532501] [027/156] (753s left) kms_big_fb (linear-32bpp-rotate-90)
[264.624280] Starting subtest: linear-32bpp-rotate-90
[264.642978] Subtest linear-32bpp-rotate-90: SKIP (0.018s)
[264.746605] [028/156] (753s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[264.793776] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[264.841262] [029/156] (753s left) gem_ctx_shared (disjoint-timelines)
[264.900910] Starting subtest: disjoint-timelines
[264.982648] Subtest disjoint-timelines: SUCCESS (0.082s)
[265.216482] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[265.220110] [030/156] (753s left) kms_psr (fbc-psr2-cursor-plane-move)
[265.296711] Starting subtest: fbc-psr2-cursor-plane-move
[265.299128] Starting dynamic subtest: eDP-1
[265.303641] Dynamic subtest eDP-1: SKIP (0.000s)
[265.308326] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[265.369152] [031/156] (752s left) gem_bad_reloc (negative-reloc-bltcopy)
[265.419032] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[265.487558] [032/156] (752s left) kms_chamelium_hpd (hdmi-hpd)
[265.847049] Subtest hdmi-hpd: SKIP (0.000s)
[266.631516] [033/156] (751s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[266.719251] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[266.723889] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[266.794955] [034/156] (751s left) gem_eio (wait-10ms)
[267.005434] Starting subtest: wait-10ms
[267.347789] Subtest wait-10ms: SUCCESS (0.344s)
[267.470787] [035/156] (751s left) kms_content_protection (legacy-hdcp14)
[267.588412] Starting subtest: legacy-hdcp14
[267.592394] Subtest legacy-hdcp14: SKIP (0.000s)
[267.696702] [036/156] (750s left) syncobj_wait (reset-signaled)
[267.760212] Starting subtest: reset-signaled
[267.761864] Subtest reset-signaled: SUCCESS (0.000s)
[267.864550] [037/156] (750s left) gem_ctx_isolation (dirty-switch)
[267.922542] Starting subtest: dirty-switch
[267.924525] Starting dynamic subtest: rcs0
[268.113241] Dynamic subtest rcs0: SUCCESS (0.190s)
[268.125193] Starting dynamic subtest: bcs0
[268.308450] Dynamic subtest bcs0: SUCCESS (0.195s)
[268.319921] Starting dynamic subtest: vcs0
[268.519593] Dynamic subtest vcs0: SUCCESS (0.211s)
[268.529828] Starting dynamic subtest: vcs1
[270.407101] Dynamic subtest vcs1: SUCCESS (1.886s)
[270.418635] Starting dynamic subtest: vecs0
[272.444733] Dynamic subtest vecs0: SUCCESS (2.037s)
[272.457135] Starting dynamic subtest: ccs0
[274.445296] Dynamic subtest ccs0: SUCCESS (2.000s)
[274.447653] Subtest dirty-switch: SUCCESS (6.522s)
[274.677170] [038/156] (743s left) gem_flink_basic (bad-open)
[274.720166] Starting subtest: bad-open
[274.722550] Subtest bad-open: SUCCESS (0.000s)
[274.828732] [039/156] (743s left) gem_caching (writes)
[274.892078] Subtest writes: SKIP (0.000s)
[274.964555] [040/156] (743s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[275.053305] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[275.055260] Starting dynamic subtest: pipe-A
[275.390234] Dynamic subtest pipe-A: SUCCESS (0.339s)
[275.400230] Starting dynamic subtest: pipe-B
[276.832506] Dynamic subtest pipe-B: SUCCESS (1.442s)
[276.843456] Starting dynamic subtest: pipe-C
[278.271495] Dynamic subtest pipe-C: SUCCESS (1.438s)
[278.282852] Starting dynamic subtest: pipe-D
[279.685097] Dynamic subtest pipe-D: SUCCESS (1.413s)
[279.687295] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.634s)
[280.872694] [041/156] (737s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[280.925957] Starting subtest: single-wait-all-for-submit-available-submitted
[280.928287] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[281.023823] [042/156] (737s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[281.105464] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[281.109601] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[281.192301] [043/156] (737s left) kms_pm_rpm (system-suspend-idle)
[283.432603] Starting subtest: system-suspend-idle
[302.339975] Subtest system-suspend-idle: SUCCESS (4.433s)
[302.741744] [044/156] (716s left) kms_atomic_transition (modeset-transition)
[302.827220] Starting subtest: modeset-transition
[302.829298] Starting dynamic subtest: 1x-outputs
[314.075107] Dynamic subtest 1x-outputs: SUCCESS (11.100s)
[314.077760] Subtest modeset-transition: SUCCESS (11.101s)
[314.945250] [045/156] (703s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[315.034665] Starting subtest: pipe-fbc-rte
[315.041611] Starting dynamic subtest: pipe-B-eDP-1
[320.530047] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.481s)
[320.537516] Subtest pipe-fbc-rte: SUCCESS (5.483s)
[322.825356] [046/156] (696s left) gem_spin_batch (resubmit-all)
[322.887430] Starting subtest: resubmit-all
[322.889678] Starting dynamic subtest: rcs0
[322.898132] Dynamic subtest rcs0: SUCCESS (0.011s)
[322.909310] Starting dynamic subtest: bcs0
[322.911490] Dynamic subtest bcs0: SUCCESS (0.002s)
[322.921030] Starting dynamic subtest: vcs0
[322.922925] Dynamic subtest vcs0: SUCCESS (0.002s)
[322.932440] Starting dynamic subtest: vcs1
[322.934249] Dynamic subtest vcs1: SUCCESS (0.002s)
[322.943388] Starting dynamic subtest: vecs0
[322.945226] Dynamic subtest vecs0: SUCCESS (0.002s)
[322.954649] Starting dynamic subtest: ccs0
[322.956562] Dynamic subtest ccs0: SUCCESS (0.002s)
[322.958484] Subtest resubmit-all: SUCCESS (0.023s)
[324.671495] [047/156] (694s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[324.746517] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[324.750738] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[324.839238] [048/156] (694s left) syncobj_basic (illegal-fd-to-handle)
[324.897969] Starting subtest: illegal-fd-to-handle
[324.899892] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[325.008303] [049/156] (694s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[325.103344] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[325.107746] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[325.197121] [050/156] (693s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[325.282567] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[326.394986] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.111s)
[326.530252] [051/156] (692s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[326.581569] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[326.687739] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[326.764050] [052/156] (692s left) api_intel_bb (misplaced-blitter)
[326.823068] Starting subtest: misplaced-blitter
[326.828181] Subtest misplaced-blitter: SUCCESS (0.007s)
[326.965797] [053/156] (692s left) kms_atomic_interruptible (universal-setplane-cursor)
[327.039051] Starting subtest: universal-setplane-cursor
[327.041381] Starting dynamic subtest: pipe-A-eDP-1
[335.183049] Dynamic subtest pipe-A-eDP-1: SUCCESS (8.132s)
[335.185441] Subtest universal-setplane-cursor: SUCCESS (8.134s)
[335.560331] [054/156] (683s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[335.651534] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[335.655903] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[335.750420] [055/156] (683s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[335.842579] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[335.846749] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[335.938958] [056/156] (683s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[336.018916] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[336.022842] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[336.117312] [057/156] (683s left) gem_pxp (create-protected-buffer)
[336.235232] Starting subtest: create-protected-buffer
[336.237915] Subtest create-protected-buffer: SUCCESS (0.000s)
[336.339409] [058/156] (683s left) gem_fence_thrash (bo-write-verify-threaded-x)
[336.375428] Starting subtest: bo-write-verify-threaded-x
[336.402993] Subtest bo-write-verify-threaded-x: SKIP (0.026s)
[336.460865] [059/156] (683s left) gen9_exec_parse (allowed-single)
[336.520753] Subtest allowed-single: SKIP (0.000s)
[336.588948] [060/156] (682s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[336.662196] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[336.665603] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[336.781719] [061/156] (682s left) gem_exec_balancer (bonded-semaphore)
[336.849244] Starting subtest: bonded-semaphore
[336.853176] Subtest bonded-semaphore: SKIP (0.000s)
[337.139729] [062/156] (682s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[337.216023] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[337.220155] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[337.302644] [063/156] (682s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[337.390069] Starting subtest: fbc-rgb565-draw-mmap-wc
[341.399533] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.013s)
[342.571759] [064/156] (677s left) kms_pwrite_crc (basic)
[342.649229] Starting subtest: basic
[343.305776] Subtest basic: SUCCESS (0.656s)
[344.213782] [065/156] (675s left) gem_flink_basic (bad-flink)
[344.268550] Starting subtest: bad-flink
[344.270538] Subtest bad-flink: SUCCESS (0.000s)
[344.365093] [066/156] (675s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[344.449293] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[344.453603] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[344.551289] [067/156] (675s left) gem_ctx_persistence (legacy-engines-persistence)
[344.615182] Starting subtest: legacy-engines-persistence
[344.617128] Starting dynamic subtest: render
[345.433873] Dynamic subtest render: SUCCESS (0.820s)
[345.447438] Starting dynamic subtest: bsd1
[346.185212] Dynamic subtest bsd1: SUCCESS (0.748s)
[346.198235] Starting dynamic subtest: bsd2
[346.963482] Dynamic subtest bsd2: SUCCESS (0.775s)
[346.977072] Starting dynamic subtest: blt
[347.733064] Dynamic subtest blt: SUCCESS (0.767s)
[347.746469] Starting dynamic subtest: vebox
[348.497138] Dynamic subtest vebox: SUCCESS (0.761s)
[348.499959] Subtest legacy-engines-persistence: SUCCESS (3.883s)
[348.581076] [068/156] (671s left) gen7_exec_parse (basic-allowed)
[348.636426] Subtest basic-allowed: SKIP (0.000s)
[348.747440] [069/156] (671s left) gem_exec_schedule (reorder-wide)
[348.937293] Subtest reorder-wide: SKIP (0.000s)
[349.121503] [070/156] (670s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[349.213922] Starting subtest: psrhdr-slowdraw
[349.217860] Subtest psrhdr-slowdraw: SKIP (0.000s)
[349.296261] [071/156] (670s left) perf (non-zero-reason)
[349.429534] Starting subtest: non-zero-reason
[349.431735] Starting dynamic subtest: 0-rcs0
[354.813130] Dynamic subtest 0-rcs0: SUCCESS (5.348s)
[354.828189] Starting dynamic subtest: 1-vecs0
[358.679844] Dynamic subtest 1-vecs0: SUCCESS (3.894s)
[358.682922] Subtest non-zero-reason: SUCCESS (9.244s)
[358.730494] [072/156] (661s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[358.826396] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[358.830781] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[358.944442] [073/156] (661s left) i915_drm_fdinfo (all-busy-check-all)
[358.988636] Starting subtest: all-busy-check-all
[358.993569] Subtest all-busy-check-all: SKIP (0.003s)
[359.063322] [074/156] (661s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[359.178016] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[359.182353] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[359.277259] [075/156] (660s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[359.356395] Starting subtest: compare-crc-sanitycheck-nv12
[359.359045] Starting dynamic subtest: pipe-A-eDP-1
[360.052085] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.697s)
[360.063445] Starting dynamic subtest: pipe-B-eDP-1
[361.393591] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.340s)
[361.404982] Starting dynamic subtest: pipe-C-eDP-1
[362.707659] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.312s)
[362.719408] Starting dynamic subtest: pipe-D-eDP-1
[363.893582] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.183s)
[363.895546] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.537s)
[364.744411] [076/156] (655s left) gem_exec_reloc (basic-write-gtt)
[364.799389] Subtest basic-write-gtt: SKIP (0.000s)
[364.869026] [077/156] (655s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[365.150508] Starting subtest: cursor-rapid-movement-max-size
[365.157000] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[365.281455] [078/156] (655s left) kms_color_pipeline (plane-lut1d)
[365.357883] Starting subtest: plane-lut1d
[365.481810] Starting dynamic subtest: pipe-A-plane-0
[365.649025] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[365.661892] Starting dynamic subtest: pipe-A-plane-1
[365.781414] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[365.794537] Starting dynamic subtest: pipe-A-plane-2
[365.914648] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[367.180480] Starting dynamic subtest: pipe-B-plane-0
[367.338235] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[367.348788] Starting dynamic subtest: pipe-B-plane-1
[367.471670] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[367.484409] Starting dynamic subtest: pipe-B-plane-2
[367.604757] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[368.758982] Starting dynamic subtest: pipe-C-plane-0
[368.906741] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[368.917078] Starting dynamic subtest: pipe-C-plane-1
[369.061847] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[369.072620] Starting dynamic subtest: pipe-C-plane-2
[369.217294] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[370.391364] Starting dynamic subtest: pipe-D-plane-0
[370.524929] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[370.535671] Starting dynamic subtest: pipe-D-plane-1
[370.658351] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[370.669333] Starting dynamic subtest: pipe-D-plane-2
[370.792002] Dynamic subtest pipe-D-plane-2: SUCCESS (0.132s)
[371.085473] Subtest plane-lut1d: SUCCESS (5.725s)
[371.969709] [079/156] (648s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[372.055071] Starting subtest: yf-tiled-16bpp-rotate-180
[372.059481] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[372.148846] [080/156] (648s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[372.229933] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[373.231041] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.000s)
[373.377815] [081/156] (647s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[373.462198] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[373.466468] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[373.574754] [082/156] (646s left) gem_exec_reloc (basic-wc-read)
[373.637993] Subtest basic-wc-read: SKIP (0.000s)
[373.705926] [083/156] (646s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[373.805659] Starting subtest: short-busy-flip-before-cursor-toggle
[373.809330] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[373.964079] [084/156] (646s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[374.036233] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[374.045636] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.008s)
[374.121910] [085/156] (646s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[374.210085] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[374.213555] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[374.311641] [086/156] (646s left) kms_draw_crc (draw-method-render)
[374.394052] Starting subtest: draw-method-render
[374.395885] Starting dynamic subtest: xrgb8888-untiled
[374.668975] Dynamic subtest xrgb8888-untiled: SUCCESS (0.277s)
[374.681433] Starting dynamic subtest: rgb565-untiled
[374.891492] Dynamic subtest rgb565-untiled: SUCCESS (0.220s)
[374.901423] Starting dynamic subtest: xrgb2101010-untiled
[375.113850] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[375.126546] Starting dynamic subtest: xbgr16161616f-untiled
[375.335949] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.220s)
[375.346828] Starting dynamic subtest: xrgb8888-xtiled
[375.458624] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[375.471560] Starting dynamic subtest: rgb565-xtiled
[375.569596] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[375.580012] Starting dynamic subtest: xrgb2101010-xtiled
[375.692137] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[375.702927] Starting dynamic subtest: xbgr16161616f-xtiled
[375.825124] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[375.838611] Starting dynamic subtest: xrgb8888-4tiled
[375.947465] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.120s)
[375.958899] Starting dynamic subtest: rgb565-4tiled
[376.069956] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[376.080683] Starting dynamic subtest: xrgb2101010-4tiled
[376.192055] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[376.202441] Starting dynamic subtest: xbgr16161616f-4tiled
[376.325483] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[376.327460] Subtest draw-method-render: SUCCESS (1.933s)
[376.431739] [087/156] (644s left) kms_cursor_crc (cursor-sliding-256x85)
[376.655294] Starting subtest: cursor-sliding-256x85
[376.697096] Subtest cursor-sliding-256x85: SKIP (0.040s)
[376.860684] [088/156] (643s left) kms_cursor_crc (cursor-onscreen-256x256)
[377.070243] Starting subtest: cursor-onscreen-256x256
[377.072293] Starting dynamic subtest: pipe-A-eDP-1
[379.172662] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.097s)
[379.202437] Starting dynamic subtest: pipe-D-eDP-1
[381.932110] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.738s)
[381.934518] Subtest cursor-onscreen-256x256: SUCCESS (4.861s)
[382.750838] [089/156] (637s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[382.825959] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[382.830343] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[382.928319] [090/156] (637s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[383.015816] Starting subtest: fbcpsr-slowdraw
[408.226036] Subtest fbcpsr-slowdraw: SUCCESS (25.216s)
[408.360891] [091/156] (612s left) kms_lease (cursor-implicit-plane)
[408.436067] Starting subtest: cursor-implicit-plane
[408.438285] Starting dynamic subtest: pipe-A-eDP-1
[408.883690] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.447s)
[408.894939] Starting dynamic subtest: pipe-B-eDP-1
[409.991900] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.105s)
[410.004423] Starting dynamic subtest: pipe-C-eDP-1
[411.106115] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[411.117258] Starting dynamic subtest: pipe-D-eDP-1
[412.228281] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.123s)
[412.230546] Subtest cursor-implicit-plane: SUCCESS (3.794s)
[413.094085] [092/156] (607s left) syncobj_wait (invalid-reset-zero-handles)
[413.140127] Starting subtest: invalid-reset-zero-handles
[413.142394] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[413.250539] [093/156] (607s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[413.330296] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[413.334250] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[413.444951] [094/156] (607s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[413.534483] Starting subtest: yf-tiled-64bpp-rotate-90
[413.539047] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[413.638649] [095/156] (607s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[413.725943] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[413.730512] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[413.811687] [096/156] (607s left) gem_exec_fence (long-history)
[414.246254] Starting subtest: long-history
[421.406315] Subtest long-history: SUCCESS (7.162s)
[422.809066] [097/156] (598s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[422.902524] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[430.202446] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.307s)
[430.325880] [098/156] (590s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[430.400277] Starting subtest: bad-rotation-90-yf-tiled-ccs
[430.402637] Starting dynamic subtest: pipe-A-eDP-1
[430.425230] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[430.438691] Starting dynamic subtest: pipe-B-eDP-1
[430.460459] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[430.471054] Starting dynamic subtest: pipe-C-eDP-1
[430.492973] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[430.502613] Starting dynamic subtest: pipe-D-eDP-1
[430.524396] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[430.528746] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[432.675551] [099/156] (588s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[432.777105] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[451.024746] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (18.247s)
[451.890520] [100/156] (569s left) kms_chamelium_audio (dp-audio-edid)
[452.280132] Subtest dp-audio-edid: SKIP (0.000s)
[453.112985] [101/156] (568s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[453.182333] Starting subtest: addfb25-yf-tiled-legacy
[453.184186] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[453.285915] [102/156] (568s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[453.358968] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[453.363000] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[453.447408] [103/156] (567s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[453.532333] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[453.536346] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[453.622884] [104/156] (567s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[453.698477] Starting subtest: fbc-2p-pri-indfb-multidraw
[453.702206] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[453.801640] [105/156] (567s left) kms_sequence (queue-busy)
[453.872352] Starting subtest: queue-busy
[453.874617] Starting dynamic subtest: pipe-A-eDP-1
[455.803417] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.931s)
[455.814675] Starting dynamic subtest: pipe-B-eDP-1
[458.443145] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.637s)
[458.455829] Starting dynamic subtest: pipe-C-eDP-1
[461.043337] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.600s)
[461.053968] Starting dynamic subtest: pipe-D-eDP-1
[463.665104] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.617s)
[463.667550] Subtest queue-busy: SUCCESS (9.791s)
[464.559761] [106/156] (556s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[464.632086] Starting subtest: plane-upscale-20x20-with-rotation
[464.634412] Starting dynamic subtest: pipe-A
[464.857480] Dynamic subtest pipe-A: SUCCESS (0.226s)
[464.868350] Starting dynamic subtest: pipe-B
[466.132708] Dynamic subtest pipe-B: SUCCESS (1.274s)
[466.143836] Starting dynamic subtest: pipe-C
[467.478557] Dynamic subtest pipe-C: SUCCESS (1.345s)
[467.488968] Starting dynamic subtest: pipe-D
[468.800353] Dynamic subtest pipe-D: SUCCESS (1.321s)
[468.802643] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.169s)
[469.984548] [107/156] (551s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[470.062578] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[470.066911] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[470.186230] [108/156] (551s left) gem_exec_reloc (basic-write-wc)
[470.239997] Subtest basic-write-wc: SKIP (0.000s)
[470.306430] [109/156] (551s left) syncobj_timeline (reset-signaled)
[470.367765] Starting subtest: reset-signaled
[470.370001] Subtest reset-signaled: SUCCESS (0.000s)
[470.492349] [110/156] (551s left) kms_psr (pr-cursor-plane-move)
[470.573100] Starting subtest: pr-cursor-plane-move
[470.577523] Subtest pr-cursor-plane-move: SKIP (0.000s)
[470.651465] [111/156] (551s left) syncobj_wait (invalid-wait-illegal-handle)
[470.713643] Starting subtest: invalid-wait-illegal-handle
[470.715781] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[470.799327] [112/156] (550s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[470.878343] Starting subtest: extended-pageflip-modeset-hang-oldfb
[470.880674] Starting dynamic subtest: pipe-A
[478.791222] Dynamic subtest pipe-A: SUCCESS (7.909s)
[478.802851] Starting dynamic subtest: pipe-D
[487.377839] Dynamic subtest pipe-D: SUCCESS (8.581s)
[487.380245] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.495s)
[488.236076] [113/156] (533s left) kms_psr (psr2-primary-blt)
[488.307228] Starting subtest: psr2-primary-blt
[488.309475] Starting dynamic subtest: eDP-1
[490.017395] Dynamic subtest eDP-1: SUCCESS (1.710s)
[490.019738] Subtest psr2-primary-blt: SUCCESS (1.711s)
[490.898992] [114/156] (530s left) gem_softpin (evict-snoop-interruptible)
[490.969778] Starting subtest: evict-snoop-interruptible
[490.974626] Subtest evict-snoop-interruptible: SKIP (0.000s)
[491.111760] [115/156] (530s left) kms_force_connector_basic (force-load-detect)
[491.169535] Subtest force-load-detect: SKIP (0.000s)
[491.226352] [116/156] (530s left) kms_chamelium_edid (hdmi-mode-timings)
[491.600264] Subtest hdmi-mode-timings: SKIP (0.000s)
[492.420331] [117/156] (529s left) syncobj_timeline (invalid-signal-zero-handles)
[492.473240] Starting subtest: invalid-signal-zero-handles
[492.475523] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[492.563468] [118/156] (529s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[492.651646] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[492.655766] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[492.752024] [119/156] (529s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[492.824232] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[492.828352] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[492.923192] [120/156] (529s left) gem_mmap_gtt (fault-concurrent-x)
[492.968276] Subtest fault-concurrent-X: SKIP (0.000s)
[493.028330] [121/156] (529s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[493.086542] Starting subtest: y-tiled-to-vebox-y-tiled
[493.090538] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[493.263191] [122/156] (528s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[493.346840] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[493.351115] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[493.447573] [123/156] (528s left) vgem_basic (debugfs)
[493.484805] Starting subtest: debugfs
[493.487114] Subtest debugfs: SUCCESS (0.001s)
[493.541451] [124/156] (528s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[493.610190] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[493.612503] Starting dynamic subtest: pipe-A-default-mode
[494.809125] Dynamic subtest pipe-A-default-mode: SKIP (1.195s)
[494.813538] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.197s)
[494.953482] [125/156] (527s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[495.031552] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[495.035701] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[495.130989] [126/156] (527s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[495.210475] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[495.214990] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[495.306648] [127/156] (527s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[495.382934] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[495.387051] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[495.482651] [128/156] (526s left) kms_vblank (wait-forked-hang)
[495.557286] Starting subtest: wait-forked-hang
[495.559582] Starting dynamic subtest: pipe-A-eDP-1
[522.387026] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.818s)
[522.402596] Starting dynamic subtest: pipe-D-eDP-1
[537.510149] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.124s)
[537.512839] Subtest wait-forked-hang: SUCCESS (41.946s)
[538.362968] [129/156] (484s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[538.446233] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[538.448565] Starting dynamic subtest: pipe-A-default-mode
[539.609784] Dynamic subtest pipe-A-default-mode: SKIP (1.162s)
[539.613665] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.163s)
[539.769926] [130/156] (482s left) gem_ctx_exec (basic-invalid-context)
[539.824746] Starting subtest: basic-invalid-context
[539.826822] Subtest basic-invalid-context: SUCCESS (0.004s)
[539.958532] [131/156] (482s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[540.047235] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[540.051585] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[540.131064] [132/156] (482s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[540.201225] Starting subtest: fbc-psr2-cursor-plane-update-sf
[540.203514] Starting dynamic subtest: pipe-A-eDP-1
[540.289963] Dynamic subtest pipe-A-eDP-1: SKIP (0.092s)
[540.300621] Starting dynamic subtest: pipe-B-eDP-1
[541.456583] Dynamic subtest pipe-B-eDP-1: SKIP (1.165s)
[541.461243] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.260s)
[542.740408] [133/156] (479s left) gem_ppgtt (blt-vs-render-ctxn)
[542.789855] Starting subtest: blt-vs-render-ctxN
[575.585606] Subtest blt-vs-render-ctxN: SUCCESS (32.796s)
[575.728821] [134/156] (446s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[575.836895] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[575.894546] [135/156] (446s left) gem_softpin (invalid)
[575.949741] Starting subtest: invalid
[575.957968] Subtest invalid: SUCCESS (0.007s)
[576.084008] [136/156] (446s left) gem_exec_capture (capture)
[576.149507] Starting subtest: capture
[576.151823] Starting dynamic subtest: rcs0-smem
[576.996050] Dynamic subtest rcs0-smem: SUCCESS (0.845s)
[577.009972] Starting dynamic subtest: bcs0-smem
[577.964879] Dynamic subtest bcs0-smem: SUCCESS (0.967s)
[577.976131] Starting dynamic subtest: vcs0-smem
[578.789104] Dynamic subtest vcs0-smem: SUCCESS (0.822s)
[578.800110] Starting dynamic subtest: vcs1-smem
[579.607368] Dynamic subtest vcs1-smem: SUCCESS (0.818s)
[579.618812] Starting dynamic subtest: vecs0-smem
[580.999878] Dynamic subtest vecs0-smem: FAIL (1.334s)
[581.011773] Starting dynamic subtest: ccs0-smem
[582.259725] Dynamic subtest ccs0-smem: SUCCESS (1.319s)
[582.262002] Subtest capture: FAIL (6.108s)
[582.384051] [137/156] (440s left) gem_tiled_blits (basic)
[582.437357] Subtest basic: SKIP (0.000s)
[582.515656] [138/156] (440s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[582.601432] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[589.884448] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.290s)
[590.012802] [139/156] (432s left) gem_mmap_offset (partial-remap)
[590.062735] Starting subtest: partial-remap
[590.065243] Starting dynamic subtest: smem0
[590.073874] Dynamic subtest smem0: SUCCESS (0.003s)
[590.076567] Subtest partial-remap: SUCCESS (0.005s)
[590.194554] [140/156] (432s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[590.277774] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[590.282274] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[590.371960] [141/156] (432s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[590.449790] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[590.453765] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[590.541548] [142/156] (432s left) i915_power (sanity)
[590.587418] Starting subtest: sanity
[594.633621] Subtest sanity: SUCCESS (4.040s)
[596.706271] [143/156] (426s left) gem_exec_schedule (preempt-queue)
[596.787442] Subtest preempt-queue: SKIP (0.000s)
[597.080688] [144/156] (426s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[597.185590] Starting subtest: cursorB-vs-flipA-legacy
[597.189942] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[597.330922] [145/156] (425s left) i915_pm_rps (reset)
[597.393450] Starting subtest: reset
[605.817938] Subtest reset: SUCCESS (8.422s)
[605.941868] [146/156] (417s left) kms_psr (fbc-psr-cursor-plane-onoff)
[606.015835] Starting subtest: fbc-psr-cursor-plane-onoff
[606.018192] Starting dynamic subtest: eDP-1
[606.022645] Dynamic subtest eDP-1: SKIP (0.000s)
[606.027186] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[606.105358] [147/156] (417s left) kms_setmode (clone-exclusive-crtc)
[606.162803] Starting subtest: clone-exclusive-crtc
[606.169180] Subtest clone-exclusive-crtc: SKIP (0.000s)
[606.277723] [148/156] (417s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[606.355741] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[613.054386] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.701s)
[613.190527] [149/156] (410s left) kms_flip (flip-vs-rmfb)
[613.261587] Starting subtest: flip-vs-rmfb
[613.263908] Starting dynamic subtest: A-eDP1
[621.960954] Dynamic subtest A-eDP1: SUCCESS (8.701s)
[621.971796] Starting dynamic subtest: B-eDP1
[630.598101] Dynamic subtest B-eDP1: SUCCESS (8.634s)
[630.610911] Starting dynamic subtest: C-eDP1
[639.212234] Dynamic subtest C-eDP1: SUCCESS (8.611s)
[639.224729] Starting dynamic subtest: D-eDP1
[647.851498] Dynamic subtest D-eDP1: SUCCESS (8.636s)
[647.856064] Subtest flip-vs-rmfb: SUCCESS (34.591s)
[649.040720] [150/156] (374s left) gem_exec_whisper (basic-contexts-all)
[649.107547] Starting subtest: basic-contexts-all
[659.876776] Subtest basic-contexts-all: SUCCESS (10.765s)
[660.074470] [151/156] (363s left) dmabuf_sync_file (export-basic)
[660.122713] Starting subtest: export-basic
[660.124957] Subtest export-basic: SUCCESS (0.001s)
[660.180020] [152/156] (363s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[660.265085] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[660.269320] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[660.368152] [153/156] (363s left) gem_sync (basic-each)
[660.444102] Starting subtest: basic-each
[662.753564] Subtest basic-each: SUCCESS (2.320s)
[662.938672] [154/156] (360s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[663.014001] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[663.016318] Starting dynamic subtest: pipe-A-eDP-1
[663.041836] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[663.055357] Starting dynamic subtest: pipe-B-eDP-1
[663.079220] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[663.089561] Starting dynamic subtest: pipe-C-eDP-1
[663.113098] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[663.122951] Starting dynamic subtest: pipe-D-eDP-1
[663.146807] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[663.151233] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[665.308718] [155/156] (358s left) syncobj_wait (multi-wait-for-submit-submitted)
[665.371646] Starting subtest: multi-wait-for-submit-submitted
[665.480034] Subtest multi-wait-for-submit-submitted: SUCCESS (0.108s)
[665.559985] [156/156] (358s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[665.655454] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[665.659828] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[665.760284] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: kms_plane_alpha_blend
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: i915_pm_freq_mult
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: gem_exec_gttfill
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_ctx_param
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_bad_reloc
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: kms_content_protection
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_ctx_isolation
results: parsing output: 37/ for test: gem_flink_basic
results: parsing output: 38/ for test: gem_caching
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_atomic_interruptible
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_fence_thrash
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_pwrite_crc
results: parsing output: 64/ for test: gem_flink_basic
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_sequence
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_softpin
results: parsing output: 114/ for test: kms_force_connector_basic
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: vgem_basic
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: gem_ppgtt
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: gem_exec_capture
results: parsing output: 136/ for test: gem_tiled_blits
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_power
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: i915_pm_rps
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_setmode
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_flip
results: parsing output: 149/ for test: gem_exec_whisper
results: parsing output: 150/ for test: dmabuf_sync_file
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_sync
results: parsing output: 153/ for test: kms_ccs
results: parsing output: 154/ for test: syncobj_wait
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.