[104.418674] Initializing watchdogs
[104.418720]   /dev/watchdog0
[104.432899] [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]
[104.437437] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[104.439787] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[104.443346] [001/132] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[104.562918] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[104.566776] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[104.639562] [002/132] (960s left) gem_exec_balancer (busy)
[104.701623] Starting subtest: busy
[105.939418] Subtest busy: SUCCESS (1.235s)
[106.253063] [003/132] (958s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[106.307863] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[106.310159] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[106.387711] [004/132] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[106.486042] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[106.490280] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[106.577516] [005/132] (958s left) gem_readwrite (read-bad-handle)
[106.631630] Subtest read-bad-handle: SKIP (0.000s)
[106.688492] [006/132] (958s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[106.781392] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[106.785629] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[106.871581] [007/132] (958s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[106.985849] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[106.990148] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[107.075846] [008/132] (958s left) kms_flip (flip-vs-blocking-wf-vblank)
[107.171988] Starting subtest: flip-vs-blocking-wf-vblank
[107.173925] Starting dynamic subtest: A-eDP1
[116.146593] Dynamic subtest A-eDP1: SUCCESS (8.971s)
[116.155876] Starting dynamic subtest: B-eDP1
[125.073985] Dynamic subtest B-eDP1: SUCCESS (8.924s)
[125.081781] Starting dynamic subtest: C-eDP1
[133.999755] Dynamic subtest C-eDP1: SUCCESS (8.922s)
[134.007270] Starting dynamic subtest: D-eDP1
[142.933215] Dynamic subtest D-eDP1: SUCCESS (8.930s)
[142.938387] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.758s)
[144.175352] [009/132] (921s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[144.235506] Subtest writes-after-reads-uncached: SKIP (0.000s)
[144.294123] [010/132] (920s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[144.395713] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[144.398098] Starting dynamic subtest: pipe-A-eDP-1
[144.423223] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[144.431556] Starting dynamic subtest: pipe-B-eDP-1
[144.454771] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[144.461869] Starting dynamic subtest: pipe-C-eDP-1
[144.485193] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[144.492434] Starting dynamic subtest: pipe-D-eDP-1
[144.516254] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[144.520159] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[146.639920] [011/132] (918s left) kms_pm_rpm (drm-resources-equal)
[148.176736] Starting subtest: drm-resources-equal
[153.536858] Subtest drm-resources-equal: SUCCESS (5.361s)
[153.625719] [012/132] (911s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[153.735369] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[153.739432] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[153.812225] [013/132] (911s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[153.910387] Starting subtest: fbcpsr-suspend
[194.250495] Subtest fbcpsr-suspend: SUCCESS (10.168s)
[194.365031] [014/132] (871s left) syncobj_wait (wait-any-snapshot)
[194.438131] Starting subtest: wait-any-snapshot
[194.520023] Subtest wait-any-snapshot: SUCCESS (0.081s)
[194.600222] [015/132] (870s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[194.699523] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[202.299408] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.485s)
[202.409949] [016/132] (863s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[202.515341] Starting subtest: fbcpsr-tiling-4
[209.661836] Subtest fbcpsr-tiling-4: SUCCESS (7.107s)
[209.796757] [017/132] (855s left) core_setmaster (master-drop-set-user)
[209.894956] Starting subtest: master-drop-set-user
[209.919389] Subtest master-drop-set-user: SUCCESS (0.025s)
[209.960696] [018/132] (855s left) gem_exec_params (no-vebox)
[210.019265] Starting subtest: no-vebox
[210.021693] Subtest no-vebox: SUCCESS (0.003s)
[212.047841] [019/132] (853s left) kms_prop_blob (invalid-get-prop-any)
[212.109427] Starting subtest: invalid-get-prop-any
[212.111659] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[212.192311] [020/132] (853s left) kms_invalid_mode (bad-vsync-end)
[212.301603] Starting subtest: bad-vsync-end
[212.304373] Starting dynamic subtest: pipe-A-eDP-1
[212.592081] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[212.601303] Starting dynamic subtest: pipe-B-eDP-1
[212.603564] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[212.611387] Starting dynamic subtest: pipe-C-eDP-1
[212.613989] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[212.622526] Starting dynamic subtest: pipe-D-eDP-1
[212.625437] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[212.628264] Subtest bad-vsync-end: SUCCESS (0.293s)
[213.402636] [021/132] (852s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[213.497488] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[213.502030] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[213.581919] [022/132] (852s left) perf (sysctl-defaults)
[213.654647] Starting subtest: sysctl-defaults
[213.657578] Subtest sysctl-defaults: SUCCESS (0.000s)
[213.773331] [023/132] (852s left) kms_psr (fbc-psr2-basic)
[213.862375] Starting subtest: fbc-psr2-basic
[213.865056] Starting dynamic subtest: eDP-1
[213.870481] Dynamic subtest eDP-1: SKIP (0.000s)
[213.875522] Subtest fbc-psr2-basic: SKIP (0.001s)
[213.936134] [024/132] (851s left) syncobj_wait (single-wait-for-submit-signaled)
[213.991558] Starting subtest: single-wait-for-submit-signaled
[213.994501] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[214.088906] [025/132] (851s left) kms_dp_link_training (uhbr-sst)
[214.171364] Starting subtest: uhbr-sst
[214.182110] Subtest uhbr-sst: SKIP (0.000s)
[214.244874] [026/132] (851s left) kms_async_flips (test-time-stamp)
[214.332168] Starting subtest: test-time-stamp
[214.719487] Starting dynamic subtest: pipe-A-eDP-1
[215.519543] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.795s)
[215.906606] Starting dynamic subtest: pipe-B-eDP-1
[216.661665] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.756s)
[217.039979] Starting dynamic subtest: pipe-C-eDP-1
[217.807806] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.769s)
[218.169688] Starting dynamic subtest: pipe-D-eDP-1
[218.937023] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.770s)
[218.939875] Subtest test-time-stamp: SUCCESS (4.594s)
[220.102862] [027/132] (845s left) gem_ctx_persistence (file)
[220.168643] Starting subtest: file
[220.282643] Subtest file: SUCCESS (0.114s)
[220.414325] [028/132] (845s left) gem_exec_schedule (u-lateslice)
[220.497429] Starting subtest: u-lateslice
[220.499972] Starting dynamic subtest: rcs0
[220.535276] Dynamic subtest rcs0: SUCCESS (0.040s)
[220.545565] Starting dynamic subtest: bcs0
[220.567037] Dynamic subtest bcs0: SUCCESS (0.030s)
[220.576249] Starting dynamic subtest: vcs0
[220.597330] Dynamic subtest vcs0: SUCCESS (0.029s)
[220.606823] Starting dynamic subtest: vcs1
[220.626704] Dynamic subtest vcs1: SUCCESS (0.028s)
[220.635132] Starting dynamic subtest: vecs0
[220.652227] Dynamic subtest vecs0: SUCCESS (0.024s)
[220.668662] Starting dynamic subtest: ccs0
[220.671235] Dynamic subtest ccs0: SUCCESS (0.018s)
[220.673827] Subtest u-lateslice: SUCCESS (0.176s)
[221.975204] [029/132] (844s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[222.066204] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[222.071756] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[223.232927] [030/132] (842s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[223.331086] Starting subtest: fbc-rgb101010-draw-pwrite
[228.726768] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.407s)
[229.833767] [031/132] (836s left) kms_psr (fbc-pr-cursor-plane-onoff)
[229.927665] Starting subtest: fbc-pr-cursor-plane-onoff
[229.932384] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[229.999977] [032/132] (836s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[230.098245] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[230.103227] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[230.191880] [033/132] (836s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[230.573584] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[231.355831] [034/132] (834s left) syncobj_basic (create-signaled)
[231.406464] Starting subtest: create-signaled
[231.409340] Subtest create-signaled: SUCCESS (0.000s)
[231.508433] [035/132] (834s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[231.716400] Starting subtest: cursor-rapid-movement-128x128
[231.719047] Starting dynamic subtest: pipe-A-eDP-1
[232.298326] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[232.325548] Starting dynamic subtest: pipe-D-eDP-1
[233.469651] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.153s)
[233.472536] Subtest cursor-rapid-movement-128x128: SUCCESS (1.755s)
[234.336599] [036/132] (832s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[234.429094] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[234.433875] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[235.567666] [037/132] (830s left) gem_mmap_offset (pf-nonblock)
[235.634670] Starting subtest: pf-nonblock
[235.641427] Subtest pf-nonblock: SUCCESS (0.009s)
[235.765666] [038/132] (830s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[235.869392] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[235.874051] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[235.964936] [039/132] (830s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[236.068211] Starting subtest: psr2-cursor-plane-move-continuous-sf
[236.071843] Starting dynamic subtest: pipe-A-eDP-1
[246.430307] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.371s)
[246.440338] Starting dynamic subtest: pipe-B-eDP-1
[257.552579] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.122s)
[257.555569] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.496s)
[258.430464] [040/132] (808s left) i915_drm_fdinfo (busy-idle)
[258.480435] Starting subtest: busy-idle
[258.483144] Starting dynamic subtest: rcs0
[258.491629] Dynamic subtest rcs0: SKIP (0.010s)
[258.499543] Starting dynamic subtest: bcs0
[258.507677] Dynamic subtest bcs0: SKIP (0.015s)
[258.518098] Starting dynamic subtest: vcs0
[258.522800] Dynamic subtest vcs0: SKIP (0.000s)
[258.531339] Starting dynamic subtest: vcs1
[258.536572] Dynamic subtest vcs1: SKIP (0.000s)
[258.545451] Starting dynamic subtest: vecs0
[258.550398] Dynamic subtest vecs0: SKIP (0.000s)
[258.558646] Starting dynamic subtest: ccs0
[258.563637] Dynamic subtest ccs0: SKIP (0.000s)
[258.568674] Subtest busy-idle: SKIP (0.029s)
[258.605384] [041/132] (807s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[258.733847] Starting subtest: basic-flip-after-cursor-varying-size
[259.669440] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.934s)
[260.470007] [042/132] (806s left) kms_psr (fbc-psr2-sprite-plane-move)
[260.566639] Starting subtest: fbc-psr2-sprite-plane-move
[260.569466] Starting dynamic subtest: eDP-1
[260.574628] Dynamic subtest eDP-1: SKIP (0.000s)
[260.579848] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[260.639384] [043/132] (806s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[260.728152] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[260.733225] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[260.822978] [044/132] (805s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[260.921439] Starting subtest: yf-tiled-8bpp-rotate-0
[260.926528] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[261.026394] [045/132] (805s left) gem_ccs (large-ctrl-surf-copy)
[261.078451] Starting subtest: large-ctrl-surf-copy
[261.084129] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[261.171536] [046/132] (805s left) gem_ctx_isolation (preservation-s3)
[261.230554] Starting subtest: preservation-S3
[261.233427] Starting dynamic subtest: rcs0
[277.525067] Dynamic subtest rcs0: SUCCESS (1.304s)
[277.534469] Starting dynamic subtest: bcs0
[293.569287] Dynamic subtest bcs0: SUCCESS (1.320s)
[293.580265] Starting dynamic subtest: vcs0
[309.556671] Dynamic subtest vcs0: SUCCESS (3.108s)
[309.570580] Starting dynamic subtest: vcs1
[327.545979] Dynamic subtest vcs1: SUCCESS (4.855s)
[327.556246] Starting dynamic subtest: vecs0
[345.558171] Dynamic subtest vecs0: SUCCESS (4.861s)
[345.569969] Starting dynamic subtest: ccs0
[363.546047] Dynamic subtest ccs0: SUCCESS (3.107s)
[363.548426] Subtest preservation-S3: SUCCESS (18.560s)
[363.793739] [047/132] (703s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[363.924060] Starting subtest: cursorA-vs-flipB-atomic-transitions
[363.928673] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[364.087779] [048/132] (702s left) perf (gen12-group-exclusive-stream-ctx-handle)
[364.188666] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[364.216700] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.026s)
[364.264616] [049/132] (702s left) syncobj_basic (test-valid-cycle)
[366.214395] Starting subtest: test-valid-cycle
[366.217033] Subtest test-valid-cycle: SUCCESS (0.000s)
[366.335042] [050/132] (700s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[366.402227] Starting subtest: mixed-tiled-to-y-tiled-ccs
[366.406879] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[366.584454] [051/132] (700s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[366.671627] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[366.698068] Starting dynamic subtest: pipe-A-plane-0
[366.993832] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[367.002827] Starting dynamic subtest: pipe-A-plane-3
[367.243602] Dynamic subtest pipe-A-plane-3: SUCCESS (0.244s)
[367.251684] Starting dynamic subtest: pipe-A-plane-5
[367.255012] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[368.396032] Starting dynamic subtest: pipe-B-plane-0
[368.706948] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[368.715203] Starting dynamic subtest: pipe-B-plane-3
[368.944415] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[368.953023] Starting dynamic subtest: pipe-B-plane-5
[368.957262] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[369.267422] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.543s)
[370.104980] [052/132] (696s left) syncobj_timeline (etime-multi-wait-all-submitted)
[370.162809] Starting subtest: etime-multi-wait-all-submitted
[370.271805] Subtest etime-multi-wait-all-submitted: SUCCESS (0.107s)
[370.345268] [053/132] (696s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[370.471597] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[370.476041] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[370.552924] [054/132] (696s left) kms_addfb_basic (bad-pitch-0)
[370.608735] Starting subtest: bad-pitch-0
[370.610946] Subtest bad-pitch-0: SUCCESS (0.000s)
[370.765903] [055/132] (696s left) fbdev (write)
[370.806282] Starting subtest: write
[371.170323] Subtest write: SUCCESS (0.359s)
[371.233146] [056/132] (695s left) kms_cursor_edge_walk (128x128-left-edge)
[371.325151] Starting subtest: 128x128-left-edge
[371.327332] Starting dynamic subtest: pipe-A-eDP-1
[374.839615] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.478s)
[374.848602] Starting dynamic subtest: pipe-D-eDP-1
[377.979394] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.115s)
[377.981692] Subtest 128x128-left-edge: SUCCESS (6.599s)
[378.802055] [057/132] (688s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[378.899083] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[378.903460] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.000s)
[378.969046] [058/132] (688s left) gem_spin_batch (spin-each)
[379.019037] Starting subtest: spin-each
[382.391518] Subtest spin-each: SUCCESS (3.350s)
[384.284860] [059/132] (683s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[384.342140] Starting subtest: rel-constants-invalid-rel-gen5
[384.344557] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[384.450848] [060/132] (682s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[384.576710] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[385.347333] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.770s)
[385.504675] [061/132] (681s left) kms_psr (psr-suspend)
[385.593203] Starting subtest: psr-suspend
[385.595321] Starting dynamic subtest: eDP-1
[402.922835] Dynamic subtest eDP-1: SUCCESS (2.909s)
[402.925107] Subtest psr-suspend: SUCCESS (2.910s)
[403.810964] [062/132] (663s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[403.910754] Starting subtest: addfb25-y-tiled-legacy
[403.912979] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[403.998977] [063/132] (663s left) gem_lmem_swapping (heavy-random)
[404.048743] Subtest heavy-random: SKIP (0.000s)
[404.101812] [064/132] (663s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[404.192230] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[404.196187] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[404.279216] [065/132] (663s left) kms_plane_lowres (tiling-none)
[404.377075] Starting subtest: tiling-none
[404.379211] Starting dynamic subtest: pipe-A-eDP-1
[404.383628] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[404.673455] Starting dynamic subtest: pipe-B-eDP-1
[404.678095] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[404.686668] Starting dynamic subtest: pipe-C-eDP-1
[404.691303] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[404.699706] Starting dynamic subtest: pipe-D-eDP-1
[404.704307] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[404.708688] Subtest tiling-none: SKIP (0.296s)
[405.521615] [066/132] (662s left) kms_psr (psr2-sprite-plane-onoff)
[405.613113] Starting subtest: psr2-sprite-plane-onoff
[405.615269] Starting dynamic subtest: eDP-1
[407.317980] Dynamic subtest eDP-1: SUCCESS (1.701s)
[407.320209] Subtest psr2-sprite-plane-onoff: SUCCESS (1.703s)
[408.185412] [067/132] (659s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[408.293035] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[415.550939] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.103s)
[415.665195] [068/132] (651s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[416.065399] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[416.896759] [069/132] (650s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[416.990278] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[417.790524] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.793s)
[417.941308] [070/132] (649s left) prime_mmap_coherency (read)
[418.000190] Starting subtest: read
[423.413598] Subtest read: SUCCESS (5.374s)
[423.542746] [071/132] (644s left) kms_panel_fitting (atomic-fastset)
[423.642740] Starting subtest: atomic-fastset
[423.645102] Starting dynamic subtest: pipe-A-eDP-1
[423.782127] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.141s)
[423.791392] Starting dynamic subtest: pipe-B-eDP-1
[425.037246] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.248s)
[425.046597] Starting dynamic subtest: pipe-C-eDP-1
[426.226363] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.183s)
[426.235573] Starting dynamic subtest: pipe-D-eDP-1
[427.366259] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[427.368556] Subtest atomic-fastset: SUCCESS (3.711s)
[428.534685] [072/132] (639s left) kms_color_pipeline (plane-lut1d)
[428.641172] Starting subtest: plane-lut1d
[428.788259] Starting dynamic subtest: pipe-A-plane-0
[429.138773] Dynamic subtest pipe-A-plane-0: FAIL (0.191s)
[429.151068] Starting dynamic subtest: pipe-A-plane-1
[429.357100] Dynamic subtest pipe-A-plane-1: FAIL (0.171s)
[429.365962] Starting dynamic subtest: pipe-A-plane-2
[429.586748] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[430.566561] Starting dynamic subtest: pipe-B-plane-0
[431.012898] Dynamic subtest pipe-B-plane-0: FAIL (0.185s)
[431.022005] Starting dynamic subtest: pipe-B-plane-1
[431.323521] Dynamic subtest pipe-B-plane-1: FAIL (0.166s)
[431.332527] Starting dynamic subtest: pipe-B-plane-2
[431.617297] Dynamic subtest pipe-B-plane-2: FAIL (0.177s)
[432.292124] Starting dynamic subtest: pipe-C-plane-0
[432.775049] Dynamic subtest pipe-C-plane-0: FAIL (0.140s)
[432.784516] Starting dynamic subtest: pipe-C-plane-1
[433.166324] Dynamic subtest pipe-C-plane-1: FAIL (0.155s)
[433.176533] Starting dynamic subtest: pipe-C-plane-2
[433.522287] Dynamic subtest pipe-C-plane-2: FAIL (0.631s)
[434.402740] Starting dynamic subtest: pipe-D-plane-0
[434.989485] Dynamic subtest pipe-D-plane-0: FAIL (0.151s)
[435.000156] Starting dynamic subtest: pipe-D-plane-1
[435.433349] Dynamic subtest pipe-D-plane-1: FAIL (0.615s)
[435.443589] Starting dynamic subtest: pipe-D-plane-2
[435.853703] Dynamic subtest pipe-D-plane-2: FAIL (0.562s)
[436.036643] Subtest plane-lut1d: FAIL (7.381s)
[436.901386] [073/132] (630s left) kms_force_connector_basic (force-connector-state)
[436.983439] Starting subtest: force-connector-state
[437.593625] Subtest force-connector-state: SUCCESS (0.612s)
[438.550545] [074/132] (629s left) kms_psr (pr-cursor-blt)
[438.636286] Starting subtest: pr-cursor-blt
[438.640825] Subtest pr-cursor-blt: SKIP (0.000s)
[438.708637] [075/132] (629s left) kms_atomic_interruptible (legacy-cursor)
[438.791821] Starting subtest: legacy-cursor
[438.794074] Starting dynamic subtest: pipe-A-eDP-1
[446.192732] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.398s)
[446.194895] Subtest legacy-cursor: SUCCESS (7.399s)
[447.019556] [076/132] (620s left) gem_exec_whisper (basic-fds-forked)
[447.087421] Starting subtest: basic-fds-forked
[471.349488] Subtest basic-fds-forked: SUCCESS (24.253s)
[471.529979] [077/132] (596s left) gem_exec_schedule (deep)
[471.717051] Starting subtest: deep
[471.719797] Starting dynamic subtest: rcs0
[478.082445] Dynamic subtest rcs0: SKIP (6.360s)
[478.092528] Starting dynamic subtest: vcs0
[488.442538] Dynamic subtest vcs0: SUCCESS (10.357s)
[488.455402] Starting dynamic subtest: vcs1
[500.018893] Dynamic subtest vcs1: SUCCESS (11.569s)
[500.032168] Starting dynamic subtest: vecs0
[510.989138] Dynamic subtest vecs0: SUCCESS (10.970s)
[511.003977] Starting dynamic subtest: ccs0
[523.468042] Dynamic subtest ccs0: SUCCESS (12.481s)
[523.471022] Subtest deep: SUCCESS (51.743s)
[524.497190] [FACT gem_exec_schedule (deep)] new: kernel.kmod_is_loaded.vgem: true
[524.501541] [078/132] (543s left) kms_dp_link_training (uhbr-mst)
[524.595708] Starting subtest: uhbr-mst
[524.604650] Subtest uhbr-mst: SKIP (0.000s)
[524.671437] [079/132] (543s left) kms_flip (2x-flip-vs-panning-vs-hang)
[524.762867] Starting subtest: 2x-flip-vs-panning-vs-hang
[524.774246] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.011s)
[524.857731] [080/132] (543s left) gem_mmap_gtt (zero-extend)
[524.911721] Subtest zero-extend: SKIP (0.000s)
[524.970085] [081/132] (543s left) kms_setmode (invalid-clone-exclusive-crtc)
[525.038906] Starting subtest: invalid-clone-exclusive-crtc
[525.045528] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[525.151901] [082/132] (543s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[525.237562] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[525.239785] Starting dynamic subtest: pipe-A-default-mode
[526.379765] Dynamic subtest pipe-A-default-mode: SKIP (1.140s)
[526.383851] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.141s)
[526.513757] [083/132] (541s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[526.619960] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[533.152059] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.533s)
[533.266609] [084/132] (535s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[533.374777] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[533.379169] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[533.491437] [085/132] (534s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[533.584861] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[533.587133] Starting dynamic subtest: pipe-A-eDP-1
[533.610041] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[533.623542] Starting dynamic subtest: pipe-B-eDP-1
[533.645145] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[533.655877] Starting dynamic subtest: pipe-C-eDP-1
[533.676839] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[533.685965] Starting dynamic subtest: pipe-D-eDP-1
[533.707703] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[533.711995] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.011s)
[535.836727] [086/132] (532s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[535.948860] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[535.953121] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[536.047315] [087/132] (532s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[536.144889] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[536.149309] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[536.247865] [088/132] (532s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[536.309063] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[536.313492] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[536.506130] [089/132] (532s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[536.612704] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[536.614985] Starting dynamic subtest: pipe-A
[536.635532] Dynamic subtest pipe-A: SKIP (0.019s)
[536.645328] Starting dynamic subtest: pipe-B
[536.668152] Dynamic subtest pipe-B: SKIP (0.032s)
[536.677919] Starting dynamic subtest: pipe-C
[536.702347] Dynamic subtest pipe-C: SKIP (0.033s)
[536.713257] Starting dynamic subtest: pipe-D
[536.735691] Dynamic subtest pipe-D: SKIP (0.033s)
[536.739458] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.120s)
[536.842836] [090/132] (531s left) prime_vgem (basic-fence-flip)
[536.911389] Starting subtest: basic-fence-flip
[537.262573] Subtest basic-fence-flip: SUCCESS (0.351s)
[537.372336] [091/132] (531s left) gem_tiled_fence_blits (basic)
[537.440986] Subtest basic: SKIP (0.000s)
[537.535776] [092/132] (531s left) kms_cursor_crc (cursor-offscreen-512x512)
[537.819144] Starting subtest: cursor-offscreen-512x512
[537.823842] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[537.990667] [093/132] (530s left) api_intel_bb (full-batch)
[538.049533] Starting subtest: full-batch
[538.051766] Subtest full-batch: SUCCESS (0.004s)
[538.156765] [094/132] (530s left) perf_pmu (rc6-all-gts)
[538.194965] Starting subtest: rc6-all-gts
[544.564922] Subtest rc6-all-gts: SUCCESS (6.368s)
[545.421674] [095/132] (523s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[545.525040] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[549.752367] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.230s)
[550.933912] [096/132] (517s left) gem_userptr_blits (coherency-unsync)
[550.992637] Subtest coherency-unsync: SKIP (0.000s)
[551.131006] [097/132] (517s left) syncobj_basic (bad-flags-handle-to-fd)
[551.178359] Starting subtest: bad-flags-handle-to-fd
[551.180536] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[551.284451] [098/132] (517s left) gem_exec_fence (basic-busy)
[551.462187] Starting subtest: basic-busy
[551.464551] Starting dynamic subtest: rcs0
[551.528836] Dynamic subtest rcs0: SUCCESS (0.069s)
[551.541205] Starting dynamic subtest: bcs0
[551.597070] Dynamic subtest bcs0: SUCCESS (0.066s)
[551.609049] Starting dynamic subtest: vcs0
[551.659070] Dynamic subtest vcs0: SUCCESS (0.060s)
[551.669506] Starting dynamic subtest: vcs1
[551.722624] Dynamic subtest vcs1: SUCCESS (0.062s)
[551.737050] Starting dynamic subtest: vecs0
[551.784739] Dynamic subtest vecs0: SUCCESS (0.059s)
[551.796071] Starting dynamic subtest: ccs0
[551.843732] Dynamic subtest ccs0: SUCCESS (0.057s)
[551.846216] Subtest basic-busy: SUCCESS (0.386s)
[553.796506] [099/132] (515s left) i915_pm_rps (thresholds-idle)
[553.861101] Starting subtest: thresholds-idle
[553.865476] Subtest thresholds-idle: SKIP (0.001s)
[553.937155] [100/132] (515s left) i915_pm_rpm (debugfs-read)
[555.879788] Starting subtest: debugfs-read
[557.037920] Subtest debugfs-read: SUCCESS (1.159s)
[557.430953] [101/132] (511s left) i915_hangman (detector)
[557.504385] Starting subtest: detector
[557.506504] Starting dynamic subtest: rcs0
[568.655835] Dynamic subtest rcs0: SUCCESS (11.149s)
[568.668242] Starting dynamic subtest: bcs0
[579.663948] Dynamic subtest bcs0: SUCCESS (11.002s)
[579.676756] Starting dynamic subtest: vcs0
[591.582763] Dynamic subtest vcs0: SUCCESS (11.896s)
[591.595171] Starting dynamic subtest: vcs1
[602.576040] Dynamic subtest vcs1: SUCCESS (11.004s)
[602.588528] Starting dynamic subtest: vecs0
[613.585009] Dynamic subtest vecs0: SUCCESS (11.002s)
[613.597816] Starting dynamic subtest: ccs0
[624.657211] Dynamic subtest ccs0: SUCCESS (11.065s)
[624.660138] Subtest detector: SUCCESS (67.135s)
[624.739877] [102/132] (444s left) syncobj_timeline (invalid-signal-illegal-handle)
[624.799647] Starting subtest: invalid-signal-illegal-handle
[624.802098] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[624.916304] [103/132] (444s left) sysfs_timeslice_duration (invalid)
[624.983163] Starting subtest: invalid
[624.985680] Starting dynamic subtest: vecs0
[624.988174] Dynamic subtest vecs0: SUCCESS (0.000s)
[624.999959] Starting dynamic subtest: ccs0
[625.002261] Dynamic subtest ccs0: SUCCESS (0.000s)
[625.011674] Starting dynamic subtest: vcs0
[625.013822] Dynamic subtest vcs0: SUCCESS (0.000s)
[625.023253] Starting dynamic subtest: bcs0
[625.025546] Dynamic subtest bcs0: SUCCESS (0.000s)
[625.035069] Starting dynamic subtest: rcs0
[625.037319] Dynamic subtest rcs0: SUCCESS (0.000s)
[625.046783] Starting dynamic subtest: vcs1
[625.048964] Dynamic subtest vcs1: SUCCESS (0.000s)
[625.051170] Subtest invalid: SUCCESS (0.006s)
[625.095480] [104/132] (444s left) syncobj_eventfd (timeline-wait)
[625.151617] Starting subtest: timeline-wait
[625.160196] Subtest timeline-wait: SUCCESS (0.010s)
[625.263568] [105/132] (443s left) gem_exec_reloc (basic-gtt-noreloc)
[625.316455] Subtest basic-gtt-noreloc: SKIP (0.000s)
[625.394826] [106/132] (443s left) api_intel_allocator (gem-pool)
[625.450342] Starting subtest: gem-pool
[625.463936] Subtest gem-pool: SUCCESS (0.016s)
[625.579978] [107/132] (443s left) prime_busy (hang)
[625.647953] Starting subtest: hang
[625.650249] Starting dynamic subtest: rcs0
[652.170205] Dynamic subtest rcs0: SUCCESS (26.522s)
[652.179693] Starting dynamic subtest: bcs0
[659.322993] Dynamic subtest bcs0: SUCCESS (7.154s)
[659.332776] Starting dynamic subtest: vcs0
[666.367569] Dynamic subtest vcs0: SUCCESS (7.043s)
[666.377011] Starting dynamic subtest: vcs1
[673.341928] Dynamic subtest vcs1: SUCCESS (6.973s)
[673.352054] Starting dynamic subtest: vecs0
[680.316544] Dynamic subtest vecs0: SUCCESS (6.975s)
[680.325995] Starting dynamic subtest: ccs0
[694.222922] Dynamic subtest ccs0: SUCCESS (13.902s)
[694.224999] Subtest hang: SUCCESS (68.576s)
[694.333143] [108/132] (375s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[694.432084] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[694.436190] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[694.535150] [109/132] (374s left) gem_exec_schedule (preemptive-hang)
[694.720339] Starting subtest: preemptive-hang
[694.723228] Starting dynamic subtest: rcs0
[703.770810] Dynamic subtest rcs0: SUCCESS (9.050s)
[703.781719] Starting dynamic subtest: bcs0
[712.794520] Dynamic subtest bcs0: SUCCESS (9.019s)
[712.804715] Starting dynamic subtest: vcs0
[721.882776] Dynamic subtest vcs0: SUCCESS (9.088s)
[721.892196] Starting dynamic subtest: vcs1
[730.794020] Dynamic subtest vcs1: SUCCESS (8.908s)
[730.811139] Starting dynamic subtest: vecs0
[739.798682] Dynamic subtest vecs0: SUCCESS (9.000s)
[739.810899] Starting dynamic subtest: ccs0
[748.829538] Dynamic subtest ccs0: SUCCESS (9.027s)
[748.832180] Subtest preemptive-hang: SUCCESS (54.103s)
[749.055459] [110/132] (320s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[749.162533] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[749.166864] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[749.258436] [111/132] (320s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[749.366933] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[749.369303] Starting dynamic subtest: pipe-A-eDP-1
[749.661739] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.263s)
[749.672387] Starting dynamic subtest: pipe-B-eDP-1
[750.980132] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.318s)
[750.991519] Starting dynamic subtest: pipe-C-eDP-1
[752.243127] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.262s)
[752.253773] Starting dynamic subtest: pipe-D-eDP-1
[753.448216] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.206s)
[753.450565] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.053s)
[754.621052] [112/132] (314s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[754.708545] Starting subtest: coverage-vs-premult-vs-constant
[755.001310] Starting dynamic subtest: pipe-A-eDP-1
[755.581835] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.580s)
[755.591899] Starting dynamic subtest: pipe-D-eDP-1
[756.923125] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.338s)
[756.925375] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.214s)
[757.737895] [113/132] (311s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[757.828757] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[761.777169] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (3.950s)
[762.936271] [114/132] (306s left) gem_mmap (big-bo)
[762.994859] Subtest big-bo: SKIP (0.000s)
[763.068236] [115/132] (306s left) syncobj_wait (wait-for-submit-snapshot)
[763.127493] Starting subtest: wait-for-submit-snapshot
[763.209593] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[763.292218] [116/132] (306s left) syncobj_wait (invalid-reset-bad-pad)
[763.342762] Starting subtest: invalid-reset-bad-pad
[763.344656] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[763.460759] [117/132] (306s left) kms_lease (lease-get)
[763.554994] Starting subtest: lease-get
[763.557188] Starting dynamic subtest: pipe-A-eDP-1
[763.559442] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[763.569549] Starting dynamic subtest: pipe-B-eDP-1
[763.571384] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[763.580817] Starting dynamic subtest: pipe-C-eDP-1
[763.582673] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[763.592115] Starting dynamic subtest: pipe-D-eDP-1
[763.594063] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[763.595875] Subtest lease-get: SUCCESS (0.009s)
[763.674074] [118/132] (306s left) gem_exec_reloc (basic-gtt-cpu-active)
[763.740280] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[763.808962] [119/132] (306s left) kms_vblank (query-forked-busy-hang)
[763.899547] Starting subtest: query-forked-busy-hang
[763.901429] Starting dynamic subtest: pipe-A-eDP-1
[790.463069] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.555s)
[790.478668] Starting dynamic subtest: pipe-D-eDP-1
[815.418929] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.949s)
[815.421251] Subtest query-forked-busy-hang: SUCCESS (51.509s)
[816.233386] [120/132] (253s left) kms_concurrent (multi-plane-atomic-lowres)
[816.327622] Starting subtest: multi-plane-atomic-lowres
[816.329950] Starting dynamic subtest: pipe-A-eDP-1
[816.824150] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.495s)
[816.835240] Starting dynamic subtest: pipe-B-eDP-1
[818.045403] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.218s)
[818.055265] Starting dynamic subtest: pipe-C-eDP-1
[819.231559] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.187s)
[819.248505] Starting dynamic subtest: pipe-D-eDP-1
[820.440427] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.205s)
[820.442782] Subtest multi-plane-atomic-lowres: SUCCESS (4.110s)
[821.242078] [121/132] (248s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[821.332809] Starting subtest: single-wait-all-for-submit-available-signaled
[821.335151] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[821.476856] [122/132] (248s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[821.711297] Starting subtest: 2x-cursor-vs-flip-legacy
[821.715904] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.002s)
[821.876018] [123/132] (248s left) gem_ctx_sseu (mmap-args)
[822.045713] Subtest mmap-args: SKIP (0.000s)
[822.332609] [124/132] (247s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[822.518768] Starting subtest: cursorB-vs-flipB-legacy
[822.523538] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[822.671948] [125/132] (247s left) gem_exec_fence (submit67)
[822.917122] Starting subtest: submit67
[822.923276] Subtest submit67: SKIP (0.000s)
[824.780396] [126/132] (245s left) gen9_exec_parse (bb-start-out)
[824.852665] Subtest bb-start-out: SKIP (0.000s)
[824.916377] [127/132] (245s left) gem_wait (write-wait)
[824.989959] Starting subtest: write-wait
[824.992503] Starting dynamic subtest: all-engines
[826.113993] Dynamic subtest all-engines: SUCCESS (1.122s)
[826.130843] Starting dynamic subtest: rcs0
[827.171079] Dynamic subtest rcs0: SUCCESS (1.055s)
[827.188437] Starting dynamic subtest: bcs0
[828.230534] Dynamic subtest bcs0: SUCCESS (1.057s)
[828.247647] Starting dynamic subtest: vcs0
[829.284853] Dynamic subtest vcs0: SUCCESS (1.052s)
[829.302382] Starting dynamic subtest: vcs1
[830.338956] Dynamic subtest vcs1: SUCCESS (1.052s)
[830.356330] Starting dynamic subtest: vecs0
[831.392661] Dynamic subtest vecs0: SUCCESS (1.051s)
[831.409973] Starting dynamic subtest: ccs0
[832.450022] Dynamic subtest ccs0: SUCCESS (1.055s)
[832.453493] Subtest write-wait: SUCCESS (7.459s)
[832.652610] [128/132] (237s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[832.766955] Starting subtest: 4-tiled-64bpp-rotate-0
[834.291313] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.524s)
[834.457936] [129/132] (235s left) gem_userptr_blits (map-fixed-invalidate-busy)
[834.520745] Starting subtest: map-fixed-invalidate-busy
[834.524954] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[834.694291] [130/132] (235s left) kms_universal_plane (cursor-fb-leak)
[834.791986] Starting subtest: cursor-fb-leak
[834.793659] Starting dynamic subtest: pipe-A-eDP-1
[835.132017] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.341s)
[835.146994] Starting dynamic subtest: pipe-B-eDP-1
[836.533651] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.399s)
[836.550592] Starting dynamic subtest: pipe-C-eDP-1
[837.895200] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.358s)
[837.905917] Starting dynamic subtest: pipe-D-eDP-1
[839.216817] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.320s)
[839.219260] Subtest cursor-fb-leak: SUCCESS (4.427s)
[840.460252] [131/132] (230s left) gem_media_fill (media-fill)
[840.513264] Subtest media-fill: SKIP (0.000s)
[840.625595] [132/132] (229s left) gem_ctx_param (non-root-set)
[840.675222] Starting subtest: non-root-set
[840.690162] Subtest non-root-set: SUCCESS (0.015s)
[840.790186] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_readwrite
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_flip
results: parsing output: 8/ for test: gem_partial_pwrite_pread
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: kms_pm_rpm
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: core_setmaster
results: parsing output: 17/ for test: gem_exec_params
results: parsing output: 18/ for test: kms_prop_blob
results: parsing output: 19/ for test: kms_invalid_mode
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: perf
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: kms_dp_link_training
results: parsing output: 25/ for test: kms_async_flips
results: parsing output: 26/ for test: gem_ctx_persistence
results: parsing output: 27/ for test: gem_exec_schedule
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_chamelium_hpd
results: parsing output: 33/ for test: syncobj_basic
results: parsing output: 34/ for test: kms_cursor_crc
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: gem_mmap_offset
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_psr2_sf
results: parsing output: 39/ for test: i915_drm_fdinfo
results: parsing output: 40/ for test: kms_cursor_legacy
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_ccs
results: parsing output: 45/ for test: gem_ctx_isolation
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: syncobj_basic
results: parsing output: 49/ for test: gem_render_copy
results: parsing output: 50/ for test: kms_plane
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_addfb_basic
results: parsing output: 54/ for test: fbdev
results: parsing output: 55/ for test: kms_cursor_edge_walk
results: parsing output: 56/ for test: kms_flip_scaled_crc
results: parsing output: 57/ for test: gem_spin_batch
results: parsing output: 58/ for test: gem_exec_params
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: kms_addfb_basic
results: parsing output: 62/ for test: gem_lmem_swapping
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_plane_lowres
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_chamelium_frames
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: prime_mmap_coherency
results: parsing output: 70/ for test: kms_panel_fitting
results: parsing output: 71/ for test: kms_color_pipeline
results: parsing output: 72/ for test: kms_force_connector_basic
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_atomic_interruptible
results: parsing output: 75/ for test: gem_exec_whisper
results: parsing output: 76/ for test: gem_exec_schedule
results: parsing output: 77/ for test: kms_dp_link_training
results: parsing output: 78/ for test: kms_flip
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: kms_setmode
results: parsing output: 81/ for test: kms_flip_scaled_crc
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_render_copy
results: parsing output: 88/ for test: kms_plane_scaling
results: parsing output: 89/ for test: prime_vgem
results: parsing output: 90/ for test: gem_tiled_fence_blits
results: parsing output: 91/ for test: kms_cursor_crc
results: parsing output: 92/ for test: api_intel_bb
results: parsing output: 93/ for test: perf_pmu
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_userptr_blits
results: parsing output: 96/ for test: syncobj_basic
results: parsing output: 97/ for test: gem_exec_fence
results: parsing output: 98/ for test: i915_pm_rps
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: i915_hangman
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: sysfs_timeslice_duration
results: parsing output: 103/ for test: syncobj_eventfd
results: parsing output: 104/ for test: gem_exec_reloc
results: parsing output: 105/ for test: api_intel_allocator
results: parsing output: 106/ for test: prime_busy
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_exec_schedule
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: kms_plane_alpha_blend
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_mmap
results: parsing output: 114/ for test: syncobj_wait
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_lease
results: parsing output: 117/ for test: gem_exec_reloc
results: parsing output: 118/ for test: kms_vblank
results: parsing output: 119/ for test: kms_concurrent
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: gem_ctx_sseu
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: gem_exec_fence
results: parsing output: 125/ for test: gen9_exec_parse
results: parsing output: 126/ for test: gem_wait
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: gem_userptr_blits
results: parsing output: 129/ for test: kms_universal_plane
results: parsing output: 130/ for test: gem_media_fill
results: parsing output: 131/ for test: gem_ctx_param
Done.