[60.331372] Initializing watchdogs
[60.331422]   /dev/watchdog0
[60.346131] [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.350815] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.353207] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.356702] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[60.494745] Starting subtest: fbc-tiling-linear
[64.298074] Subtest fbc-tiling-linear: SUCCESS (3.787s)
[65.452914] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[65.508648] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[65.570606] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[65.670628] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[65.675052] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[65.777018] [004/156] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[65.884831] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[65.889121] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[65.980095] [005/156] (955s left) kms_force_connector_basic (prune-stale-modes)
[66.050940] Starting subtest: prune-stale-modes
[66.493057] Subtest prune-stale-modes: SUCCESS (0.441s)
[66.624658] [006/156] (954s left) perf_pmu (most-busy-check-all)
[66.665296] Starting subtest: most-busy-check-all
[66.667162] Starting dynamic subtest: rcs0
[68.300855] Dynamic subtest rcs0: SUCCESS (1.631s)
[68.312634] Starting dynamic subtest: bcs0
[70.283061] Dynamic subtest bcs0: SUCCESS (1.976s)
[70.294932] Starting dynamic subtest: vcs0
[72.329059] Dynamic subtest vcs0: SUCCESS (2.041s)
[72.340985] Starting dynamic subtest: vcs1
[74.313200] Dynamic subtest vcs1: SUCCESS (1.981s)
[74.324080] Starting dynamic subtest: vecs0
[76.298644] Dynamic subtest vecs0: SUCCESS (1.983s)
[76.309695] Starting dynamic subtest: ccs0
[78.284969] Dynamic subtest ccs0: SUCCESS (1.983s)
[78.287999] Subtest most-busy-check-all: SUCCESS (11.601s)
[78.410712] [007/156] (942s left) gem_ccs (large-ctrl-surf-copy)
[78.475044] Starting subtest: large-ctrl-surf-copy
[78.480149] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[78.590838] [008/156] (942s left) kms_plane_alpha_blend (coverage-7efc)
[78.693714] Starting subtest: coverage-7efc
[78.997480] Starting dynamic subtest: pipe-A-eDP-1
[79.573249] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.575s)
[79.613443] Starting dynamic subtest: pipe-D-eDP-1
[80.871643] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.267s)
[80.874045] Subtest coverage-7efc: SUCCESS (2.179s)
[81.685038] [009/156] (939s left) gem_mmap_gtt (fault-concurrent)
[81.745045] Subtest fault-concurrent: SKIP (0.000s)
[81.813967] [010/156] (939s left) api_intel_allocator (gem-pool)
[81.864835] Starting subtest: gem-pool
[81.870861] Subtest gem-pool: SUCCESS (0.008s)
[82.005196] [011/156] (939s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[82.108058] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[82.112160] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[82.194351] [012/156] (939s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[82.292473] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[82.297085] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[82.360675] [013/156] (938s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[82.457123] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[82.459430] Starting dynamic subtest: pipe-A-eDP-1
[82.483187] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.493995] Starting dynamic subtest: pipe-B-eDP-1
[82.516178] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[82.524439] Starting dynamic subtest: pipe-C-eDP-1
[82.545314] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[82.552900] Starting dynamic subtest: pipe-D-eDP-1
[82.573692] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[82.577628] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[84.688983] [014/156] (936s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[84.781041] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[84.783292] Starting dynamic subtest: pipe-A-eDP-1
[84.805151] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[84.814635] Starting dynamic subtest: pipe-B-eDP-1
[84.835371] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[84.842918] Starting dynamic subtest: pipe-C-eDP-1
[84.864666] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[84.872109] Starting dynamic subtest: pipe-D-eDP-1
[84.893770] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[84.898206] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[87.016134] [015/156] (934s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[87.112725] Starting subtest: hdr-rgb565-draw-blt
[87.117229] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[87.206936] [016/156] (934s left) kms_pm_rpm (legacy-planes-dpms)
[89.479981] Starting subtest: legacy-planes-dpms
[89.482650] Starting dynamic subtest: plane-65
[97.796516] Dynamic subtest plane-65: SUCCESS (8.319s)
[97.807780] Starting dynamic subtest: plane-95
[106.452085] Dynamic subtest plane-95: SUCCESS (8.653s)
[106.464763] Starting dynamic subtest: plane-125
[114.881914] Dynamic subtest plane-125: SUCCESS (8.427s)
[114.895439] Starting dynamic subtest: plane-135
[123.504605] Dynamic subtest plane-135: SUCCESS (8.621s)
[123.507213] Subtest legacy-planes-dpms: SUCCESS (34.023s)
[123.891394] [017/156] (897s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[123.950112] Starting subtest: x-tiled-to-vebox-y-tiled
[123.954301] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[124.124745] [018/156] (897s left) sysfs_heartbeat_interval (invalid)
[124.181528] Starting subtest: invalid
[124.183947] Starting dynamic subtest: vecs0
[124.186292] Dynamic subtest vecs0: SUCCESS (0.000s)
[124.196261] Starting dynamic subtest: ccs0
[124.198172] Dynamic subtest ccs0: SUCCESS (0.000s)
[124.205766] Starting dynamic subtest: vcs0
[124.207664] Dynamic subtest vcs0: SUCCESS (0.000s)
[124.215405] Starting dynamic subtest: bcs0
[124.217314] Dynamic subtest bcs0: SUCCESS (0.000s)
[124.224994] Starting dynamic subtest: rcs0
[124.227592] Dynamic subtest rcs0: SUCCESS (0.000s)
[124.235651] Starting dynamic subtest: vcs1
[124.237925] Dynamic subtest vcs1: SUCCESS (0.000s)
[124.239862] Subtest invalid: SUCCESS (0.003s)
[124.281941] [019/156] (897s left) kms_frontbuffer_tracking (fbc-stridechange)
[124.389049] Starting subtest: fbc-stridechange
[127.594078] Subtest fbc-stridechange: SUCCESS (3.210s)
[128.720053] [020/156] (892s left) gem_ctx_param (basic-default)
[128.784097] Starting subtest: basic-default
[128.786440] Subtest basic-default: SUCCESS (0.000s)
[128.859232] [021/156] (892s left) kms_psr (fbc-pr-primary-mmap-gtt)
[128.959361] Starting subtest: fbc-pr-primary-mmap-gtt
[128.963840] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[129.045242] [022/156] (892s left) i915_pm_freq_mult (media-freq)
[129.104043] Starting subtest: media-freq
[129.106041] Starting dynamic subtest: gt0
[129.110193] Dynamic subtest gt0: SKIP (0.000s)
[129.120141] Starting dynamic subtest: gt1
[129.124233] Dynamic subtest gt1: SKIP (0.000s)
[129.128400] Subtest media-freq: SKIP (0.001s)
[129.212849] [023/156] (892s left) prime_busy (before-wait)
[129.267036] Starting subtest: before-wait
[129.269359] Starting dynamic subtest: rcs0
[129.301259] Dynamic subtest rcs0: SUCCESS (0.032s)
[129.310426] Starting dynamic subtest: bcs0
[129.359747] Dynamic subtest bcs0: SUCCESS (0.058s)
[129.368417] Starting dynamic subtest: vcs0
[129.400688] Dynamic subtest vcs0: SUCCESS (0.041s)
[129.411232] Starting dynamic subtest: vcs1
[129.441594] Dynamic subtest vcs1: SUCCESS (0.041s)
[129.450605] Starting dynamic subtest: vecs0
[129.481156] Dynamic subtest vecs0: SUCCESS (0.039s)
[129.489579] Starting dynamic subtest: ccs0
[129.519570] Dynamic subtest ccs0: SUCCESS (0.038s)
[129.629351] Subtest before-wait: SUCCESS (0.361s)
[129.711619] [024/156] (892s left) gem_exec_gttfill (engines)
[129.784891] Starting subtest: engines
[129.787181] Starting dynamic subtest: rcs0
[152.574359] Dynamic subtest rcs0: SUCCESS (22.788s)
[152.584268] Starting dynamic subtest: bcs0
[175.916686] Dynamic subtest bcs0: SUCCESS (23.342s)
[175.926606] Starting dynamic subtest: vcs0
[199.174359] Dynamic subtest vcs0: SUCCESS (23.256s)
[199.183605] Starting dynamic subtest: vcs1
[222.490098] Dynamic subtest vcs1: SUCCESS (23.314s)
[222.500186] Starting dynamic subtest: vecs0
[245.804261] Dynamic subtest vecs0: SUCCESS (23.312s)
[245.816041] Starting dynamic subtest: ccs0
[269.094990] Dynamic subtest ccs0: SUCCESS (23.289s)
[269.097327] Subtest engines: SUCCESS (139.306s)
[269.280518] [025/156] (752s left) syncobj_timeline (etime-multi-wait-all-submitted)
[269.377997] Starting subtest: etime-multi-wait-all-submitted
[269.486310] Subtest etime-multi-wait-all-submitted: SUCCESS (0.106s)
[269.585490] [026/156] (752s left) gem_ctx_param (get-priority-new-ctx)
[269.640847] Starting subtest: get-priority-new-ctx
[269.643654] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[269.753374] [027/156] (752s left) kms_big_fb (linear-32bpp-rotate-90)
[269.871326] Starting subtest: linear-32bpp-rotate-90
[269.894611] Subtest linear-32bpp-rotate-90: SKIP (0.020s)
[270.016443] [028/156] (751s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[270.063326] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[270.100770] [029/156] (751s left) gem_ctx_shared (disjoint-timelines)
[270.160253] Starting subtest: disjoint-timelines
[270.240212] Subtest disjoint-timelines: SUCCESS (0.083s)
[270.470984] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[270.474385] [030/156] (751s left) kms_psr (fbc-psr2-cursor-plane-move)
[270.561440] Starting subtest: fbc-psr2-cursor-plane-move
[270.563785] Starting dynamic subtest: eDP-1
[270.568050] Dynamic subtest eDP-1: SKIP (0.000s)
[270.572279] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[270.649631] [031/156] (751s left) gem_bad_reloc (negative-reloc-bltcopy)
[270.712404] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[270.785111] [032/156] (751s left) kms_chamelium_hpd (hdmi-hpd)
[271.170720] Subtest hdmi-hpd: SKIP (0.000s)
[271.965668] [033/156] (750s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[272.077078] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[272.081554] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[272.150345] [034/156] (750s left) gem_eio (wait-10ms)
[272.391073] Starting subtest: wait-10ms
[272.749224] Subtest wait-10ms: SUCCESS (0.358s)
[272.879711] [035/156] (749s left) kms_content_protection (legacy-hdcp14)
[273.025551] Starting subtest: legacy-hdcp14
[273.029540] Subtest legacy-hdcp14: SKIP (0.000s)
[273.140452] [036/156] (749s left) syncobj_wait (reset-signaled)
[273.187962] Starting subtest: reset-signaled
[273.189556] Subtest reset-signaled: SUCCESS (0.000s)
[273.301597] [037/156] (748s left) gem_ctx_isolation (dirty-switch)
[273.371340] Starting subtest: dirty-switch
[273.373199] Starting dynamic subtest: rcs0
[273.582592] Dynamic subtest rcs0: SUCCESS (0.211s)
[273.591954] Starting dynamic subtest: bcs0
[273.796080] Dynamic subtest bcs0: SUCCESS (0.213s)
[273.806805] Starting dynamic subtest: vcs0
[274.005547] Dynamic subtest vcs0: SUCCESS (0.209s)
[274.014919] Starting dynamic subtest: vcs1
[275.454997] Dynamic subtest vcs1: SUCCESS (1.449s)
[275.466976] Starting dynamic subtest: vecs0
[277.530224] Dynamic subtest vecs0: SUCCESS (2.075s)
[277.545365] Starting dynamic subtest: ccs0
[279.527416] Dynamic subtest ccs0: SUCCESS (1.997s)
[279.529806] Subtest dirty-switch: SUCCESS (6.156s)
[279.751866] [038/156] (742s left) gem_flink_basic (bad-open)
[279.814606] Starting subtest: bad-open
[279.816850] Subtest bad-open: SUCCESS (0.000s)
[279.919815] [039/156] (742s left) gem_caching (writes)
[279.985797] Subtest writes: SKIP (0.000s)
[280.056185] [040/156] (742s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[280.148229] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[280.150526] Starting dynamic subtest: pipe-A
[280.485544] Dynamic subtest pipe-A: SUCCESS (0.339s)
[280.497287] Starting dynamic subtest: pipe-B
[281.938879] Dynamic subtest pipe-B: SUCCESS (1.453s)
[281.955585] Starting dynamic subtest: pipe-C
[283.358627] Dynamic subtest pipe-C: SUCCESS (1.419s)
[283.368343] Starting dynamic subtest: pipe-D
[284.758913] Dynamic subtest pipe-D: SUCCESS (1.400s)
[284.761308] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.613s)
[285.987789] [041/156] (736s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[286.040302] Starting subtest: single-wait-all-for-submit-available-submitted
[286.042503] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[286.159940] [042/156] (736s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[286.263094] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[286.267428] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[286.355256] [043/156] (736s left) kms_pm_rpm (system-suspend-idle)
[288.507530] Starting subtest: system-suspend-idle
[308.573670] Subtest system-suspend-idle: SUCCESS (4.742s)
[308.983993] [044/156] (713s left) kms_atomic_transition (modeset-transition)
[309.085642] Starting subtest: modeset-transition
[309.087931] Starting dynamic subtest: 1x-outputs
[320.014194] Dynamic subtest 1x-outputs: SUCCESS (10.826s)
[320.016507] Subtest modeset-transition: SUCCESS (10.827s)
[320.864878] [045/156] (701s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[320.970621] Starting subtest: pipe-fbc-rte
[320.977429] Starting dynamic subtest: pipe-B-eDP-1
[326.388282] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.408s)
[326.396298] Subtest pipe-fbc-rte: SUCCESS (5.410s)
[328.713027] [046/156] (693s left) gem_spin_batch (resubmit-all)
[328.780295] Starting subtest: resubmit-all
[328.782684] Starting dynamic subtest: rcs0
[328.795342] Dynamic subtest rcs0: SUCCESS (0.015s)
[328.805750] Starting dynamic subtest: bcs0
[328.808044] Dynamic subtest bcs0: SUCCESS (0.002s)
[328.818297] Starting dynamic subtest: vcs0
[328.820474] Dynamic subtest vcs0: SUCCESS (0.002s)
[328.830384] Starting dynamic subtest: vcs1
[328.832577] Dynamic subtest vcs1: SUCCESS (0.002s)
[328.842741] Starting dynamic subtest: vecs0
[328.844981] Dynamic subtest vecs0: SUCCESS (0.002s)
[328.855261] Starting dynamic subtest: ccs0
[328.857521] Dynamic subtest ccs0: SUCCESS (0.002s)
[328.859726] Subtest resubmit-all: SUCCESS (0.027s)
[330.601779] [047/156] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[330.700831] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[330.705317] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[330.804914] [048/156] (691s left) syncobj_basic (illegal-fd-to-handle)
[330.850948] Starting subtest: illegal-fd-to-handle
[330.852864] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[330.987496] [049/156] (691s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[331.088898] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[331.093436] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[331.185948] [050/156] (691s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[331.287739] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[332.434896] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.145s)
[332.555014] [051/156] (690s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[332.619619] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[332.725518] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[332.824267] [052/156] (690s left) api_intel_bb (misplaced-blitter)
[332.884734] Starting subtest: misplaced-blitter
[332.889846] Subtest misplaced-blitter: SUCCESS (0.007s)
[333.024554] [053/156] (689s left) kms_atomic_interruptible (universal-setplane-cursor)
[333.136057] Starting subtest: universal-setplane-cursor
[333.138386] Starting dynamic subtest: pipe-A-eDP-1
[340.560006] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.420s)
[340.562335] Subtest universal-setplane-cursor: SUCCESS (7.421s)
[341.407315] [054/156] (681s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[341.510916] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[341.515326] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[341.622234] [055/156] (681s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[341.721197] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[341.725461] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[341.849143] [056/156] (681s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[341.942122] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[341.946535] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[342.027208] [057/156] (681s left) gem_pxp (create-protected-buffer)
[342.161127] Starting subtest: create-protected-buffer
[342.163677] Subtest create-protected-buffer: SUCCESS (0.000s)
[342.284562] [058/156] (680s left) gem_fence_thrash (bo-write-verify-threaded-x)
[342.325364] Starting subtest: bo-write-verify-threaded-x
[342.348982] Subtest bo-write-verify-threaded-x: SKIP (0.021s)
[342.411385] [059/156] (680s left) gen9_exec_parse (allowed-single)
[342.467342] Subtest allowed-single: SKIP (0.000s)
[342.533683] [060/156] (680s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[342.625514] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[342.629047] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[342.725550] [061/156] (680s left) gem_exec_balancer (bonded-semaphore)
[342.777683] Starting subtest: bonded-semaphore
[342.781692] Subtest bonded-semaphore: SKIP (0.000s)
[343.084500] [062/156] (680s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[343.186575] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[343.190896] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[343.263241] [063/156] (680s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[343.355159] Starting subtest: fbc-rgb565-draw-mmap-wc
[347.378036] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.029s)
[348.518812] [064/156] (674s left) kms_pwrite_crc (basic)
[348.615414] Starting subtest: basic
[349.298745] Subtest basic: SUCCESS (0.684s)
[350.204971] [065/156] (673s left) gem_flink_basic (bad-flink)
[350.267737] Starting subtest: bad-flink
[350.269802] Subtest bad-flink: SUCCESS (0.000s)
[350.392059] [066/156] (673s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[350.489346] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[350.493553] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[350.583624] [067/156] (673s left) gem_ctx_persistence (legacy-engines-persistence)
[350.650060] Starting subtest: legacy-engines-persistence
[350.652434] Starting dynamic subtest: render
[351.478283] Dynamic subtest render: SUCCESS (0.830s)
[351.493761] Starting dynamic subtest: bsd1
[352.241520] Dynamic subtest bsd1: SUCCESS (0.760s)
[352.257079] Starting dynamic subtest: bsd2
[353.013326] Dynamic subtest bsd2: SUCCESS (0.769s)
[353.026515] Starting dynamic subtest: blt
[353.784296] Dynamic subtest blt: SUCCESS (0.768s)
[353.797401] Starting dynamic subtest: vebox
[354.564043] Dynamic subtest vebox: SUCCESS (0.776s)
[354.567049] Subtest legacy-engines-persistence: SUCCESS (3.917s)
[354.646243] [068/156] (669s left) gen7_exec_parse (basic-allowed)
[354.703773] Subtest basic-allowed: SKIP (0.000s)
[354.787964] [069/156] (668s left) gem_exec_schedule (reorder-wide)
[354.970738] Subtest reorder-wide: SKIP (0.000s)
[355.152074] [070/156] (668s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[355.264064] Starting subtest: psrhdr-slowdraw
[355.268487] Subtest psrhdr-slowdraw: SKIP (0.000s)
[355.366713] [071/156] (668s left) perf (non-zero-reason)
[355.513829] Starting subtest: non-zero-reason
[355.515761] Starting dynamic subtest: 0-rcs0
[360.983005] Dynamic subtest 0-rcs0: SUCCESS (5.467s)
[361.001133] Starting dynamic subtest: 1-vecs0
[364.921078] Dynamic subtest 1-vecs0: SUCCESS (3.937s)
[364.924124] Subtest non-zero-reason: SUCCESS (9.405s)
[364.978582] [072/156] (658s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[365.083887] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[365.087993] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[365.183457] [073/156] (658s left) i915_drm_fdinfo (all-busy-check-all)
[365.227815] Starting subtest: all-busy-check-all
[365.235513] Subtest all-busy-check-all: SKIP (0.004s)
[365.310148] [074/156] (658s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[365.435936] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[365.440458] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[365.540944] [075/156] (658s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[365.626183] Starting subtest: compare-crc-sanitycheck-nv12
[365.628034] Starting dynamic subtest: pipe-A-eDP-1
[366.302428] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.675s)
[366.314598] Starting dynamic subtest: pipe-B-eDP-1
[367.668736] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.359s)
[367.680293] Starting dynamic subtest: pipe-C-eDP-1
[368.912544] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.248s)
[368.923418] Starting dynamic subtest: pipe-D-eDP-1
[370.192935] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.280s)
[370.195207] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.568s)
[371.009673] [076/156] (652s left) gem_exec_reloc (basic-write-gtt)
[371.068283] Subtest basic-write-gtt: SKIP (0.000s)
[371.147091] [077/156] (652s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[371.447090] Starting subtest: cursor-rapid-movement-max-size
[371.453513] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[371.574417] [078/156] (652s left) kms_color_pipeline (plane-lut1d)
[371.669991] Starting subtest: plane-lut1d
[371.805385] Starting dynamic subtest: pipe-A-plane-0
[371.982353] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[371.992860] Starting dynamic subtest: pipe-A-plane-1
[372.150794] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[372.163293] Starting dynamic subtest: pipe-A-plane-2
[372.315577] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[373.583435] Starting dynamic subtest: pipe-B-plane-0
[373.739626] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[373.753971] Starting dynamic subtest: pipe-B-plane-1
[373.906358] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[373.917454] Starting dynamic subtest: pipe-B-plane-2
[374.085183] Dynamic subtest pipe-B-plane-2: SUCCESS (0.177s)
[375.249561] Starting dynamic subtest: pipe-C-plane-0
[375.405988] Dynamic subtest pipe-C-plane-0: SUCCESS (0.166s)
[375.417468] Starting dynamic subtest: pipe-C-plane-1
[375.561598] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[375.572768] Starting dynamic subtest: pipe-C-plane-2
[375.717781] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[376.965284] Starting dynamic subtest: pipe-D-plane-0
[377.111243] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[377.122434] Starting dynamic subtest: pipe-D-plane-1
[377.279479] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[377.290877] Starting dynamic subtest: pipe-D-plane-2
[377.423806] Dynamic subtest pipe-D-plane-2: SUCCESS (0.143s)
[377.715721] Subtest plane-lut1d: SUCCESS (6.043s)
[378.587953] [079/156] (645s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[378.687260] Starting subtest: yf-tiled-16bpp-rotate-180
[378.691888] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[378.839995] [080/156] (645s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[378.932369] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[379.933330] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.000s)
[380.058141] [081/156] (644s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[380.154298] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[380.158205] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[380.271004] [082/156] (644s left) gem_exec_reloc (basic-wc-read)
[380.333174] Subtest basic-wc-read: SKIP (0.000s)
[380.409933] [083/156] (643s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[380.545158] Starting subtest: short-busy-flip-before-cursor-toggle
[380.549114] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[380.692318] [084/156] (643s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[380.787353] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[380.800051] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[380.900280] [085/156] (643s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[380.997021] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[381.000597] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[381.120761] [086/156] (643s left) kms_draw_crc (draw-method-render)
[381.212587] Starting subtest: draw-method-render
[381.214424] Starting dynamic subtest: xrgb8888-untiled
[381.467020] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[381.478346] Starting dynamic subtest: rgb565-untiled
[381.689320] Dynamic subtest rgb565-untiled: SUCCESS (0.221s)
[381.699915] Starting dynamic subtest: xrgb2101010-untiled
[381.922965] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.233s)
[381.933783] Starting dynamic subtest: xbgr16161616f-untiled
[382.178323] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.255s)
[382.189516] Starting dynamic subtest: xrgb8888-xtiled
[382.311881] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[382.322357] Starting dynamic subtest: rgb565-xtiled
[382.434378] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[382.447622] Starting dynamic subtest: xrgb2101010-xtiled
[382.556407] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[382.567516] Starting dynamic subtest: xbgr16161616f-xtiled
[382.689849] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[382.700806] Starting dynamic subtest: xrgb8888-4tiled
[382.823500] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[382.833837] Starting dynamic subtest: rgb565-4tiled
[382.934587] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[382.944686] Starting dynamic subtest: xrgb2101010-4tiled
[383.056539] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s)
[383.067245] Starting dynamic subtest: xbgr16161616f-4tiled
[383.190038] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[383.191957] Subtest draw-method-render: SUCCESS (1.978s)
[383.296114] [087/156] (641s left) kms_cursor_crc (cursor-sliding-256x85)
[383.549411] Starting subtest: cursor-sliding-256x85
[383.595253] Subtest cursor-sliding-256x85: SKIP (0.044s)
[383.758463] [088/156] (640s left) kms_cursor_crc (cursor-onscreen-256x256)
[384.002680] Starting subtest: cursor-onscreen-256x256
[384.007216] Starting dynamic subtest: pipe-A-eDP-1
[386.127739] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.119s)
[386.156268] Starting dynamic subtest: pipe-D-eDP-1
[388.928556] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.777s)
[388.930750] Subtest cursor-onscreen-256x256: SUCCESS (4.922s)
[389.797992] [089/156] (634s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[389.899192] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[389.903336] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[390.010620] [090/156] (634s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[390.111818] Starting subtest: fbcpsr-slowdraw
[415.189034] Subtest fbcpsr-slowdraw: SUCCESS (25.081s)
[415.315125] [091/156] (609s left) kms_lease (cursor-implicit-plane)
[415.414298] Starting subtest: cursor-implicit-plane
[415.416637] Starting dynamic subtest: pipe-A-eDP-1
[415.863459] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.448s)
[415.874120] Starting dynamic subtest: pipe-B-eDP-1
[416.982832] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.118s)
[416.994017] Starting dynamic subtest: pipe-C-eDP-1
[418.078741] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.094s)
[418.091860] Starting dynamic subtest: pipe-D-eDP-1
[419.171818] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.092s)
[419.173727] Subtest cursor-implicit-plane: SUCCESS (3.758s)
[419.980496] [092/156] (604s left) syncobj_wait (invalid-reset-zero-handles)
[420.047900] Starting subtest: invalid-reset-zero-handles
[420.049836] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[420.168050] [093/156] (604s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[420.268866] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[420.273027] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[420.366908] [094/156] (604s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[420.457637] Starting subtest: yf-tiled-64bpp-rotate-90
[420.462047] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[420.565352] [095/156] (604s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[420.662246] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[420.666354] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[420.779369] [096/156] (604s left) gem_exec_fence (long-history)
[421.228000] Starting subtest: long-history
[428.341023] Subtest long-history: SUCCESS (7.112s)
[428.821139] [097/156] (596s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[428.930898] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[436.329896] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.403s)
[436.433355] [098/156] (588s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[436.538305] Starting subtest: bad-rotation-90-yf-tiled-ccs
[436.540581] Starting dynamic subtest: pipe-A-eDP-1
[436.563334] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[436.575280] Starting dynamic subtest: pipe-B-eDP-1
[436.596193] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[436.607265] Starting dynamic subtest: pipe-C-eDP-1
[436.628580] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[436.639828] Starting dynamic subtest: pipe-D-eDP-1
[436.661841] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[436.667083] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[438.785598] [099/156] (586s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[438.899194] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[456.827134] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (17.924s)
[457.691025] [100/156] (567s left) kms_chamelium_audio (dp-audio-edid)
[458.085269] Subtest dp-audio-edid: SKIP (0.000s)
[458.877852] [101/156] (566s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[458.980738] Starting subtest: addfb25-yf-tiled-legacy
[458.983066] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[459.076756] [102/156] (566s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[459.176419] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[459.180771] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[459.307074] [103/156] (565s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[459.412367] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[459.416257] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[459.510430] [104/156] (565s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[459.616452] Starting subtest: fbc-2p-pri-indfb-multidraw
[459.620567] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[459.735426] [105/156] (565s left) kms_sequence (queue-busy)
[459.835835] Starting subtest: queue-busy
[459.837616] Starting dynamic subtest: pipe-A-eDP-1
[461.758153] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.923s)
[461.770872] Starting dynamic subtest: pipe-B-eDP-1
[464.356485] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.596s)
[464.368106] Starting dynamic subtest: pipe-C-eDP-1
[466.919041] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.559s)
[466.930108] Starting dynamic subtest: pipe-D-eDP-1
[469.539916] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.621s)
[469.542272] Subtest queue-busy: SUCCESS (9.704s)
[470.391157] [106/156] (554s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[470.494049] Starting subtest: plane-upscale-20x20-with-rotation
[470.496338] Starting dynamic subtest: pipe-A
[470.719141] Dynamic subtest pipe-A: SUCCESS (0.227s)
[470.730875] Starting dynamic subtest: pipe-B
[472.029145] Dynamic subtest pipe-B: SUCCESS (1.309s)
[472.040072] Starting dynamic subtest: pipe-C
[473.344526] Dynamic subtest pipe-C: SUCCESS (1.314s)
[473.355525] Starting dynamic subtest: pipe-D
[474.641924] Dynamic subtest pipe-D: SUCCESS (1.296s)
[474.644262] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.150s)
[475.797843] [107/156] (549s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[475.893641] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[475.897994] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[475.991945] [108/156] (549s left) gem_exec_reloc (basic-write-wc)
[476.065618] Subtest basic-write-wc: SKIP (0.000s)
[476.138139] [109/156] (549s left) syncobj_timeline (reset-signaled)
[476.182217] Starting subtest: reset-signaled
[476.184542] Subtest reset-signaled: SUCCESS (0.000s)
[476.288339] [110/156] (549s left) kms_psr (pr-cursor-plane-move)
[476.375443] Starting subtest: pr-cursor-plane-move
[476.379507] Subtest pr-cursor-plane-move: SKIP (0.000s)
[476.452628] [111/156] (549s left) syncobj_wait (invalid-wait-illegal-handle)
[476.511202] Starting subtest: invalid-wait-illegal-handle
[476.513548] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[476.612811] [112/156] (549s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[476.722854] Starting subtest: extended-pageflip-modeset-hang-oldfb
[476.724513] Starting dynamic subtest: pipe-A
[484.633548] Dynamic subtest pipe-A: SUCCESS (7.909s)
[484.644661] Starting dynamic subtest: pipe-D
[493.272298] Dynamic subtest pipe-D: SUCCESS (8.634s)
[493.274546] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.547s)
[494.097326] [113/156] (531s left) kms_psr (psr2-primary-blt)
[494.185588] Starting subtest: psr2-primary-blt
[494.187937] Starting dynamic subtest: eDP-1
[495.942577] Dynamic subtest eDP-1: SUCCESS (1.755s)
[495.944473] Subtest psr2-primary-blt: SUCCESS (1.756s)
[496.795495] [114/156] (528s left) gem_softpin (evict-snoop-interruptible)
[496.858957] Starting subtest: evict-snoop-interruptible
[496.863197] Subtest evict-snoop-interruptible: SKIP (0.000s)
[496.992525] [115/156] (528s left) kms_force_connector_basic (force-load-detect)
[497.071988] Starting subtest: force-load-detect
[497.076624] Subtest force-load-detect: SKIP (0.000s)
[497.204251] [116/156] (528s left) kms_chamelium_edid (hdmi-mode-timings)
[497.584566] Subtest hdmi-mode-timings: SKIP (0.000s)
[498.373997] [117/156] (527s left) syncobj_timeline (invalid-signal-zero-handles)
[498.435421] Starting subtest: invalid-signal-zero-handles
[498.437291] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[498.545674] [118/156] (527s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[498.648844] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[498.653362] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[498.753461] [119/156] (527s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[498.842835] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[498.847309] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[498.961701] [120/156] (527s left) gem_mmap_gtt (fault-concurrent-x)
[499.025551] Subtest fault-concurrent-X: SKIP (0.000s)
[499.094704] [121/156] (526s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[499.150826] Starting subtest: y-tiled-to-vebox-y-tiled
[499.155403] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[499.338940] [122/156] (526s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[499.435050] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[499.439285] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[499.534625] [123/156] (526s left) vgem_basic (debugfs)
[499.575718] Starting subtest: debugfs
[499.577576] Subtest debugfs: SUCCESS (0.000s)
[499.636215] [124/156] (526s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[499.724916] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[499.727066] Starting dynamic subtest: pipe-A-default-mode
[500.931918] Dynamic subtest pipe-A-default-mode: SKIP (1.203s)
[500.936369] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.205s)
[501.084644] [125/156] (525s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[501.182544] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[501.186886] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[501.284556] [126/156] (524s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[501.372353] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[501.376595] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[501.454668] [127/156] (524s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[501.554328] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[501.558415] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[501.645895] [128/156] (524s left) kms_vblank (wait-forked-hang)
[501.747354] Starting subtest: wait-forked-hang
[501.749630] Starting dynamic subtest: pipe-A-eDP-1
[528.312333] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.556s)
[528.327919] Starting dynamic subtest: pipe-D-eDP-1
[553.347537] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.039s)
[553.349774] Subtest wait-forked-hang: SUCCESS (51.600s)
[554.191042] [129/156] (472s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[554.286862] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[554.289736] Starting dynamic subtest: pipe-A-default-mode
[555.429312] Dynamic subtest pipe-A-default-mode: SKIP (1.139s)
[555.433759] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.140s)
[555.580550] [130/156] (470s left) gem_ctx_exec (basic-invalid-context)
[555.635360] Starting subtest: basic-invalid-context
[555.639921] Subtest basic-invalid-context: SUCCESS (0.005s)
[555.777089] [131/156] (470s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[555.877757] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[555.882331] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[555.977117] [132/156] (470s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[556.075330] Starting subtest: fbc-psr2-cursor-plane-update-sf
[556.077606] Starting dynamic subtest: pipe-A-eDP-1
[556.155131] Dynamic subtest pipe-A-eDP-1: SKIP (0.081s)
[556.166583] Starting dynamic subtest: pipe-B-eDP-1
[557.329765] Dynamic subtest pipe-B-eDP-1: SKIP (1.171s)
[557.336313] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.254s)
[558.591617] [133/156] (467s left) gem_ppgtt (blt-vs-render-ctxn)
[558.653334] Starting subtest: blt-vs-render-ctxN
[591.428218] Subtest blt-vs-render-ctxN: SUCCESS (32.773s)
[591.564318] [134/156] (435s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[591.666848] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[591.736974] [135/156] (434s left) gem_softpin (invalid)
[591.796617] Starting subtest: invalid
[591.804327] Subtest invalid: SUCCESS (0.008s)
[591.926757] [136/156] (434s left) gem_exec_capture (capture)
[591.993184] Starting subtest: capture
[591.995113] Starting dynamic subtest: rcs0-smem
[592.837478] Dynamic subtest rcs0-smem: SUCCESS (0.846s)
[592.848223] Starting dynamic subtest: bcs0-smem
[593.877044] Dynamic subtest bcs0-smem: SUCCESS (1.038s)
[593.887773] Starting dynamic subtest: vcs0-smem
[594.709787] Dynamic subtest vcs0-smem: SUCCESS (0.830s)
[594.720614] Starting dynamic subtest: vcs1-smem
[595.540138] Dynamic subtest vcs1-smem: SUCCESS (0.830s)
[595.554292] Starting dynamic subtest: vecs0-smem
[596.441411] Dynamic subtest vecs0-smem: FAIL (0.843s)
[596.454038] Starting dynamic subtest: ccs0-smem
[597.709975] Dynamic subtest ccs0-smem: SUCCESS (1.324s)
[597.712191] Subtest capture: FAIL (5.716s)
[597.841666] [137/156] (428s left) gem_tiled_blits (basic)
[597.901405] Subtest basic: SKIP (0.000s)
[597.981170] [138/156] (428s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[598.089678] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[605.364215] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.279s)
[605.489537] [139/156] (421s left) gem_mmap_offset (partial-remap)
[605.539422] Starting subtest: partial-remap
[605.542220] Starting dynamic subtest: smem0
[605.550830] Dynamic subtest smem0: SUCCESS (0.004s)
[605.552692] Subtest partial-remap: SUCCESS (0.004s)
[605.671074] [140/156] (421s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[605.784532] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[605.789015] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[605.892744] [141/156] (421s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[605.996963] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[606.001401] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[606.109009] [142/156] (420s left) i915_power (sanity)
[606.165337] Starting subtest: sanity
[610.213432] Subtest sanity: SUCCESS (4.038s)
[611.659282] [143/156] (415s left) gem_exec_schedule (preempt-queue)
[611.751394] Subtest preempt-queue: SKIP (0.000s)
[612.044508] [144/156] (414s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[612.176619] Starting subtest: cursorB-vs-flipA-legacy
[612.181139] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[612.322261] [145/156] (414s left) i915_pm_rps (reset)
[612.387369] Starting subtest: reset
[620.810874] Subtest reset: SUCCESS (8.425s)
[620.937525] [146/156] (406s left) kms_psr (fbc-psr-cursor-plane-onoff)
[621.048550] Starting subtest: fbc-psr-cursor-plane-onoff
[621.050741] Starting dynamic subtest: eDP-1
[621.054810] Dynamic subtest eDP-1: SKIP (0.000s)
[621.058876] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[621.127675] [147/156] (406s left) kms_setmode (clone-exclusive-crtc)
[621.186759] Starting subtest: clone-exclusive-crtc
[621.193488] Subtest clone-exclusive-crtc: SKIP (0.000s)
[621.297657] [148/156] (405s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[621.396881] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[628.076298] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.682s)
[628.228143] [149/156] (399s left) kms_flip (flip-vs-rmfb)
[628.322124] Starting subtest: flip-vs-rmfb
[628.324413] Starting dynamic subtest: A-eDP1
[637.037976] Dynamic subtest A-eDP1: SUCCESS (8.716s)
[637.051948] Starting dynamic subtest: B-eDP1
[645.736209] Dynamic subtest B-eDP1: SUCCESS (8.695s)
[645.748328] Starting dynamic subtest: C-eDP1
[654.431073] Dynamic subtest C-eDP1: SUCCESS (8.692s)
[654.443378] Starting dynamic subtest: D-eDP1
[663.129539] Dynamic subtest D-eDP1: SUCCESS (8.695s)
[663.134083] Subtest flip-vs-rmfb: SUCCESS (34.808s)
[664.297204] [150/156] (362s left) gem_exec_whisper (basic-contexts-all)
[664.362538] Starting subtest: basic-contexts-all
[675.567552] Subtest basic-contexts-all: SUCCESS (11.198s)
[675.751151] [151/156] (351s left) dmabuf_sync_file (export-basic)
[675.790978] Starting subtest: export-basic
[675.792849] Subtest export-basic: SUCCESS (0.001s)
[675.862663] [152/156] (351s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[675.973947] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[675.978096] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[676.078255] [153/156] (351s left) gem_sync (basic-each)
[676.146829] Starting subtest: basic-each
[678.536276] Subtest basic-each: SUCCESS (2.400s)
[678.724816] [154/156] (348s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[678.820944] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[678.823133] Starting dynamic subtest: pipe-A-eDP-1
[678.848934] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[678.861026] Starting dynamic subtest: pipe-B-eDP-1
[678.885415] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[678.896461] Starting dynamic subtest: pipe-C-eDP-1
[678.920298] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[678.930604] Starting dynamic subtest: pipe-D-eDP-1
[678.954812] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[678.958905] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[681.088062] [155/156] (346s left) syncobj_wait (multi-wait-for-submit-submitted)
[681.140498] Starting subtest: multi-wait-for-submit-submitted
[681.245000] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[681.358798] [156/156] (346s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[681.459959] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[681.464538] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[681.568620] 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.