[58.241846] Initializing watchdogs
[58.241894]   /dev/watchdog0
[58.257477] [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]
[58.262078] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.264344] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.268150] [001/131] (960s left) prime_mmap (test_reprime)
[58.329864] Starting subtest: test_reprime
[58.332095] Starting dynamic subtest: test_reprime-smem
[58.361328] Dynamic subtest test_reprime-smem: SUCCESS (0.032s)
[58.363968] Subtest test_reprime: SUCCESS (0.033s)
[58.480541] [002/131] (960s left) gem_exec_balancer (parallel-bb-first)
[58.736293] Starting subtest: parallel-bb-first
[59.657726] Subtest parallel-bb-first: SUCCESS (0.923s)
[59.962946] [003/131] (958s left) sw_sync (timeline_closed)
[59.994862] Starting subtest: timeline_closed
[59.997073] Subtest timeline_closed: SUCCESS (0.000s)
[60.049613] [004/131] (958s left) gem_exec_schedule (manyslice)
[60.115910] Starting subtest: manyslice
[60.118487] Starting dynamic subtest: rcs0
[66.789584] Dynamic subtest rcs0: SUCCESS (6.690s)
[66.796841] Starting dynamic subtest: bcs0
[73.394330] Dynamic subtest bcs0: SUCCESS (6.609s)
[73.402319] Starting dynamic subtest: vcs0
[78.462616] Dynamic subtest vcs0: SUCCESS (5.071s)
[78.470686] Starting dynamic subtest: vcs1
[84.530121] Dynamic subtest vcs1: SUCCESS (6.069s)
[84.539806] Starting dynamic subtest: vecs0
[90.094563] Dynamic subtest vecs0: SUCCESS (5.566s)
[90.102971] Starting dynamic subtest: ccs0
[95.731795] Dynamic subtest ccs0: SUCCESS (5.637s)
[95.734931] Subtest manyslice: SUCCESS (35.645s)
[96.027211] [005/131] (922s left) core_getversion (basic)
[96.062068] Starting subtest: basic
[96.066246] Subtest basic: SUCCESS (0.000s)
[96.130301] [006/131] (922s left) kms_flip (plain-flip-ts-check-interruptible)
[96.237917] Starting subtest: plain-flip-ts-check-interruptible
[96.240253] Starting dynamic subtest: A-eDP1
[105.090089] Dynamic subtest A-eDP1: SUCCESS (8.851s)
[105.100481] Starting dynamic subtest: B-eDP1
[113.918696] Dynamic subtest B-eDP1: SUCCESS (8.829s)
[113.928568] Starting dynamic subtest: C-eDP1
[122.733478] Dynamic subtest C-eDP1: SUCCESS (8.813s)
[122.744600] Starting dynamic subtest: D-eDP1
[131.567263] Dynamic subtest D-eDP1: SUCCESS (8.833s)
[131.571969] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.335s)
[132.732206] [007/131] (886s left) syncobj_timeline (wait-all-for-submit-snapshot)
[132.798957] Starting subtest: wait-all-for-submit-snapshot
[132.879654] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[132.950508] [008/131] (886s left) gem_pxp (protected-raw-src-copy-not-readible)
[133.081054] Starting subtest: protected-raw-src-copy-not-readible
[133.141913] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s)
[133.273938] [009/131] (885s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[133.373073] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[137.625305] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.257s)
[138.766882] [010/131] (880s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[138.836925] Starting subtest: basic-batch-kernel-default-cmd
[138.841319] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[139.014813] [011/131] (880s left) i915_pm_rc6_residency (media-rc6-accuracy)
[139.071804] Starting subtest: media-rc6-accuracy
[139.075840] Subtest media-rc6-accuracy: SKIP (0.000s)
[139.164382] [012/131] (880s left) kms_flip (2x-flip-vs-expired-vblank)
[139.263186] Starting subtest: 2x-flip-vs-expired-vblank
[139.274281] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[139.348702] [013/131] (879s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[139.480175] Starting subtest: short-flip-before-cursor-toggle
[140.396658] Subtest short-flip-before-cursor-toggle: SUCCESS (0.916s)
[141.193773] [014/131] (878s left) kms_atomic (crtc-invalid-params)
[141.280967] Starting subtest: crtc-invalid-params
[141.283387] Starting dynamic subtest: pipe-A-eDP-1
[141.636800] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.353s)
[141.639117] Subtest crtc-invalid-params: SUCCESS (0.353s)
[142.506262] [015/131] (876s left) vgem_basic (bad-handle)
[142.594762] Starting subtest: bad-handle
[142.596879] Subtest bad-handle: SUCCESS (0.000s)
[142.666759] [FACT vgem_basic (bad-handle)] new: kernel.kmod_is_loaded.vgem: true
[142.669832] [016/131] (876s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[142.765058] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[142.767340] Starting dynamic subtest: pipe-A-default-mode
[145.557776] Dynamic subtest pipe-A-default-mode: SUCCESS (2.790s)
[145.560023] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.791s)
[146.408869] [017/131] (872s left) kms_addfb_basic (bad-pitch-128)
[146.454317] Starting subtest: bad-pitch-128
[146.456674] Subtest bad-pitch-128: SUCCESS (0.000s)
[146.601402] [018/131] (872s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[146.702797] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[153.748235] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.047s)
[153.914631] [019/131] (865s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[154.011755] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[154.014126] Starting dynamic subtest: pipe-A-eDP-1
[154.040555] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[154.054332] Starting dynamic subtest: pipe-B-eDP-1
[154.079369] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[154.088825] Starting dynamic subtest: pipe-C-eDP-1
[154.113018] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[154.121707] Starting dynamic subtest: pipe-D-eDP-1
[154.145166] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[154.149404] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.002s)
[156.309683] [020/131] (863s left) kms_vblank (query-idle-hang)
[156.410448] Starting subtest: query-idle-hang
[156.412602] Starting dynamic subtest: pipe-A-eDP-1
[182.675656] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.264s)
[182.688652] Starting dynamic subtest: pipe-D-eDP-1
[207.633846] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.954s)
[207.636070] Subtest query-idle-hang: SUCCESS (51.222s)
[208.459883] [021/131] (811s left) gem_exec_whisper (basic-queues-forked-all)
[208.515752] Starting subtest: basic-queues-forked-all
[226.273452] Subtest basic-queues-forked-all: SUCCESS (17.757s)
[226.463567] [022/131] (793s left) kms_content_protection (dp-mst-lic-type-1)
[226.625199] Starting subtest: dp-mst-lic-type-1
[226.629622] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[226.779091] [023/131] (792s left) kms_cursor_edge_walk (256x256-top-bottom)
[226.865823] Starting subtest: 256x256-top-bottom
[226.868116] Starting dynamic subtest: pipe-A-eDP-1
[230.327752] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.463s)
[230.337211] Starting dynamic subtest: pipe-D-eDP-1
[233.450491] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.119s)
[233.452738] Subtest 256x256-top-bottom: SUCCESS (6.584s)
[234.248022] [024/131] (785s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[234.649071] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[235.465962] [025/131] (784s left) kms_psr (pr-cursor-mmap-cpu)
[235.558042] Starting subtest: pr-cursor-mmap-cpu
[235.563176] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[235.628009] [026/131] (784s left) i915_hangman (detector)
[235.690219] Starting subtest: detector
[235.692118] Starting dynamic subtest: rcs0
[245.880662] Dynamic subtest rcs0: SUCCESS (10.189s)
[245.890964] Starting dynamic subtest: bcs0
[257.870578] Dynamic subtest bcs0: SUCCESS (11.963s)
[257.878305] Starting dynamic subtest: vcs0
[269.815593] Dynamic subtest vcs0: SUCCESS (11.969s)
[269.826308] Starting dynamic subtest: vcs1
[280.823350] Dynamic subtest vcs1: SUCCESS (11.006s)
[280.834437] Starting dynamic subtest: vecs0
[290.805726] Dynamic subtest vecs0: SUCCESS (9.980s)
[290.817090] Starting dynamic subtest: ccs0
[300.855274] Dynamic subtest ccs0: SUCCESS (10.047s)
[300.858397] Subtest detector: SUCCESS (65.160s)
[300.933963] [027/131] (718s left) kms_properties (crtc-properties-atomic)
[301.025103] Starting subtest: crtc-properties-atomic
[301.027489] Starting dynamic subtest: pipe-A-eDP-1
[301.462059] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.437s)
[301.472299] Starting dynamic subtest: pipe-B-eDP-1
[302.644157] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.182s)
[302.653061] Starting dynamic subtest: pipe-C-eDP-1
[303.761610] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.116s)
[303.771161] Starting dynamic subtest: pipe-D-eDP-1
[304.888983] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[304.891407] Subtest crtc-properties-atomic: SUCCESS (3.864s)
[305.705394] [028/131] (714s left) kms_dsc (dsc-fractional-bpp)
[305.801352] Starting subtest: dsc-fractional-bpp
[305.814535] Subtest dsc-fractional-bpp: SKIP (0.000s)
[305.871686] [029/131] (713s left) gem_exec_schedule (u-lateslice)
[305.941830] Starting subtest: u-lateslice
[305.943814] Starting dynamic subtest: rcs0
[305.953303] Dynamic subtest rcs0: SUCCESS (0.022s)
[305.963571] Starting dynamic subtest: bcs0
[305.971066] Dynamic subtest bcs0: SUCCESS (0.018s)
[305.980061] Starting dynamic subtest: vcs0
[305.990265] Dynamic subtest vcs0: SUCCESS (0.019s)
[305.999249] Starting dynamic subtest: vcs1
[306.008217] Dynamic subtest vcs1: SUCCESS (0.018s)
[306.019069] Starting dynamic subtest: vecs0
[306.026780] Dynamic subtest vecs0: SUCCESS (0.018s)
[306.036346] Starting dynamic subtest: ccs0
[306.044273] Dynamic subtest ccs0: SUCCESS (0.017s)
[306.046421] Subtest u-lateslice: SUCCESS (0.113s)
[308.025810] [030/131] (711s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[308.116163] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[308.118461] Starting dynamic subtest: pipe-A-eDP-1
[310.224674] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.110s)
[310.233960] Starting dynamic subtest: pipe-B-eDP-1
[312.276406] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.050s)
[312.284735] Starting dynamic subtest: pipe-C-eDP-1
[314.287333] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.010s)
[314.296206] Starting dynamic subtest: pipe-D-eDP-1
[316.322213] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.035s)
[316.324500] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.208s)
[317.561623] [031/131] (702s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[317.674668] Starting subtest: fbcpsr-rgb101010-draw-render
[324.475062] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (6.803s)
[324.598794] [032/131] (695s left) gem_eio (reset-stress)
[324.800491] Starting subtest: reset-stress
[368.412443] Subtest reset-stress: SUCCESS (43.608s)
[369.919196] [033/131] (650s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[370.017540] Starting subtest: psr-1p-primscrn-cur-indfb-move
[377.187517] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.173s)
[377.358962] [034/131] (642s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[377.482711] Starting subtest: basic-busy-flip-before-cursor-legacy
[377.487301] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[377.626290] [035/131] (642s left) perf (disabled-read-error)
[377.752368] Starting subtest: disabled-read-error
[377.769195] Subtest disabled-read-error: SUCCESS (0.017s)
[377.820882] [036/131] (642s left) kms_chamelium_hpd (vga-hpd-fast)
[378.205897] Subtest vga-hpd-fast: SKIP (0.000s)
[379.033888] [037/131] (641s left) kms_properties (plane-properties-atomic)
[379.128281] Starting subtest: plane-properties-atomic
[379.130486] Starting dynamic subtest: pipe-A-eDP-1
[379.688300] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.561s)
[379.697718] Starting dynamic subtest: pipe-B-eDP-1
[380.923610] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.233s)
[380.932244] Starting dynamic subtest: pipe-C-eDP-1
[382.157070] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.232s)
[382.167241] Starting dynamic subtest: pipe-D-eDP-1
[383.399308] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.240s)
[383.401713] Subtest plane-properties-atomic: SUCCESS (4.272s)
[384.200880] [038/131] (636s left) dmabuf_sync_file (export-multiwait)
[384.243803] Starting subtest: export-multiwait
[384.245999] Subtest export-multiwait: SUCCESS (0.000s)
[384.300171] [039/131] (635s left) i915_drm_fdinfo (all-busy-check-all)
[384.338557] Starting subtest: all-busy-check-all
[384.345657] Subtest all-busy-check-all: SKIP (0.004s)
[384.418395] [040/131] (635s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[384.501608] Starting subtest: yf-tiled-ccs-to-x-tiled
[384.506203] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[384.684384] [041/131] (635s left) gem_spin_batch (resubmit-all)
[384.753434] Starting subtest: resubmit-all
[384.755716] Starting dynamic subtest: rcs0
[384.767315] Dynamic subtest rcs0: SUCCESS (0.013s)
[384.778847] Starting dynamic subtest: bcs0
[384.781328] Dynamic subtest bcs0: SUCCESS (0.002s)
[384.789229] Starting dynamic subtest: vcs0
[384.791098] Dynamic subtest vcs0: SUCCESS (0.002s)
[384.798892] Starting dynamic subtest: vcs1
[384.800811] Dynamic subtest vcs1: SUCCESS (0.002s)
[384.808757] Starting dynamic subtest: vecs0
[384.810676] Dynamic subtest vecs0: SUCCESS (0.002s)
[384.818830] Starting dynamic subtest: ccs0
[384.820868] Dynamic subtest ccs0: SUCCESS (0.002s)
[384.822874] Subtest resubmit-all: SUCCESS (0.026s)
[385.916438] [042/131] (634s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[386.017133] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[386.021274] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[386.103474] [043/131] (634s left) syncobj_wait (multi-wait-all-signaled)
[386.160419] Starting subtest: multi-wait-all-signaled
[386.162784] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[386.266192] [044/131] (634s left) gem_exec_suspend (basic-s3)
[386.326895] Starting subtest: basic-S3
[386.329179] Starting dynamic subtest: smem
[406.545971] Dynamic subtest smem: SUCCESS (6.598s)
[406.549089] Subtest basic-S3: SUCCESS (6.600s)
[406.731093] [045/131] (613s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[406.848400] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[407.635311] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.792s)
[407.774657] [046/131] (612s left) syncobj_timeline (multi-wait-all-signaled)
[407.825908] Starting subtest: multi-wait-all-signaled
[407.828115] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[407.924299] [047/131] (612s left) kms_vblank (wait-busy)
[408.027140] Starting subtest: wait-busy
[408.029519] Starting dynamic subtest: pipe-A-eDP-1
[409.901238] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.880s)
[409.915316] Starting dynamic subtest: pipe-D-eDP-1
[412.436381] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.540s)
[412.439030] Subtest wait-busy: SUCCESS (4.424s)
[413.236909] [048/131] (607s left) syncobj_timeline (signal-array)
[413.285212] Starting subtest: signal-array
[413.287763] Subtest signal-array: SUCCESS (0.000s)
[413.402950] [049/131] (607s left) gen9_exec_parse (basic-rejected)
[413.454557] Subtest basic-rejected: SKIP (0.000s)
[413.519789] [050/131] (607s left) kms_psr (psr-sprite-render)
[413.610994] Starting subtest: psr-sprite-render
[413.613306] Starting dynamic subtest: eDP-1
[415.360562] Dynamic subtest eDP-1: SUCCESS (1.751s)
[415.363030] Subtest psr-sprite-render: SUCCESS (1.751s)
[416.207541] [051/131] (604s left) gem_eio (throttle)
[416.451153] Starting subtest: throttle
[416.810876] Subtest throttle: SUCCESS (0.358s)
[416.961794] [052/131] (603s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[417.060077] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[417.065159] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[417.178457] [053/131] (603s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[417.270021] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[417.282823] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.011s)
[417.388867] [054/131] (603s left) kms_cursor_crc (cursor-random-128x128)
[417.594570] Starting subtest: cursor-random-128x128
[417.598675] Starting dynamic subtest: pipe-A-eDP-1
[420.959316] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.362s)
[420.990148] Starting dynamic subtest: pipe-D-eDP-1
[425.012064] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.034s)
[425.014444] Subtest cursor-random-128x128: SUCCESS (7.425s)
[425.850368] [055/131] (595s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[425.946903] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[425.952120] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[426.055569] [056/131] (594s left) gem_exec_params (no-vebox)
[426.113691] Starting subtest: no-vebox
[426.116105] Subtest no-vebox: SUCCESS (0.003s)
[427.628305] [057/131] (593s left) gem_exec_fence (submit3)
[427.793148] Starting subtest: submit3
[427.798283] Subtest submit3: SKIP (0.000s)
[429.623947] [058/131] (591s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[429.727204] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[429.732179] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[429.856750] [059/131] (591s left) i915_pm_rps (min-max-config-idle)
[429.916326] Starting subtest: min-max-config-idle
[429.922107] Subtest min-max-config-idle: SKIP (0.001s)
[430.006811] [060/131] (591s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[430.093637] Starting subtest: modeset-transition-nonblocking-fencing
[430.095948] Starting dynamic subtest: 1x-outputs
[440.863649] Dynamic subtest 1x-outputs: SUCCESS (10.776s)
[441.129317] Subtest modeset-transition-nonblocking-fencing: SUCCESS (11.039s)
[441.989954] [061/131] (579s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[446.974970] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[446.980414] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[447.824374] [062/131] (573s left) syncobj_eventfd (invalid-illegal-eventfd)
[447.882237] Starting subtest: invalid-illegal-eventfd
[447.884713] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[447.974339] [063/131] (573s left) gem_exec_params (larger-than-life-batch)
[448.029460] Starting subtest: larger-than-life-batch
[450.238906] Subtest larger-than-life-batch: SUCCESS (2.212s)
[450.571486] [064/131] (570s left) perf (sysctl-defaults)
[450.709376] Starting subtest: sysctl-defaults
[450.712340] Subtest sysctl-defaults: SUCCESS (0.000s)
[450.784261] [065/131] (570s left) gem_exec_schedule (promotion)
[450.872098] Starting subtest: promotion
[450.874541] Starting dynamic subtest: rcs0
[451.030605] Dynamic subtest rcs0: SUCCESS (0.166s)
[451.043898] Starting dynamic subtest: vcs0
[451.146998] Dynamic subtest vcs0: SUCCESS (0.115s)
[451.159288] Starting dynamic subtest: vcs1
[451.265419] Dynamic subtest vcs1: SUCCESS (0.118s)
[451.276425] Starting dynamic subtest: vecs0
[451.387150] Dynamic subtest vecs0: SUCCESS (0.120s)
[451.401469] Starting dynamic subtest: ccs0
[451.528342] Dynamic subtest ccs0: SUCCESS (0.140s)
[451.530996] Subtest promotion: SUCCESS (0.665s)
[452.668610] [066/131] (568s left) gem_ctx_persistence (engines-hang)
[452.741215] Starting subtest: engines-hang
[452.743565] Starting dynamic subtest: rcs0
[452.886533] Dynamic subtest rcs0: SUCCESS (0.145s)
[452.899516] Starting dynamic subtest: bcs0
[453.005417] Dynamic subtest bcs0: SUCCESS (0.117s)
[453.018400] Starting dynamic subtest: vcs0
[453.124809] Dynamic subtest vcs0: SUCCESS (0.117s)
[453.137913] Starting dynamic subtest: vcs1
[453.247740] Dynamic subtest vcs1: SUCCESS (0.121s)
[453.261389] Starting dynamic subtest: vecs0
[453.370501] Dynamic subtest vecs0: SUCCESS (0.121s)
[453.383209] Starting dynamic subtest: ccs0
[453.458843] Dynamic subtest ccs0: SUCCESS (0.086s)
[453.461569] Subtest engines-hang: SUCCESS (0.719s)
[453.604586] [067/131] (567s left) syncobj_wait (invalid-signal-illegal-handle)
[453.669219] Starting subtest: invalid-signal-illegal-handle
[453.671428] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[453.785747] [068/131] (567s left) gem_exec_reloc (basic-cpu-gtt)
[453.849444] Subtest basic-cpu-gtt: SKIP (0.000s)
[453.915593] [069/131] (567s left) kms_psr (fbc-psr2-sprite-render)
[454.019133] Starting subtest: fbc-psr2-sprite-render
[454.021088] Starting dynamic subtest: eDP-1
[454.024627] Dynamic subtest eDP-1: SKIP (0.000s)
[454.028392] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[454.092749] [070/131] (567s left) kms_big_fb (linear-64bpp-rotate-180)
[454.202831] Starting subtest: linear-64bpp-rotate-180
[455.725061] Subtest linear-64bpp-rotate-180: SUCCESS (1.524s)
[455.893613] [071/131] (565s left) kms_flip (flip-vs-suspend)
[455.992718] Starting subtest: flip-vs-suspend
[455.995410] Starting dynamic subtest: A-eDP1
[507.956231] Dynamic subtest A-eDP1: SUCCESS (6.766s)
[507.971879] Starting dynamic subtest: B-eDP1
[543.952283] Dynamic subtest B-eDP1: SUCCESS (5.655s)
[543.969293] Starting dynamic subtest: C-eDP1
[593.613954] Dynamic subtest C-eDP1: SUCCESS (4.367s)
[593.625333] Starting dynamic subtest: D-eDP1
[642.620859] Dynamic subtest D-eDP1: SUCCESS (4.362s)
[642.623437] Subtest flip-vs-suspend: SUCCESS (21.158s)
[643.852493] [072/131] (377s left) prime_mmap (test_dup)
[643.917753] Starting subtest: test_dup
[643.919726] Starting dynamic subtest: test_dup-smem
[643.946135] Dynamic subtest test_dup-smem: SUCCESS (0.030s)
[643.948843] Subtest test_dup: SUCCESS (0.031s)
[644.021610] [073/131] (377s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[644.083679] Starting subtest: multi-wait-all-for-submit-submitted
[644.190016] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.107s)
[644.266996] [074/131] (377s left) kms_joiner (basic-max-non-joiner)
[644.364103] Starting subtest: basic-max-non-joiner
[644.369350] Subtest basic-max-non-joiner: SKIP (0.000s)
[644.430752] [075/131] (377s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[644.534128] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[644.538703] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[644.651475] [076/131] (377s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[645.028802] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[645.847198] [077/131] (376s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[645.933695] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[645.936028] Starting dynamic subtest: pipe-A-eDP-1
[645.956441] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[645.967553] Starting dynamic subtest: pipe-B-eDP-1
[645.989422] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[645.999509] Starting dynamic subtest: pipe-C-eDP-1
[646.021450] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[646.031397] Starting dynamic subtest: pipe-D-eDP-1
[646.054099] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[646.056551] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[648.230968] [078/131] (373s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[648.334772] Starting subtest: psr-shrfb-scaledprimary
[654.132410] Subtest psr-shrfb-scaledprimary: SUCCESS (5.811s)
[654.272915] [079/131] (367s left) kms_plane_multiple (2x-tiling-yf)
[654.364045] Starting subtest: 2x-tiling-yf
[654.369138] Subtest 2x-tiling-yf: SKIP (0.000s)
[654.438126] [080/131] (367s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[654.531125] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[654.536055] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[654.622864] [081/131] (367s left) kms_addfb_basic (unused-handle)
[654.672071] Starting subtest: unused-handle
[654.674423] Subtest unused-handle: SUCCESS (0.000s)
[654.824232] [082/131] (367s left) gem_mmap_gtt (cpuset-medium-copy)
[654.877843] Subtest cpuset-medium-copy: SKIP (0.000s)
[654.940330] [083/131] (367s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[655.031557] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[655.035258] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[655.140830] [084/131] (367s left) kms_pm_dc (dc5-dpms-negative)
[656.231643] Starting subtest: dc5-dpms-negative
[656.236551] Subtest dc5-dpms-negative: SKIP (0.000s)
[656.302921] [085/131] (365s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[656.394160] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[656.396669] Starting dynamic subtest: pipe-A-eDP-1
[656.421594] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[656.433282] Starting dynamic subtest: pipe-B-eDP-1
[656.457984] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[656.467673] Starting dynamic subtest: pipe-C-eDP-1
[656.493542] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[656.503876] Starting dynamic subtest: pipe-D-eDP-1
[656.528305] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[656.533744] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[658.620471] [086/131] (363s left) kms_flip (flip-vs-expired-vblank-interruptible)
[658.725290] Starting subtest: flip-vs-expired-vblank-interruptible
[658.727961] Starting dynamic subtest: A-eDP1
[667.438265] Dynamic subtest A-eDP1: SUCCESS (8.714s)
[667.450445] Starting dynamic subtest: B-eDP1
[676.061259] Dynamic subtest B-eDP1: SUCCESS (8.624s)
[676.073173] Starting dynamic subtest: C-eDP1
[684.680879] Dynamic subtest C-eDP1: SUCCESS (8.622s)
[684.691345] Starting dynamic subtest: D-eDP1
[693.309079] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[693.314420] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.602s)
[694.578845] [087/131] (327s left) perf_pmu (busy-idle-no-semaphores)
[694.619953] Starting subtest: busy-idle-no-semaphores
[694.622274] Starting dynamic subtest: rcs0
[695.289733] Dynamic subtest rcs0: SUCCESS (0.670s)
[695.307287] Starting dynamic subtest: bcs0
[695.942816] Dynamic subtest bcs0: SUCCESS (0.650s)
[695.955453] Starting dynamic subtest: vcs0
[696.593576] Dynamic subtest vcs0: SUCCESS (0.650s)
[696.607462] Starting dynamic subtest: vcs1
[698.666555] Dynamic subtest vcs1: SUCCESS (2.073s)
[698.679154] Starting dynamic subtest: vecs0
[700.649016] Dynamic subtest vecs0: SUCCESS (1.982s)
[700.662814] Starting dynamic subtest: ccs0
[702.635579] Dynamic subtest ccs0: SUCCESS (1.985s)
[702.638342] Subtest busy-idle-no-semaphores: SUCCESS (8.020s)
[702.752037] [088/131] (319s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[702.860304] Starting subtest: y-tiled-addfb-size-offset-overflow
[702.864989] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[702.955146] [089/131] (319s left) gem_mmap_wc (bad-size)
[703.029162] Subtest bad-size: SKIP (0.000s)
[703.095819] [090/131] (319s left) kms_joiner (invalid-modeset-big-joiner)
[703.193182] Starting subtest: invalid-modeset-big-joiner
[703.198272] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[703.259919] [091/131] (319s left) gem_userptr_blits (stress-mm)
[703.325222] Starting subtest: stress-mm
[708.693304] Subtest stress-mm: SUCCESS (5.370s)
[708.839041] [092/131] (313s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[708.954265] Starting subtest: cursorB-vs-flipA-toggle
[708.959760] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[709.101760] [093/131] (313s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-pwrite)
[709.196967] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-pwrite
[718.513645] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (9.324s)
[718.652505] [094/131] (303s left) syncobj_basic (bad-destroy-pad)
[718.708209] Starting subtest: bad-destroy-pad
[718.710401] Subtest bad-destroy-pad: SUCCESS (0.000s)
[718.823099] [095/131] (303s left) api_intel_allocator (reopen)
[718.884112] Starting subtest: reopen
[718.887128] Subtest reopen: SUCCESS (0.006s)
[719.011466] [096/131] (303s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[719.119958] Starting subtest: yf-tiled-32bpp-rotate-90
[719.124244] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[719.226415] [097/131] (303s left) gem_exec_balancer (bonded-true-hang)
[719.482013] Starting subtest: bonded-true-hang
[719.487698] Subtest bonded-true-hang: SKIP (0.000s)
[719.602062] [098/131] (303s left) kms_cursor_crc (cursor-sliding-128x42)
[719.814987] Starting subtest: cursor-sliding-128x42
[719.858416] Subtest cursor-sliding-128x42: SKIP (0.038s)
[720.076260] [099/131] (302s left) gem_busy (extended)
[720.264385] Starting subtest: extended
[720.267492] Starting dynamic subtest: rcs0
[720.364562] Dynamic subtest rcs0: SUCCESS (0.100s)
[720.377346] Starting dynamic subtest: bcs0
[720.405413] Dynamic subtest bcs0: SUCCESS (0.039s)
[720.417961] Starting dynamic subtest: vcs0
[722.050823] Dynamic subtest vcs0: SUCCESS (1.643s)
[722.065411] Starting dynamic subtest: vcs1
[724.034591] Dynamic subtest vcs1: SUCCESS (1.980s)
[724.052442] Starting dynamic subtest: vecs0
[726.018918] Dynamic subtest vecs0: SUCCESS (1.981s)
[726.032420] Starting dynamic subtest: ccs0
[726.063929] Dynamic subtest ccs0: SUCCESS (0.044s)
[726.066528] Subtest extended: SUCCESS (5.798s)
[726.314883] [100/131] (296s left) gem_create (create-massive)
[726.366903] Starting subtest: create-massive
[726.369038] Subtest create-massive: SUCCESS (0.000s)
[726.557961] [101/131] (296s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[726.650950] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[726.655890] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[726.762082] [102/131] (296s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[726.884314] Starting subtest: 2x-long-flip-vs-cursor-atomic
[726.888871] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[727.023448] [103/131] (295s left) perf_pmu (semaphore-wait-idle)
[727.067298] Starting subtest: semaphore-wait-idle
[727.069527] Starting dynamic subtest: rcs0
[727.582229] Dynamic subtest rcs0: SUCCESS (0.516s)
[727.597298] Starting dynamic subtest: bcs0
[728.097463] Dynamic subtest bcs0: SUCCESS (0.512s)
[728.110296] Starting dynamic subtest: vcs0
[728.609814] Dynamic subtest vcs0: SUCCESS (0.510s)
[728.622984] Starting dynamic subtest: vcs1
[729.122185] Dynamic subtest vcs1: SUCCESS (0.510s)
[729.133914] Starting dynamic subtest: vecs0
[729.636056] Dynamic subtest vecs0: SUCCESS (0.512s)
[729.653106] Starting dynamic subtest: ccs0
[730.152140] Dynamic subtest ccs0: SUCCESS (0.513s)
[730.154929] Subtest semaphore-wait-idle: SUCCESS (3.085s)
[730.293506] [104/131] (292s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[730.410846] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[730.414960] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[730.497040] [105/131] (292s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[735.532725] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[735.535555] Starting dynamic subtest: pipe-A-eDP-1
[736.336096] Dynamic subtest pipe-A-eDP-1: SKIP (0.800s)
[736.347801] Starting dynamic subtest: pipe-B-eDP-1
[737.468232] Dynamic subtest pipe-B-eDP-1: SKIP (1.130s)
[737.472769] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.934s)
[738.660745] [106/131] (284s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[738.753587] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[738.761498] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[738.883389] [107/131] (284s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[738.972963] Starting subtest: fbc-pr-cursor-mmap-cpu
[738.977271] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[739.047082] [108/131] (284s left) prime_busy (before-wait)
[739.111037] Starting subtest: before-wait
[739.116553] Starting dynamic subtest: rcs0
[739.160938] Dynamic subtest rcs0: SUCCESS (0.047s)
[739.173241] Starting dynamic subtest: bcs0
[739.225189] Dynamic subtest bcs0: SUCCESS (0.064s)
[739.236035] Starting dynamic subtest: vcs0
[739.264536] Dynamic subtest vcs0: SUCCESS (0.039s)
[739.275226] Starting dynamic subtest: vcs1
[739.304614] Dynamic subtest vcs1: SUCCESS (0.040s)
[739.315307] Starting dynamic subtest: vecs0
[739.347419] Dynamic subtest vecs0: SUCCESS (0.042s)
[739.358423] Starting dynamic subtest: ccs0
[739.388299] Dynamic subtest ccs0: SUCCESS (0.040s)
[739.496313] Subtest before-wait: SUCCESS (0.384s)
[739.576274] [109/131] (283s left) prime_vgem (basic-write)
[739.656438] Starting subtest: basic-write
[739.694082] Subtest basic-write: SKIP (0.037s)
[739.821861] [110/131] (283s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[739.927988] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[739.930320] Starting dynamic subtest: pipe-A-default-mode
[740.219216] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[740.224545] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.288s)
[741.091497] [111/131] (282s left) kms_pipe_crc_basic (hang-read-crc)
[741.185106] Starting subtest: hang-read-crc
[741.187089] Starting dynamic subtest: pipe-A-eDP-1
[743.252175] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.067s)
[743.264039] Starting dynamic subtest: pipe-B-eDP-1
[745.935853] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.678s)
[745.947222] Starting dynamic subtest: pipe-C-eDP-1
[748.570360] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.634s)
[748.581396] Starting dynamic subtest: pipe-D-eDP-1
[751.205392] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.636s)
[751.208189] Subtest hang-read-crc: SUCCESS (10.021s)
[752.061688] [112/131] (271s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[752.159397] Starting subtest: primary-yf-tiled-reflect-x-270
[752.164053] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[752.232659] [113/131] (271s left) kms_flip (bo-too-big-interruptible)
[752.320149] Starting subtest: bo-too-big-interruptible
[752.322856] Starting dynamic subtest: A-eDP1
[753.626041] Dynamic subtest A-eDP1: SUCCESS (1.305s)
[753.641749] Starting dynamic subtest: B-eDP1
[754.872925] Dynamic subtest B-eDP1: SUCCESS (1.246s)
[754.884926] Starting dynamic subtest: C-eDP1
[756.123859] Dynamic subtest C-eDP1: SUCCESS (1.249s)
[756.136207] Starting dynamic subtest: D-eDP1
[757.369013] Dynamic subtest D-eDP1: SUCCESS (1.245s)
[757.371345] Subtest bo-too-big-interruptible: SUCCESS (5.052s)
[758.583831] [114/131] (264s left) gem_lmem_swapping (heavy-verify-multi)
[758.630144] Subtest heavy-verify-multi: SKIP (0.000s)
[758.677909] [115/131] (264s left) kms_prop_blob (blob-prop-core)
[758.731226] Starting subtest: blob-prop-core
[758.733768] Subtest blob-prop-core: SUCCESS (0.000s)
[758.840655] [116/131] (264s left) gem_ctx_sseu (engines)
[758.898283] Subtest engines: SKIP (0.000s)
[758.961252] [117/131] (264s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[759.066997] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[765.746303] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.682s)
[765.878034] [118/131] (257s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[765.928996] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[765.932092] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[766.022617] [119/131] (257s left) kms_psr (pr-primary-render)
[766.111186] Starting subtest: pr-primary-render
[766.116225] Subtest pr-primary-render: SKIP (0.000s)
[766.189830] [120/131] (257s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[766.286995] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[767.444967] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: FAIL (0.499s)
[767.487899] [121/131] (256s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[767.580885] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[767.584873] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[768.751744] [122/131] (255s left) gem_workarounds (suspend-resume-fd)
[768.837803] Starting subtest: suspend-resume-fd
[784.656292] Subtest suspend-resume-fd: SUCCESS (1.157s)
[784.805900] [123/131] (239s left) gem_ctx_bad_destroy (double-destroy)
[784.863308] Starting subtest: double-destroy
[784.865697] Subtest double-destroy: SUCCESS (0.000s)
[784.940162] [124/131] (238s left) gem_softpin (allocator-fork)
[785.001175] Starting subtest: allocator-fork
[787.223459] Subtest allocator-fork: SUCCESS (2.213s)
[787.378903] [125/131] (236s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[787.493345] Starting subtest: y-tiled-64bpp-rotate-90
[787.497763] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[787.603493] [126/131] (236s left) kms_plane (plane-position-hole)
[787.694822] Starting subtest: plane-position-hole
[788.217117] Starting dynamic subtest: pipe-A-plane-1
[789.527369] Dynamic subtest pipe-A-plane-1: SUCCESS (1.308s)
[789.539569] Starting dynamic subtest: pipe-A-plane-2
[790.931098] Dynamic subtest pipe-A-plane-2: SUCCESS (1.396s)
[790.942859] Starting dynamic subtest: pipe-A-plane-3
[792.258148] Dynamic subtest pipe-A-plane-3: SUCCESS (1.324s)
[792.269178] Starting dynamic subtest: pipe-A-plane-4
[793.486285] Dynamic subtest pipe-A-plane-4: SUCCESS (1.226s)
[793.497593] Starting dynamic subtest: pipe-A-plane-5
[793.510891] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[794.644865] Starting dynamic subtest: pipe-B-plane-1
[795.898726] Dynamic subtest pipe-B-plane-1: SUCCESS (1.259s)
[795.912376] Starting dynamic subtest: pipe-B-plane-2
[797.210150] Dynamic subtest pipe-B-plane-2: SUCCESS (1.312s)
[797.222143] Starting dynamic subtest: pipe-B-plane-3
[798.452053] Dynamic subtest pipe-B-plane-3: SUCCESS (1.237s)
[798.463595] Starting dynamic subtest: pipe-B-plane-4
[799.703653] Dynamic subtest pipe-B-plane-4: SUCCESS (1.252s)
[799.715357] Starting dynamic subtest: pipe-B-plane-5
[799.732871] Dynamic subtest pipe-B-plane-5: SUCCESS (0.027s)
[799.735123] Subtest plane-position-hole: SUCCESS (12.019s)
[800.526395] [127/131] (223s left) vgem_basic (second-client)
[800.577685] Starting subtest: second-client
[800.599340] Subtest second-client: SUCCESS (0.022s)
[800.661159] [128/131] (223s left) kms_plane_scaling (planes-scaler-unity-scaling)
[800.758835] Starting subtest: planes-scaler-unity-scaling
[800.761178] Starting dynamic subtest: pipe-A
[800.826749] Dynamic subtest pipe-A: SUCCESS (0.068s)
[800.840198] Starting dynamic subtest: pipe-B
[801.939180] Dynamic subtest pipe-B: SUCCESS (1.111s)
[801.952033] Starting dynamic subtest: pipe-C
[803.040015] Dynamic subtest pipe-C: SUCCESS (1.099s)
[803.050688] Starting dynamic subtest: pipe-D
[804.183087] Dynamic subtest pipe-D: SUCCESS (1.141s)
[804.185396] Subtest planes-scaler-unity-scaling: SUCCESS (3.423s)
[805.340452] [129/131] (218s left) gem_mmap_gtt (basic-small-bo-tiledy)
[805.394692] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[805.469102] [130/131] (218s left) i915_suspend (basic-s2idle-without-i915)
[805.503945] Starting subtest: basic-s2idle-without-i915
[826.133897] Subtest basic-s2idle-without-i915: SUCCESS (5.849s)
[826.245005] [131/131] (197s left) kms_psr (psr2-sprite-mmap-cpu)
[826.350051] Starting subtest: psr2-sprite-mmap-cpu
[826.352207] Starting dynamic subtest: eDP-1
[828.046100] Dynamic subtest eDP-1: SUCCESS (1.690s)
[828.048487] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.691s)
[828.927019] Closing watchdogs
results: parsing output: 0/ for test: prime_mmap
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: sw_sync
results: parsing output: 3/ for test: gem_exec_schedule
results: parsing output: 4/ for test: core_getversion
results: parsing output: 5/ for test: kms_flip
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: gem_pxp
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_flush
results: parsing output: 10/ for test: i915_pm_rc6_residency
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: kms_cursor_legacy
results: parsing output: 13/ for test: kms_atomic
results: parsing output: 14/ for test: vgem_basic
results: parsing output: 15/ for test: kms_flip_scaled_crc
results: parsing output: 16/ for test: kms_addfb_basic
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: kms_vblank
results: parsing output: 20/ for test: gem_exec_whisper
results: parsing output: 21/ for test: kms_content_protection
results: parsing output: 22/ for test: kms_cursor_edge_walk
results: parsing output: 23/ for test: kms_chamelium_edid
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: i915_hangman
results: parsing output: 26/ for test: kms_properties
results: parsing output: 27/ for test: kms_dsc
results: parsing output: 28/ for test: gem_exec_schedule
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_eio
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: perf
results: parsing output: 35/ for test: kms_chamelium_hpd
results: parsing output: 36/ for test: kms_properties
results: parsing output: 37/ for test: dmabuf_sync_file
results: parsing output: 38/ for test: i915_drm_fdinfo
results: parsing output: 39/ for test: gem_render_copy
results: parsing output: 40/ for test: gem_spin_batch
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: syncobj_wait
results: parsing output: 43/ for test: gem_exec_suspend
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: kms_vblank
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: gen9_exec_parse
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: gem_eio
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_exec_params
results: parsing output: 56/ for test: gem_exec_fence
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: i915_pm_rps
results: parsing output: 59/ for test: kms_atomic_transition
results: parsing output: 60/ for test: kms_psr2_sf
results: parsing output: 61/ for test: syncobj_eventfd
results: parsing output: 62/ for test: gem_exec_params
results: parsing output: 63/ for test: perf
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: gem_ctx_persistence
results: parsing output: 66/ for test: syncobj_wait
results: parsing output: 67/ for test: gem_exec_reloc
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: prime_mmap
results: parsing output: 72/ for test: syncobj_wait
results: parsing output: 73/ for test: kms_joiner
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_chamelium_hpd
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_plane_multiple
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_addfb_basic
results: parsing output: 81/ for test: gem_mmap_gtt
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_pm_dc
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: perf_pmu
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: gem_mmap_wc
results: parsing output: 89/ for test: kms_joiner
results: parsing output: 90/ for test: gem_userptr_blits
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: syncobj_basic
results: parsing output: 94/ for test: api_intel_allocator
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: gem_exec_balancer
results: parsing output: 97/ for test: kms_cursor_crc
results: parsing output: 98/ for test: gem_busy
results: parsing output: 99/ for test: gem_create
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: perf_pmu
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_psr2_sf
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: prime_busy
results: parsing output: 108/ for test: prime_vgem
results: parsing output: 109/ for test: kms_flip_scaled_crc
results: parsing output: 110/ for test: kms_pipe_crc_basic
results: parsing output: 111/ for test: kms_rotation_crc
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: gem_lmem_swapping
results: parsing output: 114/ for test: kms_prop_blob
results: parsing output: 115/ for test: gem_ctx_sseu
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: gem_workarounds
results: parsing output: 122/ for test: gem_ctx_bad_destroy
results: parsing output: 123/ for test: gem_softpin
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: kms_plane
results: parsing output: 126/ for test: vgem_basic
results: parsing output: 127/ for test: kms_plane_scaling
results: parsing output: 128/ for test: gem_mmap_gtt
results: parsing output: 129/ for test: i915_suspend
results: parsing output: 130/ for test: kms_psr
Done.