[57.372869] Initializing watchdogs
[57.372916]   /dev/watchdog0
[57.388318] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.392876] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.395424] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.397517] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[57.494414] Starting subtest: fbc-tiling-linear
[61.236384] Subtest fbc-tiling-linear: SUCCESS (3.818s)
[62.375948] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[62.422158] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[62.471532] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[62.557681] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[62.559249] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[62.650737] [004/156] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[62.738909] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[62.740553] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[62.844975] [005/156] (955s left) kms_force_connector_basic (prune-stale-modes)
[62.917341] Starting subtest: prune-stale-modes
[63.358052] Subtest prune-stale-modes: SUCCESS (0.446s)
[63.496237] [006/156] (954s left) perf_pmu (most-busy-check-all)
[63.533787] Starting subtest: most-busy-check-all
[63.534786] Starting dynamic subtest: rcs0
[65.319382] Dynamic subtest rcs0: SUCCESS (1.795s)
[65.326739] Starting dynamic subtest: bcs0
[67.302961] Dynamic subtest bcs0: SUCCESS (1.998s)
[67.309993] Starting dynamic subtest: vcs0
[69.350903] Dynamic subtest vcs0: SUCCESS (2.059s)
[69.359340] Starting dynamic subtest: vcs1
[71.336722] Dynamic subtest vcs1: SUCCESS (1.994s)
[71.345320] Starting dynamic subtest: vecs0
[73.320481] Dynamic subtest vecs0: SUCCESS (1.989s)
[73.330573] Starting dynamic subtest: ccs0
[75.367837] Dynamic subtest ccs0: SUCCESS (2.052s)
[75.369230] Subtest most-busy-check-all: SUCCESS (11.893s)
[75.473384] [007/156] (942s left) gem_ccs (large-ctrl-surf-copy)
[75.521975] Starting subtest: large-ctrl-surf-copy
[75.524015] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[75.621621] [008/156] (942s left) kms_plane_alpha_blend (coverage-7efc)
[75.714786] Starting subtest: coverage-7efc
[76.010515] Starting dynamic subtest: pipe-A-eDP-1
[76.575290] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.565s)
[76.605457] Starting dynamic subtest: pipe-D-eDP-1
[77.878326] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.281s)
[77.879190] Subtest coverage-7efc: SUCCESS (2.167s)
[78.693984] [009/156] (939s left) gem_mmap_gtt (fault-concurrent)
[78.738940] Subtest fault-concurrent: SKIP (0.000s)
[78.792206] [010/156] (939s left) api_intel_allocator (gem-pool)
[78.851201] Starting subtest: gem-pool
[78.858736] Subtest gem-pool: SUCCESS (0.008s)
[78.966471] [011/156] (939s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[79.065193] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[79.067052] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[79.141581] [012/156] (939s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[79.237219] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[79.238868] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[79.301688] [013/156] (938s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[79.393141] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[79.394120] Starting dynamic subtest: pipe-A-eDP-1
[79.402240] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[79.409521] Starting dynamic subtest: pipe-B-eDP-1
[79.417702] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[79.427665] Starting dynamic subtest: pipe-C-eDP-1
[79.435726] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[79.443028] Starting dynamic subtest: pipe-D-eDP-1
[79.451560] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[79.453368] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[81.596534] [014/156] (936s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[81.681920] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[81.682930] Starting dynamic subtest: pipe-A-eDP-1
[81.693069] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[81.701869] Starting dynamic subtest: pipe-B-eDP-1
[81.710976] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[81.718135] Starting dynamic subtest: pipe-C-eDP-1
[81.726498] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[81.733323] Starting dynamic subtest: pipe-D-eDP-1
[81.741966] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[81.743743] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.002s)
[83.979830] [015/156] (934s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[84.068204] Starting subtest: hdr-rgb565-draw-blt
[84.069857] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[84.163684] [016/156] (934s left) kms_pm_rpm (legacy-planes-dpms)
[85.707962] Starting subtest: legacy-planes-dpms
[85.709478] Starting dynamic subtest: plane-65
[94.063283] Dynamic subtest plane-65: SUCCESS (8.354s)
[94.074292] Starting dynamic subtest: plane-95
[102.392531] Dynamic subtest plane-95: SUCCESS (8.327s)
[102.403228] Starting dynamic subtest: plane-125
[111.123740] Dynamic subtest plane-125: SUCCESS (8.728s)
[111.134335] Starting dynamic subtest: plane-135
[119.431836] Dynamic subtest plane-135: SUCCESS (8.306s)
[119.433762] Subtest legacy-planes-dpms: SUCCESS (33.721s)
[119.826138] [017/156] (898s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[119.885764] Starting subtest: x-tiled-to-vebox-y-tiled
[119.887703] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[120.058668] [018/156] (898s left) sysfs_heartbeat_interval (invalid)
[120.119513] Starting subtest: invalid
[120.120584] Starting dynamic subtest: vecs0
[120.121715] Dynamic subtest vecs0: SUCCESS (0.000s)
[120.130388] Starting dynamic subtest: ccs0
[120.131175] Dynamic subtest ccs0: SUCCESS (0.000s)
[120.138613] Starting dynamic subtest: vcs0
[120.139544] Dynamic subtest vcs0: SUCCESS (0.000s)
[120.146375] Starting dynamic subtest: bcs0
[120.147222] Dynamic subtest bcs0: SUCCESS (0.000s)
[120.153971] Starting dynamic subtest: rcs0
[120.155066] Dynamic subtest rcs0: SUCCESS (0.000s)
[120.161807] Starting dynamic subtest: vcs1
[120.162740] Dynamic subtest vcs1: SUCCESS (0.000s)
[120.163634] Subtest invalid: SUCCESS (0.005s)
[120.189818] [019/156] (898s left) kms_frontbuffer_tracking (fbc-stridechange)
[120.281073] Starting subtest: fbc-stridechange
[123.473739] Subtest fbc-stridechange: SUCCESS (3.193s)
[124.597876] [020/156] (893s left) gem_ctx_param (basic-default)
[124.641474] Starting subtest: basic-default
[124.642532] Subtest basic-default: SUCCESS (0.000s)
[124.710699] [021/156] (893s left) kms_psr (fbc-pr-primary-mmap-gtt)
[124.802032] Starting subtest: fbc-pr-primary-mmap-gtt
[124.803966] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[124.865664] [022/156] (893s left) i915_pm_freq_mult (media-freq)
[124.918582] Starting subtest: media-freq
[124.919830] Starting dynamic subtest: gt0
[124.922425] Dynamic subtest gt0: SKIP (0.000s)
[124.933040] Starting dynamic subtest: gt1
[124.935099] Dynamic subtest gt1: SKIP (0.000s)
[124.936869] Subtest media-freq: SKIP (0.001s)
[125.035075] [023/156] (893s left) prime_busy (before-wait)
[125.094234] Starting subtest: before-wait
[125.097518] Starting dynamic subtest: rcs0
[125.131109] Dynamic subtest rcs0: SUCCESS (0.033s)
[125.139875] Starting dynamic subtest: bcs0
[125.176133] Dynamic subtest bcs0: SUCCESS (0.045s)
[125.183885] Starting dynamic subtest: vcs0
[125.216580] Dynamic subtest vcs0: SUCCESS (0.040s)
[125.224257] Starting dynamic subtest: vcs1
[125.257126] Dynamic subtest vcs1: SUCCESS (0.040s)
[125.264771] Starting dynamic subtest: vecs0
[125.299995] Dynamic subtest vecs0: SUCCESS (0.042s)
[125.307952] Starting dynamic subtest: ccs0
[125.340511] Dynamic subtest ccs0: SUCCESS (0.040s)
[125.453953] Subtest before-wait: SUCCESS (0.351s)
[125.528158] [024/156] (893s left) gem_exec_gttfill (engines)
[125.582694] Starting subtest: engines
[125.583740] Starting dynamic subtest: rcs0
[148.380026] Dynamic subtest rcs0: SUCCESS (22.795s)
[148.389575] Starting dynamic subtest: bcs0
[171.618707] Dynamic subtest bcs0: SUCCESS (23.237s)
[171.629215] Starting dynamic subtest: vcs0
[194.925416] Dynamic subtest vcs0: SUCCESS (23.303s)
[194.938095] Starting dynamic subtest: vcs1
[218.230836] Dynamic subtest vcs1: SUCCESS (23.302s)
[218.241551] Starting dynamic subtest: vecs0
[241.563258] Dynamic subtest vecs0: SUCCESS (23.331s)
[241.574261] Starting dynamic subtest: ccs0
[264.909427] Dynamic subtest ccs0: SUCCESS (23.341s)
[264.910535] Subtest engines: SUCCESS (139.317s)
[265.090230] [025/156] (753s left) syncobj_timeline (etime-multi-wait-all-submitted)
[265.227764] Starting subtest: etime-multi-wait-all-submitted
[265.330052] Subtest etime-multi-wait-all-submitted: SUCCESS (0.101s)
[265.400440] [026/156] (753s left) gem_ctx_param (get-priority-new-ctx)
[265.445522] Starting subtest: get-priority-new-ctx
[265.446723] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[265.542301] [027/156] (753s left) kms_big_fb (linear-32bpp-rotate-90)
[265.652838] Starting subtest: linear-32bpp-rotate-90
[265.673928] Subtest linear-32bpp-rotate-90: SKIP (0.020s)
[265.780477] [028/156] (752s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[265.815785] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[265.854012] [029/156] (752s left) gem_ctx_shared (disjoint-timelines)
[265.915283] Starting subtest: disjoint-timelines
[265.994186] Subtest disjoint-timelines: SUCCESS (0.080s)
[266.206008] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[266.207848] [030/156] (752s left) kms_psr (fbc-psr2-cursor-plane-move)
[266.293333] Starting subtest: fbc-psr2-cursor-plane-move
[266.294180] Starting dynamic subtest: eDP-1
[266.295841] Dynamic subtest eDP-1: SKIP (0.000s)
[266.297469] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[266.366376] [031/156] (752s left) gem_bad_reloc (negative-reloc-bltcopy)
[266.426450] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[266.477591] [032/156] (752s left) kms_chamelium_hpd (hdmi-hpd)
[266.848706] Subtest hdmi-hpd: SKIP (0.000s)
[267.659588] [033/156] (751s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[267.742209] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[267.744209] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[267.815112] [034/156] (751s left) gem_eio (wait-10ms)
[268.045085] Starting subtest: wait-10ms
[268.420372] Subtest wait-10ms: SUCCESS (0.375s)
[268.545822] [035/156] (750s left) kms_content_protection (legacy-hdcp14)
[268.690177] Starting subtest: legacy-hdcp14
[268.692231] Subtest legacy-hdcp14: SKIP (0.000s)
[268.815156] [036/156] (750s left) syncobj_wait (reset-signaled)
[268.866409] Starting subtest: reset-signaled
[268.867385] Subtest reset-signaled: SUCCESS (0.000s)
[268.960266] [037/156] (750s left) gem_ctx_isolation (dirty-switch)
[269.012082] Starting subtest: dirty-switch
[269.013021] Starting dynamic subtest: rcs0
[269.214294] Dynamic subtest rcs0: SUCCESS (0.203s)
[269.224736] Starting dynamic subtest: bcs0
[269.422259] Dynamic subtest bcs0: SUCCESS (0.207s)
[269.433366] Starting dynamic subtest: vcs0
[269.657865] Dynamic subtest vcs0: SUCCESS (0.235s)
[269.669617] Starting dynamic subtest: vcs1
[271.551819] Dynamic subtest vcs1: SUCCESS (1.893s)
[271.562964] Starting dynamic subtest: vecs0
[273.505737] Dynamic subtest vecs0: SUCCESS (1.953s)
[273.516606] Starting dynamic subtest: ccs0
[275.586404] Dynamic subtest ccs0: SUCCESS (2.080s)
[275.587541] Subtest dirty-switch: SUCCESS (6.575s)
[275.831079] [038/156] (743s left) gem_flink_basic (bad-open)
[275.880986] Starting subtest: bad-open
[275.882086] Subtest bad-open: SUCCESS (0.000s)
[275.980704] [039/156] (743s left) gem_caching (writes)
[276.034953] Subtest writes: SKIP (0.000s)
[276.104172] [040/156] (743s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[276.197163] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[276.198078] Starting dynamic subtest: pipe-A
[276.535863] Dynamic subtest pipe-A: SUCCESS (0.339s)
[276.544240] Starting dynamic subtest: pipe-B
[277.965987] Dynamic subtest pipe-B: SUCCESS (1.429s)
[277.978100] Starting dynamic subtest: pipe-C
[279.452749] Dynamic subtest pipe-C: SUCCESS (1.485s)
[279.461487] Starting dynamic subtest: pipe-D
[280.853503] Dynamic subtest pipe-D: SUCCESS (1.400s)
[280.854718] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.657s)
[281.982174] [041/156] (737s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[282.027198] Starting subtest: single-wait-all-for-submit-available-submitted
[282.028096] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[282.122772] [042/156] (737s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[282.218025] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[282.219804] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[282.314347] [043/156] (736s left) kms_pm_rpm (system-suspend-idle)
[284.466385] Starting subtest: system-suspend-idle
[305.400688] Subtest system-suspend-idle: SUCCESS (5.166s)
[308.851961] [044/156] (713s left) kms_atomic_transition (modeset-transition)
[308.953331] Starting subtest: modeset-transition
[308.954698] Starting dynamic subtest: 1x-outputs
[319.611005] Dynamic subtest 1x-outputs: SUCCESS (10.908s)
[319.612572] Subtest modeset-transition: SUCCESS (10.910s)
[320.425042] [045/156] (701s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[320.517888] Starting subtest: pipe-fbc-rte
[320.520738] Starting dynamic subtest: pipe-B-eDP-1
[325.998097] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.521s)
[326.002796] Subtest pipe-fbc-rte: SUCCESS (5.523s)
[328.297484] [046/156] (694s left) gem_spin_batch (resubmit-all)
[328.354976] Starting subtest: resubmit-all
[328.356416] Starting dynamic subtest: rcs0
[328.367798] Dynamic subtest rcs0: SUCCESS (0.013s)
[328.379848] Starting dynamic subtest: bcs0
[328.381107] Dynamic subtest bcs0: SUCCESS (0.002s)
[328.390136] Starting dynamic subtest: vcs0
[328.391439] Dynamic subtest vcs0: SUCCESS (0.003s)
[328.400444] Starting dynamic subtest: vcs1
[328.401685] Dynamic subtest vcs1: SUCCESS (0.002s)
[328.410707] Starting dynamic subtest: vecs0
[328.412055] Dynamic subtest vecs0: SUCCESS (0.002s)
[328.421135] Starting dynamic subtest: ccs0
[328.422405] Dynamic subtest ccs0: SUCCESS (0.004s)
[328.423810] Subtest resubmit-all: SUCCESS (0.028s)
[330.146492] [047/156] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[330.234650] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[330.236320] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[330.334352] [048/156] (692s left) syncobj_basic (illegal-fd-to-handle)
[330.378095] Starting subtest: illegal-fd-to-handle
[330.379396] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[330.496690] [049/156] (692s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[330.586704] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[330.589100] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[330.676733] [050/156] (691s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[330.781156] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[331.913404] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.136s)
[332.010647] [051/156] (690s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[332.056863] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[332.164161] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[332.251807] [052/156] (690s left) api_intel_bb (misplaced-blitter)
[332.304716] Starting subtest: misplaced-blitter
[332.311405] Subtest misplaced-blitter: SUCCESS (0.008s)
[332.429156] [053/156] (690s left) kms_atomic_interruptible (universal-setplane-cursor)
[332.513726] Starting subtest: universal-setplane-cursor
[332.514940] Starting dynamic subtest: pipe-A-eDP-1
[339.950230] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.447s)
[339.951771] Subtest universal-setplane-cursor: SUCCESS (7.448s)
[340.751935] [054/156] (681s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[340.844114] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[340.846436] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[340.932442] [055/156] (681s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[341.020078] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[341.030027] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[341.122203] [056/156] (681s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[341.212438] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[341.215117] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[341.281848] [057/156] (681s left) gem_pxp (create-protected-buffer)
[341.392318] Starting subtest: create-protected-buffer
[341.393937] Subtest create-protected-buffer: SUCCESS (0.000s)
[341.510343] [058/156] (681s left) gem_fence_thrash (bo-write-verify-threaded-x)
[341.548294] Starting subtest: bo-write-verify-threaded-x
[341.568306] Subtest bo-write-verify-threaded-x: SKIP (0.017s)
[341.642285] [059/156] (681s left) gen9_exec_parse (allowed-single)
[341.697633] Subtest allowed-single: SKIP (0.000s)
[341.761164] [060/156] (681s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[341.853548] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[341.855308] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[341.945813] [061/156] (681s left) gem_exec_balancer (bonded-semaphore)
[342.004410] Starting subtest: bonded-semaphore
[342.007314] Subtest bonded-semaphore: SKIP (0.000s)
[342.304196] [062/156] (680s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[342.404967] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[342.406791] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[342.472544] [063/156] (680s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[342.561565] Starting subtest: fbc-rgb565-draw-mmap-wc
[346.573785] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.012s)
[347.771868] [064/156] (675s left) kms_pwrite_crc (basic)
[347.859909] Starting subtest: basic
[348.573905] Subtest basic: SUCCESS (0.715s)
[349.479227] [065/156] (673s left) gem_flink_basic (bad-flink)
[349.531275] Starting subtest: bad-flink
[349.532905] Subtest bad-flink: SUCCESS (0.000s)
[349.642700] [066/156] (673s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[349.744270] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[349.747442] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[349.832432] [067/156] (673s left) gem_ctx_persistence (legacy-engines-persistence)
[349.883688] Starting subtest: legacy-engines-persistence
[349.884624] Starting dynamic subtest: render
[350.715876] Dynamic subtest render: SUCCESS (0.831s)
[350.731134] Starting dynamic subtest: bsd1
[351.458063] Dynamic subtest bsd1: SUCCESS (0.738s)
[351.469692] Starting dynamic subtest: bsd2
[352.242408] Dynamic subtest bsd2: SUCCESS (0.784s)
[352.256969] Starting dynamic subtest: blt
[352.993743] Dynamic subtest blt: SUCCESS (0.745s)
[353.004922] Starting dynamic subtest: vebox
[353.784756] Dynamic subtest vebox: SUCCESS (0.791s)
[353.786344] Subtest legacy-engines-persistence: SUCCESS (3.902s)
[353.877849] [068/156] (669s left) gen7_exec_parse (basic-allowed)
[353.925404] Subtest basic-allowed: SKIP (0.000s)
[353.987211] [069/156] (669s left) gem_exec_schedule (reorder-wide)
[354.172104] Subtest reorder-wide: SKIP (0.000s)
[354.339021] [070/156] (668s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[354.430029] Starting subtest: psrhdr-slowdraw
[354.432035] Subtest psrhdr-slowdraw: SKIP (0.000s)
[354.517753] [071/156] (668s left) perf (non-zero-reason)
[354.640596] Starting subtest: non-zero-reason
[354.641419] Starting dynamic subtest: 0-rcs0
[360.301894] Dynamic subtest 0-rcs0: SUCCESS (5.615s)
[360.314660] Starting dynamic subtest: 1-vecs0
[364.289150] Dynamic subtest 1-vecs0: SUCCESS (4.022s)
[364.290938] Subtest non-zero-reason: SUCCESS (9.639s)
[364.330057] [072/156] (659s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[364.433039] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[364.434895] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[364.522415] [073/156] (658s left) i915_drm_fdinfo (all-busy-check-all)
[364.558838] Starting subtest: all-busy-check-all
[364.563216] Subtest all-busy-check-all: SKIP (0.004s)
[364.632696] [074/156] (658s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[364.761801] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[364.763449] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[364.856123] [075/156] (658s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[364.956923] Starting subtest: compare-crc-sanitycheck-nv12
[364.957979] Starting dynamic subtest: pipe-A-eDP-1
[365.679606] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.722s)
[365.690156] Starting dynamic subtest: pipe-B-eDP-1
[367.027261] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.346s)
[367.036880] Starting dynamic subtest: pipe-C-eDP-1
[368.244194] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.217s)
[368.254072] Starting dynamic subtest: pipe-D-eDP-1
[369.456943] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.212s)
[369.458057] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.503s)
[370.256273] [076/156] (653s left) gem_exec_reloc (basic-write-gtt)
[370.312223] Subtest basic-write-gtt: SKIP (0.000s)
[370.372162] [077/156] (653s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[370.664795] Starting subtest: cursor-rapid-movement-max-size
[370.667802] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[370.783894] [078/156] (652s left) kms_color_pipeline (plane-lut1d)
[370.874739] Starting subtest: plane-lut1d
[371.021152] Starting dynamic subtest: pipe-A-plane-0
[371.198941] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[371.209422] Starting dynamic subtest: pipe-A-plane-1
[371.343736] Dynamic subtest pipe-A-plane-1: SUCCESS (0.144s)
[371.355232] Starting dynamic subtest: pipe-A-plane-2
[371.487620] Dynamic subtest pipe-A-plane-2: SUCCESS (0.143s)
[372.751962] Starting dynamic subtest: pipe-B-plane-0
[372.920647] Dynamic subtest pipe-B-plane-0: SUCCESS (0.177s)
[372.930684] Starting dynamic subtest: pipe-B-plane-1
[373.087619] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[373.102566] Starting dynamic subtest: pipe-B-plane-2
[373.253686] Dynamic subtest pipe-B-plane-2: SUCCESS (0.165s)
[374.455820] Starting dynamic subtest: pipe-C-plane-0
[374.602813] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[374.615768] Starting dynamic subtest: pipe-C-plane-1
[374.758110] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[374.768290] Starting dynamic subtest: pipe-C-plane-2
[374.902413] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[376.122795] Starting dynamic subtest: pipe-D-plane-0
[376.256734] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[376.266769] Starting dynamic subtest: pipe-D-plane-1
[376.401061] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[376.411849] Starting dynamic subtest: pipe-D-plane-2
[376.578722] Dynamic subtest pipe-D-plane-2: SUCCESS (0.177s)
[376.875139] Subtest plane-lut1d: SUCCESS (6.004s)
[377.753889] [079/156] (645s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[377.848688] Starting subtest: yf-tiled-16bpp-rotate-180
[377.850688] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[377.938704] [080/156] (645s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[378.033962] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[379.078989] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.046s)
[379.198257] [081/156] (644s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[379.293854] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[379.295612] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[379.388347] [082/156] (644s left) gem_exec_reloc (basic-wc-read)
[379.442249] Subtest basic-wc-read: SKIP (0.000s)
[379.505126] [083/156] (644s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[379.618977] Starting subtest: short-busy-flip-before-cursor-toggle
[379.620917] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[379.762675] [084/156] (644s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[379.851048] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[379.861902] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[379.935653] [085/156] (643s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[380.024856] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[380.026854] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[380.111694] [086/156] (643s left) kms_draw_crc (draw-method-render)
[380.197941] Starting subtest: draw-method-render
[380.198918] Starting dynamic subtest: xrgb8888-untiled
[380.464077] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[380.475160] Starting dynamic subtest: rgb565-untiled
[380.708715] Dynamic subtest rgb565-untiled: SUCCESS (0.243s)
[380.718290] Starting dynamic subtest: xrgb2101010-untiled
[380.930765] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[380.940356] Starting dynamic subtest: xbgr16161616f-untiled
[381.163929] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[381.173701] Starting dynamic subtest: xrgb8888-xtiled
[381.286244] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[381.296351] Starting dynamic subtest: rgb565-xtiled
[381.408756] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[381.418897] Starting dynamic subtest: xrgb2101010-xtiled
[381.530943] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[381.542193] Starting dynamic subtest: xbgr16161616f-xtiled
[381.652777] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s)
[381.663078] Starting dynamic subtest: xrgb8888-4tiled
[381.775048] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[381.785006] Starting dynamic subtest: rgb565-4tiled
[381.885991] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[381.896205] Starting dynamic subtest: xrgb2101010-4tiled
[381.997069] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[382.006861] Starting dynamic subtest: xbgr16161616f-4tiled
[382.130753] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[382.132022] Subtest draw-method-render: SUCCESS (1.934s)
[382.233764] [087/156] (641s left) kms_cursor_crc (cursor-sliding-256x85)
[382.490635] Starting subtest: cursor-sliding-256x85
[382.535175] Subtest cursor-sliding-256x85: SKIP (0.043s)
[382.714265] [088/156] (641s left) kms_cursor_crc (cursor-onscreen-256x256)
[382.945455] Starting subtest: cursor-onscreen-256x256
[382.948163] Starting dynamic subtest: pipe-A-eDP-1
[385.083183] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.134s)
[385.113906] Starting dynamic subtest: pipe-D-eDP-1
[387.894358] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.789s)
[387.895849] Subtest cursor-onscreen-256x256: SUCCESS (4.950s)
[388.749506] [089/156] (635s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[388.842101] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[388.843997] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[388.932501] [090/156] (635s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[389.032698] Starting subtest: fbcpsr-slowdraw
[414.259663] Subtest fbcpsr-slowdraw: SUCCESS (25.228s)
[414.389768] [091/156] (609s left) kms_lease (cursor-implicit-plane)
[414.480699] Starting subtest: cursor-implicit-plane
[414.481663] Starting dynamic subtest: pipe-A-eDP-1
[414.924295] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.444s)
[414.934796] Starting dynamic subtest: pipe-B-eDP-1
[416.043931] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.117s)
[416.054835] Starting dynamic subtest: pipe-C-eDP-1
[417.128015] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.082s)
[417.138095] Starting dynamic subtest: pipe-D-eDP-1
[418.209271] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.081s)
[418.210713] Subtest cursor-implicit-plane: SUCCESS (3.729s)
[419.038363] [092/156] (605s left) syncobj_wait (invalid-reset-zero-handles)
[419.084428] Starting subtest: invalid-reset-zero-handles
[419.085832] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[419.186080] [093/156] (604s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[419.272935] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[419.275073] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[419.349553] [094/156] (604s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[419.446041] Starting subtest: yf-tiled-64bpp-rotate-90
[419.448762] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[419.541984] [095/156] (604s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[419.640531] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[419.642706] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[419.728156] [096/156] (604s left) gem_exec_fence (long-history)
[420.174326] Starting subtest: long-history
[427.781852] Subtest long-history: SUCCESS (7.597s)
[428.825942] [097/156] (595s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[428.923579] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[436.278128] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.357s)
[436.398712] [098/156] (587s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[436.482060] Starting subtest: bad-rotation-90-yf-tiled-ccs
[436.483081] Starting dynamic subtest: pipe-A-eDP-1
[436.492586] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[436.502930] Starting dynamic subtest: pipe-B-eDP-1
[436.513606] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[436.523601] Starting dynamic subtest: pipe-C-eDP-1
[436.532144] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[436.543927] Starting dynamic subtest: pipe-D-eDP-1
[436.553965] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[436.555674] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[438.743740] [099/156] (585s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[438.864092] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[456.792343] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (17.930s)
[457.639776] [100/156] (566s left) kms_chamelium_audio (dp-audio-edid)
[458.023841] Subtest dp-audio-edid: SKIP (0.000s)
[458.813913] [101/156] (565s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[458.900586] Starting subtest: addfb25-yf-tiled-legacy
[458.901557] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[458.984121] [102/156] (565s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[459.073044] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[459.075049] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[459.164263] [103/156] (565s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[459.251870] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[459.253621] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[459.356896] [104/156] (565s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[459.448705] Starting subtest: fbc-2p-pri-indfb-multidraw
[459.450516] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[459.547086] [105/156] (565s left) kms_sequence (queue-busy)
[459.631063] Starting subtest: queue-busy
[459.632179] Starting dynamic subtest: pipe-A-eDP-1
[461.554037] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.922s)
[461.563859] Starting dynamic subtest: pipe-B-eDP-1
[464.955979] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.389s)
[464.967338] Starting dynamic subtest: pipe-C-eDP-1
[467.064368] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.116s)
[467.074462] Starting dynamic subtest: pipe-D-eDP-1
[469.695092] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.625s)
[469.696695] Subtest queue-busy: SUCCESS (10.059s)
[470.539186] [106/156] (554s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[470.625132] Starting subtest: plane-upscale-20x20-with-rotation
[470.626128] Starting dynamic subtest: pipe-A
[470.852120] Dynamic subtest pipe-A: SUCCESS (0.227s)
[470.861811] Starting dynamic subtest: pipe-B
[472.195176] Dynamic subtest pipe-B: SUCCESS (1.341s)
[472.205171] Starting dynamic subtest: pipe-C
[473.481646] Dynamic subtest pipe-C: SUCCESS (1.286s)
[473.491810] Starting dynamic subtest: pipe-D
[474.786691] Dynamic subtest pipe-D: SUCCESS (1.304s)
[474.787946] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.163s)
[475.930592] [107/156] (548s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[476.026027] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[476.028158] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[476.115770] [108/156] (548s left) gem_exec_reloc (basic-write-wc)
[476.176794] Subtest basic-write-wc: SKIP (0.000s)
[476.238151] [109/156] (548s left) syncobj_timeline (reset-signaled)
[476.287939] Starting subtest: reset-signaled
[476.289051] Subtest reset-signaled: SUCCESS (0.000s)
[476.394628] [110/156] (548s left) kms_psr (pr-cursor-plane-move)
[476.483409] Starting subtest: pr-cursor-plane-move
[476.486001] Subtest pr-cursor-plane-move: SKIP (0.000s)
[476.553992] [111/156] (548s left) syncobj_wait (invalid-wait-illegal-handle)
[476.607829] Starting subtest: invalid-wait-illegal-handle
[476.608703] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[476.699290] [112/156] (548s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[476.793081] Starting subtest: extended-pageflip-modeset-hang-oldfb
[476.794488] Starting dynamic subtest: pipe-A
[484.698889] Dynamic subtest pipe-A: SUCCESS (7.906s)
[484.710797] Starting dynamic subtest: pipe-D
[493.329725] Dynamic subtest pipe-D: SUCCESS (8.629s)
[493.331247] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.542s)
[494.132368] [113/156] (530s left) kms_psr (psr2-primary-blt)
[494.220423] Starting subtest: psr2-primary-blt
[494.221638] Starting dynamic subtest: eDP-1
[495.993105] Dynamic subtest eDP-1: SUCCESS (1.773s)
[495.993925] Subtest psr2-primary-blt: SUCCESS (1.774s)
[496.818353] [114/156] (528s left) gem_softpin (evict-snoop-interruptible)
[496.874157] Starting subtest: evict-snoop-interruptible
[496.876001] Subtest evict-snoop-interruptible: SKIP (0.000s)
[496.985473] [115/156] (527s left) kms_force_connector_basic (force-load-detect)
[497.054829] Starting subtest: force-load-detect
[497.056685] Subtest force-load-detect: SKIP (0.000s)
[497.164276] [116/156] (527s left) kms_chamelium_edid (hdmi-mode-timings)
[497.541079] Subtest hdmi-mode-timings: SKIP (0.000s)
[498.403322] [117/156] (526s left) syncobj_timeline (invalid-signal-zero-handles)
[498.452913] Starting subtest: invalid-signal-zero-handles
[498.454057] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[498.549097] [118/156] (526s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[498.644166] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[498.646582] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[498.751829] [119/156] (526s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[498.839402] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[498.841247] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[498.938363] [120/156] (526s left) gem_mmap_gtt (fault-concurrent-x)
[498.982963] Subtest fault-concurrent-X: SKIP (0.000s)
[499.038691] [121/156] (526s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[499.101687] Starting subtest: y-tiled-to-vebox-y-tiled
[499.104625] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[499.282183] [122/156] (525s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[499.365979] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[499.368018] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[499.471229] [123/156] (525s left) vgem_basic (debugfs)
[499.513828] Starting subtest: debugfs
[499.515132] Subtest debugfs: SUCCESS (0.000s)
[499.569330] [124/156] (525s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[499.652754] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[499.654030] Starting dynamic subtest: pipe-A-default-mode
[500.863432] Dynamic subtest pipe-A-default-mode: SKIP (1.210s)
[500.865825] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.212s)
[500.995089] [125/156] (524s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[501.080989] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[501.083598] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[501.190325] [126/156] (524s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[501.284020] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[501.285829] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[501.389209] [127/156] (524s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[501.473763] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[501.475553] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[501.584954] [128/156] (523s left) kms_vblank (wait-forked-hang)
[501.668463] Starting subtest: wait-forked-hang
[501.671181] Starting dynamic subtest: pipe-A-eDP-1
[528.827076] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.161s)
[528.840720] Starting dynamic subtest: pipe-D-eDP-1
[553.856225] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.021s)
[553.858001] Subtest wait-forked-hang: SUCCESS (52.186s)
[554.729762] [129/156] (470s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[554.816037] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[554.817330] Starting dynamic subtest: pipe-A-default-mode
[555.951572] Dynamic subtest pipe-A-default-mode: SKIP (1.136s)
[555.953958] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.138s)
[556.094841] [130/156] (469s left) gem_ctx_exec (basic-invalid-context)
[556.147954] Starting subtest: basic-invalid-context
[556.152738] Subtest basic-invalid-context: SUCCESS (0.005s)
[556.271141] [131/156] (469s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[556.363877] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[556.365711] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[556.469660] [132/156] (469s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[556.560259] Starting subtest: fbc-psr2-cursor-plane-update-sf
[556.561446] Starting dynamic subtest: pipe-A-eDP-1
[556.641292] Dynamic subtest pipe-A-eDP-1: SKIP (0.082s)
[556.651178] Starting dynamic subtest: pipe-B-eDP-1
[557.765064] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[557.767471] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.207s)
[559.018809] [133/156] (466s left) gem_ppgtt (blt-vs-render-ctxn)
[559.069856] Starting subtest: blt-vs-render-ctxN
[591.917896] Subtest blt-vs-render-ctxN: SUCCESS (32.834s)
[592.032404] [134/156] (433s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[592.163730] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[592.233947] [135/156] (433s left) gem_softpin (invalid)
[592.300719] Starting subtest: invalid
[592.310753] Subtest invalid: SUCCESS (0.010s)
[592.423289] [136/156] (433s left) gem_exec_capture (capture)
[592.477336] Starting subtest: capture
[592.478772] Starting dynamic subtest: rcs0-smem
[593.324200] Dynamic subtest rcs0-smem: SUCCESS (0.849s)
[593.334389] Starting dynamic subtest: bcs0-smem
[594.391249] Dynamic subtest bcs0-smem: SUCCESS (1.064s)
[594.402121] Starting dynamic subtest: vcs0-smem
[595.220815] Dynamic subtest vcs0-smem: SUCCESS (0.831s)
[595.231135] Starting dynamic subtest: vcs1-smem
[596.047752] Dynamic subtest vcs1-smem: SUCCESS (0.826s)
[596.060645] Starting dynamic subtest: vecs0-smem
[597.420779] Dynamic subtest vecs0-smem: FAIL (1.345s)
[597.430969] Starting dynamic subtest: ccs0-smem
[598.714695] Dynamic subtest ccs0-smem: SUCCESS (1.320s)
[598.716430] Subtest capture: FAIL (6.241s)
[598.847497] [137/156] (426s left) gem_tiled_blits (basic)
[598.895284] Subtest basic: SKIP (0.000s)
[598.953775] [138/156] (426s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[599.058552] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[606.375931] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.319s)
[606.498654] [139/156] (419s left) gem_mmap_offset (partial-remap)
[606.546865] Starting subtest: partial-remap
[606.548116] Starting dynamic subtest: smem0
[606.554164] Dynamic subtest smem0: SUCCESS (0.003s)
[606.555286] Subtest partial-remap: SUCCESS (0.004s)
[606.654289] [140/156] (419s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[606.743616] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[606.745332] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[606.836493] [141/156] (419s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[606.931922] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[606.933904] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[607.023446] [142/156] (418s left) i915_power (sanity)
[607.086898] Starting subtest: sanity
[611.139317] Subtest sanity: SUCCESS (4.042s)
[613.126330] [143/156] (412s left) gem_exec_schedule (preempt-queue)
[613.196608] Subtest preempt-queue: SKIP (0.000s)
[613.492630] [144/156] (412s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[613.614113] Starting subtest: cursorB-vs-flipA-legacy
[613.616019] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[613.754225] [145/156] (412s left) i915_pm_rps (reset)
[613.818813] Starting subtest: reset
[622.275147] Subtest reset: SUCCESS (8.452s)
[622.383852] [146/156] (403s left) kms_psr (fbc-psr-cursor-plane-onoff)
[622.480558] Starting subtest: fbc-psr-cursor-plane-onoff
[622.481564] Starting dynamic subtest: eDP-1
[622.483314] Dynamic subtest eDP-1: SKIP (0.000s)
[622.485263] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[622.565549] [147/156] (403s left) kms_setmode (clone-exclusive-crtc)
[622.618111] Starting subtest: clone-exclusive-crtc
[622.621610] Subtest clone-exclusive-crtc: SKIP (0.001s)
[622.710096] [148/156] (403s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[622.805120] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[629.594433] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.791s)
[629.727973] [149/156] (396s left) kms_flip (flip-vs-rmfb)
[629.816138] Starting subtest: flip-vs-rmfb
[629.819031] Starting dynamic subtest: A-eDP1
[638.565731] Dynamic subtest A-eDP1: SUCCESS (8.747s)
[638.577160] Starting dynamic subtest: B-eDP1
[647.272400] Dynamic subtest B-eDP1: SUCCESS (8.706s)
[647.283640] Starting dynamic subtest: C-eDP1
[655.997821] Dynamic subtest C-eDP1: SUCCESS (8.725s)
[656.010867] Starting dynamic subtest: D-eDP1
[664.697801] Dynamic subtest D-eDP1: SUCCESS (8.698s)
[664.700769] Subtest flip-vs-rmfb: SUCCESS (34.886s)
[665.907368] [150/156] (360s left) gem_exec_whisper (basic-contexts-all)
[665.974417] Starting subtest: basic-contexts-all
[676.121019] Subtest basic-contexts-all: SUCCESS (10.136s)
[676.296518] [151/156] (349s left) dmabuf_sync_file (export-basic)
[676.337322] Starting subtest: export-basic
[676.339075] Subtest export-basic: SUCCESS (0.001s)
[676.392633] [152/156] (349s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[676.479244] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[676.480970] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[676.570121] [153/156] (349s left) gem_sync (basic-each)
[676.623617] Starting subtest: basic-each
[679.047460] Subtest basic-each: SUCCESS (2.424s)
[679.232849] [154/156] (347s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[679.324429] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[679.325836] Starting dynamic subtest: pipe-A-eDP-1
[679.336920] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[679.347369] Starting dynamic subtest: pipe-B-eDP-1
[679.356930] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[679.367075] Starting dynamic subtest: pipe-C-eDP-1
[679.378543] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[679.388314] Starting dynamic subtest: pipe-D-eDP-1
[679.399022] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[679.400823] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[681.599905] [155/156] (344s left) syncobj_wait (multi-wait-for-submit-submitted)
[681.649039] Starting subtest: multi-wait-for-submit-submitted
[681.756471] Subtest multi-wait-for-submit-submitted: SUCCESS (0.107s)
[681.833802] [156/156] (344s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[681.930001] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[681.931903] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[682.033637] 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.