[57.391405] Initializing watchdogs
[57.391457]   /dev/watchdog0
[57.405499] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.409750] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.412052] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.415286] [001/133] (960s left) syncobj_timeline (wait-for-submit-snapshot)
[57.492645] Starting subtest: wait-for-submit-snapshot
[57.574321] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[57.652988] [002/133] (960s left) kms_plane_scaling (planes-scaler-unity-scaling)
[57.751616] Starting subtest: planes-scaler-unity-scaling
[57.753739] Starting dynamic subtest: pipe-A
[57.835438] Dynamic subtest pipe-A: SUCCESS (0.085s)
[57.844749] Starting dynamic subtest: pipe-B
[59.002672] Dynamic subtest pipe-B: SUCCESS (1.167s)
[59.011481] Starting dynamic subtest: pipe-C
[60.118454] Dynamic subtest pipe-C: SUCCESS (1.115s)
[60.126314] Starting dynamic subtest: pipe-D
[61.253725] Dynamic subtest pipe-D: SUCCESS (1.135s)
[61.256084] Subtest planes-scaler-unity-scaling: SUCCESS (3.504s)
[62.399283] [003/133] (955s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[62.454778] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[62.457081] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[62.550687] [004/133] (955s left) gem_fenced_exec_thrash (too-many-fences)
[62.612585] Subtest too-many-fences: SKIP (0.000s)
[62.676802] [005/133] (955s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[62.769088] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[62.773511] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[62.835190] [006/133] (955s left) gem_flink_basic (flink-lifetime)
[62.891965] Starting subtest: flink-lifetime
[62.894368] Subtest flink-lifetime: SUCCESS (0.002s)
[62.988232] [007/133] (955s left) perf (invalid-oa-metric-set-id)
[63.107241] Starting subtest: invalid-oa-metric-set-id
[63.117667] Subtest invalid-oa-metric-set-id: SUCCESS (0.011s)
[63.152671] [008/133] (955s left) gem_eio (suspend)
[63.297135] Starting subtest: suspend
[76.235037] Subtest suspend: SUCCESS (12.937s)
[76.345107] [009/133] (941s left) kms_plane_alpha_blend (alpha-7efc)
[76.439312] Starting subtest: alpha-7efc
[76.756649] Starting dynamic subtest: pipe-A-eDP-1
[77.369567] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.612s)
[77.399817] Starting dynamic subtest: pipe-D-eDP-1
[78.712625] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.319s)
[78.714993] Subtest alpha-7efc: SUCCESS (2.271s)
[79.550859] [010/133] (938s left) kms_frontbuffer_tracking (fbc-tiling-4)
[79.655279] Starting subtest: fbc-tiling-4
[83.841019] Subtest fbc-tiling-4: SUCCESS (4.192s)
[84.960326] [011/133] (933s left) i915_module_load (resize-bar)
[85.000731] Starting subtest: resize-bar
[85.007447] Subtest resize-bar: SKIP (0.005s)
[85.047876] [012/133] (933s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[85.140640] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[85.145070] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[85.247380] [013/133] (933s left) kms_3d (basic)
[85.299782] Starting subtest: basic
[87.285685] Subtest basic: SUCCESS (1.988s)
[87.765247] [014/133] (930s left) kms_flip (2x-flip-vs-panning-interruptible)
[87.859826] Starting subtest: 2x-flip-vs-panning-interruptible
[87.874242] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.013s)
[87.966339] [015/133] (930s left) gem_close (basic)
[88.022595] Starting subtest: basic
[88.024740] Subtest basic: SUCCESS (0.000s)
[88.116270] [016/133] (930s left) prime_busy (after)
[88.172373] Starting subtest: after
[88.174344] Starting dynamic subtest: rcs0
[88.206316] Dynamic subtest rcs0: SUCCESS (0.032s)
[88.216752] Starting dynamic subtest: bcs0
[88.246264] Dynamic subtest bcs0: SUCCESS (0.039s)
[88.254135] Starting dynamic subtest: vcs0
[88.287522] Dynamic subtest vcs0: SUCCESS (0.041s)
[88.295475] Starting dynamic subtest: vcs1
[88.327762] Dynamic subtest vcs1: SUCCESS (0.040s)
[88.335504] Starting dynamic subtest: vecs0
[88.371424] Dynamic subtest vecs0: SUCCESS (0.044s)
[88.379836] Starting dynamic subtest: ccs0
[88.412494] Dynamic subtest ccs0: SUCCESS (0.041s)
[88.521208] Subtest after: SUCCESS (0.348s)
[88.592675] [017/133] (929s left) api_intel_bb (misplaced-blitter)
[88.647908] Starting subtest: misplaced-blitter
[88.654625] Subtest misplaced-blitter: SUCCESS (0.009s)
[88.767338] [018/133] (929s left) vgem_basic (second-client)
[88.845764] Starting subtest: second-client
[88.867891] Subtest second-client: SUCCESS (0.022s)
[88.926747] [FACT vgem_basic (second-client)] new: kernel.kmod_is_loaded.vgem: true
[88.930246] [019/133] (929s left) gem_flink_race (flink_close)
[88.965381] Starting subtest: flink_close
[94.060629] Subtest flink_close: SUCCESS (5.080s)
[94.126149] [020/133] (924s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[94.234066] Starting subtest: x-tiled-16bpp-rotate-270
[94.254199] Subtest x-tiled-16bpp-rotate-270: SKIP (0.020s)
[94.369064] [021/133] (924s left) kms_pm_rpm (dpms-lpsp)
[96.033964] Starting subtest: dpms-lpsp
[96.609668] Subtest dpms-lpsp: SUCCESS (0.578s)
[96.708226] [022/133] (922s left) syncobj_timeline (invalid-signal-zero-handles)
[96.778063] Starting subtest: invalid-signal-zero-handles
[96.780401] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[96.880691] [023/133] (921s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[97.413431] Starting subtest: syncobj-backward-timeline-chain-engines
[97.533891] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.121s)
[98.986745] [024/133] (919s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[99.091586] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[99.093770] Starting dynamic subtest: pipe-A-eDP-1
[99.187446] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[99.199218] Starting dynamic subtest: pipe-B-eDP-1
[100.336248] Dynamic subtest pipe-B-eDP-1: SKIP (1.147s)
[100.340835] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.249s)
[101.526821] [025/133] (917s left) syncobj_wait (single-wait-all-signaled)
[101.595439] Starting subtest: single-wait-all-signaled
[101.597737] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[101.687164] [026/133] (917s left) gem_mmap_wc (close)
[101.736454] Subtest close: SKIP (0.000s)
[101.793273] [027/133] (917s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[101.852825] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[101.857325] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[102.031837] [028/133] (916s left) kms_plane_scaling (invalid-parameters)
[102.117542] Starting subtest: invalid-parameters
[102.119988] Starting dynamic subtest: less-than-1-height-src
[102.130732] Dynamic subtest less-than-1-height-src: SUCCESS (0.013s)
[102.141034] Starting dynamic subtest: less-than-1-width-src
[102.143427] Dynamic subtest less-than-1-width-src: SUCCESS (0.013s)
[102.145665] Subtest invalid-parameters: SUCCESS (0.026s)
[102.239823] [029/133] (916s left) gem_exec_balancer (parallel-contexts)
[102.497061] Starting subtest: parallel-contexts
[105.539271] Subtest parallel-contexts: SUCCESS (3.042s)
[106.128211] [030/133] (912s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[106.234018] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[113.614327] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.383s)
[113.725454] [031/133] (905s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[113.812168] Starting subtest: flip-vs-dpms-on-nop-interruptible
[113.814304] Starting dynamic subtest: A-eDP1
[115.648080] Dynamic subtest A-eDP1: SUCCESS (1.835s)
[115.658012] Starting dynamic subtest: B-eDP1
[117.481453] Dynamic subtest B-eDP1: SUCCESS (1.832s)
[117.495253] Starting dynamic subtest: C-eDP1
[119.311342] Dynamic subtest C-eDP1: SUCCESS (1.828s)
[119.323679] Starting dynamic subtest: D-eDP1
[121.145615] Dynamic subtest D-eDP1: SUCCESS (1.833s)
[121.147856] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.335s)
[122.335716] [032/133] (896s left) gem_ctx_persistence (userptr)
[122.411072] Starting subtest: userptr
[122.610593] Subtest userptr: SUCCESS (0.199s)
[122.689119] [033/133] (896s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[122.791511] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[126.876829] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.088s)
[128.035549] [034/133] (891s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[128.412232] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[129.230326] [035/133] (890s left) gem_exec_balancer (noheartbeat)
[129.489263] Starting subtest: noheartbeat
[129.493940] Subtest noheartbeat: SKIP (0.000s)
[129.602064] [036/133] (889s left) kms_psr (psr-cursor-plane-onoff)
[129.695985] Starting subtest: psr-cursor-plane-onoff
[129.698325] Starting dynamic subtest: eDP-1
[131.436272] Dynamic subtest eDP-1: SUCCESS (1.741s)
[131.438605] Subtest psr-cursor-plane-onoff: SUCCESS (1.742s)
[132.314299] [037/133] (887s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[132.420705] Starting subtest: 4-tiled-64bpp-rotate-180
[134.249322] Subtest 4-tiled-64bpp-rotate-180: FAIL (1.259s)
[134.289609] [038/133] (885s left) kms_sequence (get-forked-busy)
[134.379339] Starting subtest: get-forked-busy
[134.380929] Starting dynamic subtest: pipe-A-eDP-1
[136.324472] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.943s)
[136.334507] Starting dynamic subtest: pipe-B-eDP-1
[138.899497] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.574s)
[138.911930] Starting dynamic subtest: pipe-C-eDP-1
[141.526716] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.626s)
[141.536923] Starting dynamic subtest: pipe-D-eDP-1
[144.167600] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.639s)
[144.169974] Subtest get-forked-busy: SUCCESS (9.788s)
[144.966599] [039/133] (874s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[145.050688] Starting subtest: primary-yf-tiled-reflect-x-270
[145.055085] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[145.122558] [040/133] (874s left) kms_cursor_crc (cursor-random-64x64)
[145.296051] Starting subtest: cursor-random-64x64
[145.301183] Starting dynamic subtest: pipe-A-eDP-1
[148.617888] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.315s)
[148.644100] Starting dynamic subtest: pipe-D-eDP-1
[152.669711] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.030s)
[152.671807] Subtest cursor-random-64x64: SUCCESS (7.371s)
[153.535399] [041/133] (865s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[153.633166] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[153.635368] Starting dynamic subtest: pipe-A-eDP-1
[154.103426] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.442s)
[154.115377] Starting dynamic subtest: pipe-B-eDP-1
[155.518612] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.412s)
[155.530162] Starting dynamic subtest: pipe-C-eDP-1
[156.923270] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.404s)
[156.934267] Starting dynamic subtest: pipe-D-eDP-1
[158.245122] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[158.247097] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.586s)
[159.440957] [042/133] (860s left) syncobj_wait (multi-wait-signaled)
[159.501718] Starting subtest: multi-wait-signaled
[159.503859] Subtest multi-wait-signaled: SUCCESS (0.000s)
[159.588586] [043/133] (860s left) perf_pmu (invalid-open)
[159.626871] Starting subtest: invalid-open
[159.628856] Subtest invalid-open: SUCCESS (0.000s)
[159.733287] [044/133] (859s left) kms_flip (plain-flip-fb-recreate-interruptible)
[159.829258] Starting subtest: plain-flip-fb-recreate-interruptible
[159.831465] Starting dynamic subtest: A-eDP1
[168.643605] Dynamic subtest A-eDP1: SUCCESS (8.813s)
[168.656253] Starting dynamic subtest: B-eDP1
[177.474615] Dynamic subtest B-eDP1: SUCCESS (8.828s)
[177.487308] Starting dynamic subtest: C-eDP1
[186.308565] Dynamic subtest C-eDP1: SUCCESS (8.831s)
[186.317562] Starting dynamic subtest: D-eDP1
[195.139466] Dynamic subtest D-eDP1: SUCCESS (8.829s)
[195.143659] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.312s)
[196.330682] [045/133] (823s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[196.424393] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[196.428649] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[196.524762] [046/133] (823s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[196.666690] Starting subtest: dp-mst-lic-type-0-hdcp14
[196.671281] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[196.794577] [047/133] (822s left) kms_plane_multiple (tiling-none)
[196.901745] Starting subtest: tiling-none
[196.903870] Starting dynamic subtest: pipe-A-eDP-1
[200.572883] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.670s)
[200.583740] Starting dynamic subtest: pipe-B-eDP-1
[205.008321] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.435s)
[205.017582] Starting dynamic subtest: pipe-C-eDP-1
[209.353135] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.342s)
[209.362489] Starting dynamic subtest: pipe-D-eDP-1
[213.700874] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.347s)
[213.703040] Subtest tiling-none: SUCCESS (16.798s)
[214.522756] [048/133] (805s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[214.632057] Starting subtest: fbc-psr2-sprite-mmap-gtt
[214.634391] Starting dynamic subtest: eDP-1
[214.638800] Dynamic subtest eDP-1: SKIP (0.000s)
[214.643211] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.001s)
[214.705831] [049/133] (805s left) gem_exec_parallel (basic)
[214.760032] Starting subtest: basic
[214.762384] Starting dynamic subtest: rcs0
[216.161386] Dynamic subtest rcs0: SUCCESS (1.404s)
[216.170759] Starting dynamic subtest: bcs0
[217.604086] Dynamic subtest bcs0: SUCCESS (1.441s)
[217.615800] Starting dynamic subtest: vcs0
[220.886487] Dynamic subtest vcs0: SUCCESS (3.278s)
[220.898811] Starting dynamic subtest: vcs1
[222.869384] Dynamic subtest vcs1: SUCCESS (1.980s)
[222.880387] Starting dynamic subtest: vecs0
[224.916356] Dynamic subtest vecs0: SUCCESS (2.045s)
[224.928395] Starting dynamic subtest: ccs0
[226.431250] Dynamic subtest ccs0: SUCCESS (1.514s)
[226.433725] Subtest basic: SUCCESS (11.675s)
[226.633925] [050/133] (793s left) kms_psr (pr-dpms)
[226.735166] Starting subtest: pr-dpms
[226.739497] Subtest pr-dpms: SKIP (0.000s)
[226.807341] [051/133] (793s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[226.896527] Starting subtest: flip-vs-dpms-off-vs-modeset
[226.898600] Starting dynamic subtest: A-eDP1
[230.520430] Dynamic subtest A-eDP1: SUCCESS (3.624s)
[230.530150] Starting dynamic subtest: B-eDP1
[233.729137] Dynamic subtest B-eDP1: SUCCESS (3.207s)
[233.739384] Starting dynamic subtest: C-eDP1
[236.825918] Dynamic subtest C-eDP1: SUCCESS (3.097s)
[236.835826] Starting dynamic subtest: D-eDP1
[239.958032] Dynamic subtest D-eDP1: SUCCESS (3.127s)
[239.960705] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.064s)
[240.747082] [052/133] (779s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[240.801487] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[240.905500] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.103s)
[240.980681] [053/133] (779s left) gem_busy (busy)
[241.050552] Starting subtest: busy
[241.052724] Starting dynamic subtest: all-engines
[241.118845] Dynamic subtest all-engines: SUCCESS (0.067s)
[241.128446] Starting dynamic subtest: rcs0
[242.845088] Dynamic subtest rcs0: SUCCESS (1.725s)
[242.854213] Starting dynamic subtest: bcs0
[242.886144] Dynamic subtest bcs0: SUCCESS (0.039s)
[242.894676] Starting dynamic subtest: vcs0
[242.926094] Dynamic subtest vcs0: SUCCESS (0.038s)
[242.934811] Starting dynamic subtest: vcs1
[244.890289] Dynamic subtest vcs1: SUCCESS (1.961s)
[244.900364] Starting dynamic subtest: vecs0
[246.989814] Dynamic subtest vecs0: SUCCESS (2.046s)
[247.001414] Starting dynamic subtest: ccs0
[248.922964] Dynamic subtest ccs0: SUCCESS (1.984s)
[248.925366] Subtest busy: SUCCESS (7.869s)
[249.297251] [054/133] (770s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[249.401032] Starting subtest: psr-rgb101010-draw-pwrite
[256.976012] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.578s)
[257.080310] [055/133] (763s left) gem_ctx_engines (invalid-engines)
[257.143134] Starting subtest: invalid-engines
[257.148675] Subtest invalid-engines: SUCCESS (0.008s)
[257.372999] [056/133] (762s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[257.466405] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[257.470407] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[257.559731] [057/133] (762s left) perf (gen12-group-exclusive-stream-sample-oa)
[257.649702] Starting subtest: gen12-group-exclusive-stream-sample-oa
[257.666808] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.017s)
[257.716384] [058/133] (762s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[257.807855] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[264.645579] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.840s)
[264.780635] [059/133] (755s left) gem_exec_async (forked-writes)
[264.837602] Starting subtest: forked-writes
[264.840087] Starting dynamic subtest: rcs0
[264.866665] Dynamic subtest rcs0: SUCCESS (0.029s)
[264.876856] Starting dynamic subtest: bcs0
[264.888690] Dynamic subtest bcs0: SUCCESS (0.021s)
[264.901635] Starting dynamic subtest: vcs0
[264.910724] Dynamic subtest vcs0: SUCCESS (0.021s)
[264.920563] Starting dynamic subtest: vcs1
[264.932521] Dynamic subtest vcs1: SUCCESS (0.021s)
[264.941979] Starting dynamic subtest: vecs0
[264.956835] Dynamic subtest vecs0: SUCCESS (0.023s)
[264.967448] Starting dynamic subtest: ccs0
[264.979215] Dynamic subtest ccs0: SUCCESS (0.021s)
[264.981384] Subtest forked-writes: SUCCESS (0.142s)
[265.205517] [060/133] (755s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[265.262778] Starting subtest: multi-wait-for-submit-available-submitted
[265.265208] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.001s)
[265.370906] [061/133] (754s left) kms_psr (psr-sprite-plane-move)
[265.461916] Starting subtest: psr-sprite-plane-move
[265.464077] Starting dynamic subtest: eDP-1
[267.237547] Dynamic subtest eDP-1: SUCCESS (1.774s)
[267.239811] Subtest psr-sprite-plane-move: SUCCESS (1.775s)
[268.067299] [062/133] (752s left) gem_exec_schedule (promotion)
[268.129370] Starting subtest: promotion
[268.131550] Starting dynamic subtest: rcs0
[268.227974] Dynamic subtest rcs0: SUCCESS (0.105s)
[268.239777] Starting dynamic subtest: vcs0
[268.320433] Dynamic subtest vcs0: SUCCESS (0.092s)
[268.332842] Starting dynamic subtest: vcs1
[268.410519] Dynamic subtest vcs1: SUCCESS (0.089s)
[268.422022] Starting dynamic subtest: vecs0
[268.501904] Dynamic subtest vecs0: SUCCESS (0.091s)
[268.514159] Starting dynamic subtest: ccs0
[268.598324] Dynamic subtest ccs0: SUCCESS (0.096s)
[268.601265] Subtest promotion: SUCCESS (0.476s)
[269.995179] [063/133] (750s left) kms_lease (lease-revoke)
[270.102982] Starting subtest: lease-revoke
[270.105174] Starting dynamic subtest: pipe-A-eDP-1
[270.136918] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.035s)
[270.149196] Starting dynamic subtest: pipe-B-eDP-1
[270.172782] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.032s)
[270.181988] Starting dynamic subtest: pipe-C-eDP-1
[270.200868] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.025s)
[270.210013] Starting dynamic subtest: pipe-D-eDP-1
[270.228394] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.027s)
[270.230666] Subtest lease-revoke: SUCCESS (0.128s)
[270.347443] [064/133] (750s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[270.445950] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[270.450256] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[270.535179] [065/133] (749s left) gem_exec_schedule (noreorder-corked)
[270.719647] Starting subtest: noreorder-corked
[270.722075] Starting dynamic subtest: rcs0
[271.851889] Dynamic subtest rcs0: SUCCESS (1.131s)
[271.862758] Starting dynamic subtest: vcs0
[273.871992] Dynamic subtest vcs0: SUCCESS (2.016s)
[273.881605] Starting dynamic subtest: vcs1
[275.928558] Dynamic subtest vcs1: SUCCESS (2.053s)
[275.939559] Starting dynamic subtest: vecs0
[277.902552] Dynamic subtest vecs0: SUCCESS (1.972s)
[277.915035] Starting dynamic subtest: ccs0
[279.051087] Dynamic subtest ccs0: SUCCESS (1.147s)
[279.053663] Subtest noreorder-corked: SUCCESS (8.332s)
[279.241994] [066/133] (741s left) api_intel_allocator (open-vm)
[279.289883] Starting subtest: open-vm
[279.294660] Subtest open-vm: SUCCESS (0.005s)
[279.413106] [067/133] (741s left) kms_flip (absolute-wf_vblank)
[279.516729] Starting subtest: absolute-wf_vblank
[279.518681] Starting dynamic subtest: A-eDP1
[288.257630] Dynamic subtest A-eDP1: SUCCESS (8.706s)
[288.269383] Starting dynamic subtest: B-eDP1
[297.001759] Dynamic subtest B-eDP1: SUCCESS (8.744s)
[297.012549] Starting dynamic subtest: C-eDP1
[305.729769] Dynamic subtest C-eDP1: SUCCESS (8.713s)
[305.739299] Starting dynamic subtest: D-eDP1
[314.461954] Dynamic subtest D-eDP1: SUCCESS (8.727s)
[314.464589] Subtest absolute-wf_vblank: SUCCESS (34.903s)
[315.639497] [068/133] (704s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[315.747411] Starting subtest: y-tiled-16bpp-rotate-90
[315.751701] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[315.848560] [069/133] (704s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[315.955849] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[315.958085] Starting dynamic subtest: pipe-A-eDP-1
[315.982196] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[315.993187] Starting dynamic subtest: pipe-B-eDP-1
[316.015752] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[316.024664] Starting dynamic subtest: pipe-C-eDP-1
[316.047260] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[316.056192] Starting dynamic subtest: pipe-D-eDP-1
[316.080148] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[316.084236] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[318.217710] [070/133] (702s left) i915_pm_rpm (gem-execbuf)
[320.045651] Starting subtest: gem-execbuf
[320.048612] Starting dynamic subtest: smem0
[321.784225] Dynamic subtest smem0: SUCCESS (1.739s)
[321.786642] Subtest gem-execbuf: SUCCESS (1.741s)
[321.886556] [071/133] (698s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[321.978054] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[321.982577] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[322.079488] [072/133] (698s left) kms_rmfb (rmfb-ioctl)
[322.173395] Starting subtest: rmfb-ioctl
[322.175665] Starting dynamic subtest: pipe-A-eDP-1
[322.276901] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[322.289592] Starting dynamic subtest: pipe-B-eDP-1
[323.495211] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.215s)
[323.505201] Starting dynamic subtest: pipe-C-eDP-1
[324.618603] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.122s)
[324.628525] Starting dynamic subtest: pipe-D-eDP-1
[325.751986] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s)
[325.754610] Subtest rmfb-ioctl: SUCCESS (3.582s)
[326.970757] [073/133] (693s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[327.068666] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[333.818958] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.754s)
[333.946472] [074/133] (686s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[334.041288] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[334.043519] Starting dynamic subtest: pipe-A-eDP-1
[345.356053] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.317s)
[345.365436] Starting dynamic subtest: pipe-B-eDP-1
[357.321923] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.965s)
[357.324205] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.284s)
[358.171342] [075/133] (662s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[358.255430] Starting subtest: plane-lut1d-ctm3x4-lut1d
[358.378886] Starting dynamic subtest: pipe-A-plane-0
[358.523281] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[358.533027] Starting dynamic subtest: pipe-A-plane-1
[358.712173] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[358.721756] Starting dynamic subtest: pipe-A-plane-2
[358.845402] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[360.050277] Starting dynamic subtest: pipe-B-plane-0
[360.186801] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[360.196176] Starting dynamic subtest: pipe-B-plane-1
[360.320198] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[360.329786] Starting dynamic subtest: pipe-B-plane-2
[360.453497] Dynamic subtest pipe-B-plane-2: SUCCESS (0.132s)
[361.682195] Starting dynamic subtest: pipe-C-plane-0
[361.807745] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[361.817342] Starting dynamic subtest: pipe-C-plane-1
[361.951783] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[361.961361] Starting dynamic subtest: pipe-C-plane-2
[362.085617] Dynamic subtest pipe-C-plane-2: SUCCESS (0.132s)
[363.324900] Starting dynamic subtest: pipe-D-plane-0
[363.472622] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[363.482010] Starting dynamic subtest: pipe-D-plane-1
[363.628191] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[363.640049] Starting dynamic subtest: pipe-D-plane-2
[363.783709] Dynamic subtest pipe-D-plane-2: SUCCESS (0.152s)
[364.080194] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (5.824s)
[364.890610] [076/133] (656s left) i915_drm_fdinfo (idle)
[364.944980] Starting subtest: idle
[364.946944] Starting dynamic subtest: rcs0
[365.531313] Dynamic subtest rcs0: SUCCESS (0.587s)
[365.543307] Starting dynamic subtest: bcs0
[366.038584] Dynamic subtest bcs0: SUCCESS (0.503s)
[366.050616] Starting dynamic subtest: vcs0
[366.545396] Dynamic subtest vcs0: SUCCESS (0.504s)
[366.556098] Starting dynamic subtest: vcs1
[367.050802] Dynamic subtest vcs1: SUCCESS (0.503s)
[367.061863] Starting dynamic subtest: vecs0
[367.556934] Dynamic subtest vecs0: SUCCESS (0.503s)
[367.568718] Starting dynamic subtest: ccs0
[368.062845] Dynamic subtest ccs0: SUCCESS (0.503s)
[368.065890] Subtest idle: SUCCESS (3.119s)
[368.140110] [077/133] (652s left) gem_exec_suspend (basic-s0)
[368.217569] Starting subtest: basic-S0
[368.220135] Starting dynamic subtest: smem
[389.525315] Dynamic subtest smem: SUCCESS (6.712s)
[389.528321] Subtest basic-S0: SUCCESS (6.715s)
[389.725537] [078/133] (631s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[389.826415] Starting subtest: yf-tiled-addfb-size-overflow
[389.830781] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[389.919687] [079/133] (631s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[390.013098] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[390.017308] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[390.111769] [080/133] (631s left) kms_cursor_crc (cursor-sliding-32x10)
[390.290793] Starting subtest: cursor-sliding-32x10
[390.335916] Subtest cursor-sliding-32x10: SKIP (0.042s)
[390.598522] [081/133] (630s left) gem_mmap_offset (perf)
[390.646797] Starting subtest: perf
[390.648665] Starting dynamic subtest: smem0
[391.083485] Dynamic subtest smem0: SUCCESS (0.434s)
[391.086232] Subtest perf: SUCCESS (0.436s)
[391.217033] [082/133] (630s left) syncobj_wait (invalid-signal-bad-pad)
[391.264502] Starting subtest: invalid-signal-bad-pad
[391.266753] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[391.356275] [083/133] (629s left) core_getversion (basic)
[391.395103] Starting subtest: basic
[391.399403] Subtest basic: SUCCESS (0.000s)
[391.451344] [084/133] (629s left) gem_exec_reloc (basic-write-cpu-active)
[391.511031] Subtest basic-write-cpu-active: SKIP (0.000s)
[391.581825] [085/133] (629s left) gem_eio (in-flight-immediate)
[391.803131] Starting subtest: in-flight-immediate
[400.493011] Subtest in-flight-immediate: SUCCESS (8.685s)
[400.637515] [086/133] (620s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[401.009319] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[401.827877] [087/133] (619s left) kms_atomic (plane-primary-legacy)
[401.916610] Starting subtest: plane-primary-legacy
[401.918874] Starting dynamic subtest: pipe-A-eDP-1
[402.369858] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.455s)
[402.372350] Subtest plane-primary-legacy: SUCCESS (0.456s)
[403.187392] [088/133] (618s left) gem_ppgtt (shrink-vs-evict-pinned)
[403.238613] Starting subtest: shrink-vs-evict-pinned
[408.540868] Subtest shrink-vs-evict-pinned: SUCCESS (5.251s)
[408.595897] [089/133] (612s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[408.702191] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[408.706464] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[408.799205] [090/133] (612s left) kms_draw_crc (draw-method-mmap-cpu)
[408.892405] Starting subtest: draw-method-mmap-cpu
[408.894605] Starting dynamic subtest: xrgb8888-untiled
[409.114221] Dynamic subtest xrgb8888-untiled: SUCCESS (0.223s)
[409.128005] Starting dynamic subtest: rgb565-untiled
[409.380688] Dynamic subtest rgb565-untiled: SUCCESS (0.266s)
[409.393537] Starting dynamic subtest: xrgb2101010-untiled
[409.646967] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.264s)
[409.656191] Starting dynamic subtest: xbgr16161616f-untiled
[409.858141] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[409.867479] Starting dynamic subtest: xrgb8888-xtiled
[409.991935] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[410.003999] Starting dynamic subtest: rgb565-xtiled
[410.113616] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[410.125529] Starting dynamic subtest: xrgb2101010-xtiled
[410.246964] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.131s)
[410.256665] Starting dynamic subtest: xbgr16161616f-xtiled
[410.380371] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[410.389773] Starting dynamic subtest: xrgb8888-4tiled
[410.513546] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[410.522750] Starting dynamic subtest: rgb565-4tiled
[410.647742] Dynamic subtest rgb565-4tiled: SUCCESS (0.133s)
[410.658524] Starting dynamic subtest: xrgb2101010-4tiled
[410.791328] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.142s)
[410.800050] Starting dynamic subtest: xbgr16161616f-4tiled
[410.936267] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.144s)
[410.938272] Subtest draw-method-mmap-cpu: SUCCESS (2.046s)
[411.040983] [091/133] (610s left) gem_lmem_evict (dontneed-evict-race)
[411.079949] Subtest dontneed-evict-race: SKIP (0.000s)
[411.127866] [092/133] (610s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[411.225900] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[414.935981] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.715s)
[416.096516] [093/133] (605s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[416.187769] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[416.191881] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[416.283305] [094/133] (605s left) syncobj_timeline (signal-array)
[416.327831] Starting subtest: signal-array
[416.330051] Subtest signal-array: SUCCESS (0.000s)
[416.417408] [095/133] (605s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[416.520289] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[417.632038] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.114s)
[417.740607] [096/133] (604s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[417.846743] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[417.850246] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[417.967409] [097/133] (603s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[418.074640] Starting subtest: short-busy-flip-before-cursor-toggle
[418.078400] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[418.219948] [098/133] (603s left) kms_vblank (query-forked-hang)
[418.316038] Starting subtest: query-forked-hang
[418.317643] Starting dynamic subtest: pipe-A-eDP-1
[445.278583] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.961s)
[445.293565] Starting dynamic subtest: pipe-D-eDP-1
[470.299358] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.015s)
[470.301721] Subtest query-forked-hang: SUCCESS (51.980s)
[471.145595] [099/133] (550s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[471.251260] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[471.255510] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[471.348820] [100/133] (550s left) kms_cursor_crc (cursor-onscreen-max-size)
[471.681222] Starting subtest: cursor-onscreen-max-size
[471.688059] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[471.821819] [101/133] (550s left) gem_basic (bad-close)
[471.879583] Starting subtest: bad-close
[471.883668] Subtest bad-close: SUCCESS (0.000s)
[471.975921] [102/133] (550s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[472.068938] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[472.070881] Starting dynamic subtest: pipe-A-eDP-1
[472.216317] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.139s)
[472.228891] Starting dynamic subtest: pipe-B-eDP-1
[472.245976] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[472.256944] Starting dynamic subtest: pipe-C-eDP-1
[472.273026] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[472.281960] Starting dynamic subtest: pipe-D-eDP-1
[472.296017] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s)
[472.297605] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.176s)
[474.509260] [103/133] (547s left) gem_eio (in-flight-contexts-10ms)
[474.717771] Starting subtest: in-flight-contexts-10ms
[488.567030] Subtest in-flight-contexts-10ms: SUCCESS (13.849s)
[488.748960] [104/133] (533s left) gem_exec_gttfill (multigpu-basic)
[488.922092] Starting subtest: multigpu-basic
[502.549917] Subtest multigpu-basic: SUCCESS (13.630s)
[502.652918] [105/133] (519s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier)
[502.802394] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier
[502.806583] Subtest pixel-format-4-tiled-lnl-ccs-modifier: SKIP (0.000s)
[502.887273] [106/133] (519s left) kms_prime (basic-crc-vgem)
[502.979565] Starting subtest: basic-crc-vgem
[502.981873] Starting dynamic subtest: second-to-first
[503.945175] Dynamic subtest second-to-first: SUCCESS (0.970s)
[503.947150] Subtest basic-crc-vgem: SUCCESS (0.971s)
[504.044934] [107/133] (518s left) gem_ctx_isolation (nonpriv)
[504.148110] Starting subtest: nonpriv
[504.149913] Starting dynamic subtest: rcs0
[504.361289] Dynamic subtest rcs0: SUCCESS (0.215s)
[504.370957] Starting dynamic subtest: bcs0
[504.606874] Dynamic subtest bcs0: SUCCESS (0.245s)
[504.619422] Starting dynamic subtest: vcs0
[504.859515] Dynamic subtest vcs0: SUCCESS (0.252s)
[504.871633] Starting dynamic subtest: vcs1
[506.760779] Dynamic subtest vcs1: SUCCESS (1.900s)
[506.773518] Starting dynamic subtest: vecs0
[508.731925] Dynamic subtest vecs0: SUCCESS (1.970s)
[508.741799] Starting dynamic subtest: ccs0
[510.741893] Dynamic subtest ccs0: SUCCESS (2.009s)
[510.744350] Subtest nonpriv: SUCCESS (6.596s)
[510.988946] [108/133] (511s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[511.048311] Starting subtest: basic-batch-kernel-default-cmd
[511.052441] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[511.246466] [109/133] (511s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[511.339523] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[511.343851] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[511.433809] [110/133] (510s left) gem_softpin (allocator-basic)
[511.481898] Starting subtest: allocator-basic
[511.554265] Subtest allocator-basic: SUCCESS (0.073s)
[511.668457] [111/133] (510s left) kms_flip (modeset-vs-vblank-race-interruptible)
[511.769994] Starting subtest: modeset-vs-vblank-race-interruptible
[511.772614] Starting dynamic subtest: A-eDP1
[516.431460] Dynamic subtest A-eDP1: SUCCESS (4.660s)
[516.444123] Starting dynamic subtest: B-eDP1
[521.062405] Dynamic subtest B-eDP1: SUCCESS (4.627s)
[521.075567] Starting dynamic subtest: C-eDP1
[525.585183] Dynamic subtest C-eDP1: SUCCESS (4.520s)
[525.597177] Starting dynamic subtest: D-eDP1
[530.133047] Dynamic subtest D-eDP1: SUCCESS (4.547s)
[530.135297] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.366s)
[531.298662] [112/133] (491s left) gen9_exec_parse (allowed-all)
[531.363790] Subtest allowed-all: SKIP (0.000s)
[531.434415] [113/133] (491s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[531.532994] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[531.537410] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[531.600055] [114/133] (490s left) kms_invalid_mode (bad-vtotal)
[531.695894] Starting subtest: bad-vtotal
[531.698190] Starting dynamic subtest: pipe-A-eDP-1
[531.981521] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[531.992441] Starting dynamic subtest: pipe-B-eDP-1
[531.994924] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[532.004268] Starting dynamic subtest: pipe-C-eDP-1
[532.006390] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[532.015711] Starting dynamic subtest: pipe-D-eDP-1
[532.018135] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[532.020879] Subtest bad-vtotal: SUCCESS (0.294s)
[532.833910] [115/133] (489s left) gem_tiled_partial_pwrite_pread (reads)
[532.884416] Subtest reads: SKIP (0.000s)
[532.954973] [116/133] (489s left) gem_wait (await)
[533.013687] Starting subtest: await
[533.016095] Starting dynamic subtest: all-engines
[534.107288] Dynamic subtest all-engines: SUCCESS (1.093s)
[534.124357] Starting dynamic subtest: rcs0
[535.165102] Dynamic subtest rcs0: SUCCESS (1.055s)
[535.182497] Starting dynamic subtest: bcs0
[536.223974] Dynamic subtest bcs0: SUCCESS (1.056s)
[536.241417] Starting dynamic subtest: vcs0
[537.278703] Dynamic subtest vcs0: SUCCESS (1.052s)
[537.297074] Starting dynamic subtest: vcs1
[538.331913] Dynamic subtest vcs1: SUCCESS (1.051s)
[538.348490] Starting dynamic subtest: vecs0
[539.384963] Dynamic subtest vecs0: SUCCESS (1.051s)
[539.401619] Starting dynamic subtest: ccs0
[540.441904] Dynamic subtest ccs0: SUCCESS (1.054s)
[540.444374] Subtest await: SUCCESS (7.429s)
[540.654876] [117/133] (482s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[540.724512] Starting subtest: yf-tiled-ccs-to-yf-tiled
[540.729259] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[540.910209] [118/133] (481s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[540.972506] Starting subtest: multi-wait-for-submit-submitted-signaled
[540.974761] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[541.088354] [119/133] (481s left) testdisplay
[541.408496] [120/133] (481s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[541.498438] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[541.502810] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[542.656240] [121/133] (480s left) gem_mmap_gtt (bad-object)
[542.718313] Subtest bad-object: SKIP (0.000s)
[542.788878] [122/133] (480s left) kms_addfb_basic (unused-modifier)
[542.846646] Starting subtest: unused-modifier
[542.848551] Subtest unused-modifier: SUCCESS (0.000s)
[542.983140] [123/133] (479s left) gem_exec_params (dr4-dirt)
[543.041421] Starting subtest: DR4-dirt
[543.043666] Subtest DR4-dirt: SUCCESS (0.000s)
[543.145970] [124/133] (479s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[543.233510] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[543.235824] Starting dynamic subtest: pipe-A-eDP-1
[543.259237] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[543.272497] Starting dynamic subtest: pipe-B-eDP-1
[543.293128] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[543.302772] Starting dynamic subtest: pipe-C-eDP-1
[543.323829] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[543.334497] Starting dynamic subtest: pipe-D-eDP-1
[543.356082] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[543.360230] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[545.437221] [125/133] (477s left) kms_rotation_crc (primary-rotation-270)
[545.533588] Starting subtest: primary-rotation-270
[545.537621] Subtest primary-rotation-270: SKIP (0.001s)
[545.604151] [126/133] (477s left) perf (invalid-oa-exponent)
[545.708713] Starting subtest: invalid-oa-exponent
[545.721071] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[545.760484] [127/133] (477s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[545.853609] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[545.857590] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[545.961802] [128/133] (477s left) i915_selftest (mock)
[547.699807] Starting subtest: mock
[547.703305] Starting dynamic subtest: sanitycheck
[548.378493] Dynamic subtest sanitycheck: SUCCESS (0.678s)
[548.388946] Starting dynamic subtest: shmem
[549.046136] Dynamic subtest shmem: SUCCESS (0.665s)
[549.063333] Starting dynamic subtest: fence
[556.606490] Dynamic subtest fence: SUCCESS (7.556s)
[556.617066] Starting dynamic subtest: scatterlist
[558.245501] Dynamic subtest scatterlist: SUCCESS (1.638s)
[558.256222] Starting dynamic subtest: syncmap
[561.496350] Dynamic subtest syncmap: SUCCESS (3.249s)
[561.506991] Starting dynamic subtest: uncore
[562.161480] Dynamic subtest uncore: SUCCESS (0.663s)
[562.171714] Starting dynamic subtest: ring
[562.845679] Dynamic subtest ring: SUCCESS (0.682s)
[562.855736] Starting dynamic subtest: engine
[563.516508] Dynamic subtest engine: SUCCESS (0.669s)
[563.526748] Starting dynamic subtest: timelines
[567.295269] Dynamic subtest timelines: SUCCESS (3.776s)
[567.305360] Starting dynamic subtest: requests
[570.653282] Dynamic subtest requests: SUCCESS (3.356s)
[570.663671] Starting dynamic subtest: objects
[571.388378] Dynamic subtest objects: SUCCESS (0.734s)
[571.398517] Starting dynamic subtest: phys
[572.145570] Dynamic subtest phys: SUCCESS (0.756s)
[572.155546] Starting dynamic subtest: dmabuf
[572.920204] Dynamic subtest dmabuf: SUCCESS (0.772s)
[572.930422] Starting dynamic subtest: vma
[578.758076] Dynamic subtest vma: SUCCESS (5.836s)
[578.768426] Starting dynamic subtest: evict
[580.178111] Dynamic subtest evict: SUCCESS (1.418s)
[580.188071] Starting dynamic subtest: gtt
[583.442216] Dynamic subtest gtt: SUCCESS (3.262s)
[583.457135] Starting dynamic subtest: hugepages
[584.311676] Dynamic subtest hugepages: SUCCESS (0.869s)
[584.321548] Starting dynamic subtest: memory_region
[586.765105] Dynamic subtest memory_region: SUCCESS (2.451s)
[586.767597] Subtest mock: SUCCESS (39.063s)
[586.793939] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[586.794652] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[586.797837] [129/133] (436s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[588.291045] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[588.293550] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[588.391556] [FACT syncobj_wait (invalid-multi-wait-unsubmitted-signaled)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[588.392785] [FACT syncobj_wait (invalid-multi-wait-unsubmitted-signaled)] new: kernel.kmod_is_loaded.i915: true
[588.396723] [130/133] (434s left) kms_psr (fbc-psr2-primary-page-flip)
[588.508587] Starting subtest: fbc-psr2-primary-page-flip
[588.510826] Starting dynamic subtest: eDP-1
[588.515046] Dynamic subtest eDP-1: SKIP (0.000s)
[588.519229] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[588.580755] [131/133] (434s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[588.639533] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[588.744906] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[588.822078] [132/133] (434s left) kms_big_fb (linear-32bpp-rotate-90)
[588.926528] Starting subtest: linear-32bpp-rotate-90
[588.963099] Subtest linear-32bpp-rotate-90: SKIP (0.036s)
[589.089136] [133/133] (434s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[589.202381] Starting subtest: fbcpsr-shrfb-scaledprimary
[594.996546] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.800s)
[595.143214] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_plane_scaling
results: parsing output: 2/ for test: syncobj_wait
results: parsing output: 3/ for test: gem_fenced_exec_thrash
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: gem_flink_basic
results: parsing output: 6/ for test: perf
results: parsing output: 7/ for test: gem_eio
results: parsing output: 8/ for test: kms_plane_alpha_blend
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: i915_module_load
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_3d
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: gem_close
results: parsing output: 15/ for test: prime_busy
results: parsing output: 16/ for test: api_intel_bb
results: parsing output: 17/ for test: vgem_basic
results: parsing output: 18/ for test: gem_flink_race
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_pm_rpm
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_exec_fence
results: parsing output: 23/ for test: kms_psr2_sf
results: parsing output: 24/ for test: syncobj_wait
results: parsing output: 25/ for test: gem_mmap_wc
results: parsing output: 26/ for test: gem_render_copy
results: parsing output: 27/ for test: kms_plane_scaling
results: parsing output: 28/ for test: gem_exec_balancer
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: gem_ctx_persistence
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_chamelium_edid
results: parsing output: 34/ for test: gem_exec_balancer
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_sequence
results: parsing output: 38/ for test: kms_rotation_crc
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: kms_ccs
results: parsing output: 41/ for test: syncobj_wait
results: parsing output: 42/ for test: perf_pmu
results: parsing output: 43/ for test: kms_flip
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_content_protection
results: parsing output: 46/ for test: kms_plane_multiple
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: gem_exec_parallel
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: gem_busy
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_ctx_engines
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: perf
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_exec_async
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_lease
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: api_intel_allocator
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_ccs
results: parsing output: 69/ for test: i915_pm_rpm
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_rmfb
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_psr2_sf
results: parsing output: 74/ for test: kms_color_pipeline
results: parsing output: 75/ for test: i915_drm_fdinfo
results: parsing output: 76/ for test: gem_exec_suspend
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_cursor_crc
results: parsing output: 80/ for test: gem_mmap_offset
results: parsing output: 81/ for test: syncobj_wait
results: parsing output: 82/ for test: core_getversion
results: parsing output: 83/ for test: gem_exec_reloc
results: parsing output: 84/ for test: gem_eio
results: parsing output: 85/ for test: kms_chamelium_hpd
results: parsing output: 86/ for test: kms_atomic
results: parsing output: 87/ for test: gem_ppgtt
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_draw_crc
results: parsing output: 90/ for test: gem_lmem_evict
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: kms_vblank
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_cursor_crc
results: parsing output: 100/ for test: gem_basic
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: gem_eio
results: parsing output: 103/ for test: gem_exec_gttfill
results: parsing output: 104/ for test: kms_plane
results: parsing output: 105/ for test: kms_prime
results: parsing output: 106/ for test: gem_ctx_isolation
results: parsing output: 107/ for test: gem_exec_flush
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_softpin
results: parsing output: 110/ for test: kms_flip
results: parsing output: 111/ for test: gen9_exec_parse
results: parsing output: 112/ for test: kms_psr2_sf
results: parsing output: 113/ for test: kms_invalid_mode
results: parsing output: 114/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 115/ for test: gem_wait
results: parsing output: 116/ for test: gem_render_copy
results: parsing output: 117/ for test: syncobj_wait
results: parsing output: 118/ for test: testdisplay
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: gem_mmap_gtt
results: parsing output: 121/ for test: kms_addfb_basic
results: parsing output: 122/ for test: gem_exec_params
results: parsing output: 123/ for test: kms_ccs
results: parsing output: 124/ for test: kms_rotation_crc
results: parsing output: 125/ for test: perf
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: i915_selftest
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: syncobj_timeline
results: parsing output: 131/ for test: kms_big_fb
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.