[95.075342] Initializing watchdogs
[95.075406]   /dev/watchdog0
[95.090597] [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]
[95.095321] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[95.097875] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[95.101426] [001/133] (960s left) gem_ctx_persistence (engines-cleanup)
[95.174088] Starting subtest: engines-cleanup
[95.176319] Starting dynamic subtest: rcs0
[95.214539] Dynamic subtest rcs0: SUCCESS (0.041s)
[95.223930] Starting dynamic subtest: bcs0
[95.300940] Dynamic subtest bcs0: SUCCESS (0.085s)
[95.310885] Starting dynamic subtest: vcs0
[95.355529] Dynamic subtest vcs0: SUCCESS (0.054s)
[95.365522] Starting dynamic subtest: vcs1
[95.395634] Dynamic subtest vcs1: SUCCESS (0.039s)
[95.405891] Starting dynamic subtest: vecs0
[95.448660] Dynamic subtest vecs0: SUCCESS (0.052s)
[95.458791] Starting dynamic subtest: ccs0
[95.506538] Dynamic subtest ccs0: SUCCESS (0.057s)
[95.509115] Subtest engines-cleanup: SUCCESS (0.333s)
[95.597648] [002/133] (960s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[95.701855] Starting subtest: x-tiled-8bpp-rotate-270
[95.740932] Subtest x-tiled-8bpp-rotate-270: SKIP (0.038s)
[95.864425] [003/133] (959s left) kms_universal_plane (cursor-fb-leak)
[95.956932] Starting subtest: cursor-fb-leak
[95.959437] Starting dynamic subtest: pipe-A-eDP-1
[96.296831] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.341s)
[96.306766] Starting dynamic subtest: pipe-B-eDP-1
[97.673864] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.375s)
[97.684768] Starting dynamic subtest: pipe-C-eDP-1
[98.989288] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.313s)
[98.999722] Starting dynamic subtest: pipe-D-eDP-1
[100.333588] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[100.336053] Subtest cursor-fb-leak: SUCCESS (4.376s)
[101.480958] [004/133] (954s left) gem_ctx_param (invalid-size-set)
[101.538752] Starting subtest: invalid-size-set
[101.541021] Subtest invalid-size-set: SUCCESS (0.001s)
[101.633238] [005/133] (954s left) kms_flip (flip-vs-suspend)
[101.724179] Starting subtest: flip-vs-suspend
[101.726431] Starting dynamic subtest: A-eDP1
[135.626555] Dynamic subtest A-eDP1: SUCCESS (4.357s)
[135.639121] Starting dynamic subtest: B-eDP1
[169.624877] Dynamic subtest B-eDP1: SUCCESS (4.984s)
[169.633881] Starting dynamic subtest: C-eDP1
[203.615317] Dynamic subtest C-eDP1: SUCCESS (4.125s)
[203.623725] Starting dynamic subtest: D-eDP1
[237.627279] Dynamic subtest D-eDP1: SUCCESS (4.163s)
[237.632033] Subtest flip-vs-suspend: SUCCESS (17.638s)
[247.804446] [006/133] (817s left) core_debugfs (read-all-entries)
[247.867038] Starting subtest: read-all-entries
[247.960699] Subtest read-all-entries: SUCCESS (0.096s)
[248.037870] [007/133] (816s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[248.138649] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[248.143136] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[248.238582] [008/133] (816s left) gem_exec_fence (expired-history)
[248.711503] Starting subtest: expired-history
[256.021034] Subtest expired-history: SUCCESS (7.150s)
[256.470936] [FACT gem_exec_fence (expired-history)] new: kernel.kmod_is_loaded.vgem: true
[256.474055] [009/133] (808s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[256.593831] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[257.617598] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.014s)
[257.755342] [010/133] (807s left) gen9_exec_parse (batch-without-end)
[257.817265] Subtest batch-without-end: SKIP (0.000s)
[257.894399] [011/133] (807s left) gem_sync (basic-all)
[257.960301] Starting subtest: basic-all
[263.116387] Subtest basic-all: SUCCESS (5.121s)
[263.307402] [012/133] (801s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[263.365075] Starting subtest: x-tiled-to-vebox-yf-tiled
[263.369754] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[263.555544] [013/133] (801s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[263.677349] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[263.681389] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[263.793034] [014/133] (801s left) perf (invalid-oa-format-id)
[263.925010] Starting subtest: invalid-oa-format-id
[263.937062] Subtest invalid-oa-format-id: SUCCESS (0.012s)
[263.988938] [015/133] (801s left) gem_softpin (evict-prime)
[264.043877] Starting subtest: evict-prime
[264.045769] Starting dynamic subtest: rcs0
[265.197680] Dynamic subtest rcs0: SUCCESS (1.150s)
[265.212902] Starting dynamic subtest: bcs0
[266.339988] Dynamic subtest bcs0: SUCCESS (1.137s)
[266.351230] Starting dynamic subtest: vcs0
[268.111724] Dynamic subtest vcs0: SUCCESS (1.763s)
[268.121492] Starting dynamic subtest: vcs1
[270.153340] Dynamic subtest vcs1: SUCCESS (2.035s)
[270.164903] Starting dynamic subtest: vecs0
[272.141051] Dynamic subtest vecs0: SUCCESS (1.984s)
[272.152378] Starting dynamic subtest: ccs0
[273.258739] Dynamic subtest ccs0: SUCCESS (1.117s)
[273.268208] Starting dynamic subtest: all
[275.151949] Dynamic subtest all: SUCCESS (1.889s)
[275.154689] Subtest evict-prime: SUCCESS (11.082s)
[275.249085] [016/133] (789s left) kms_plane (pixel-format-linear-modifier-source-clamping)
[275.347402] Starting subtest: pixel-format-linear-modifier-source-clamping
[275.376380] Starting dynamic subtest: pipe-A-plane-0
[278.901473] Dynamic subtest pipe-A-plane-0: SUCCESS (3.523s)
[278.910175] Starting dynamic subtest: pipe-A-plane-3
[280.613427] Dynamic subtest pipe-A-plane-3: SUCCESS (1.709s)
[281.734756] Starting dynamic subtest: pipe-B-plane-0
[285.462395] Dynamic subtest pipe-B-plane-0: SUCCESS (3.733s)
[285.476471] Starting dynamic subtest: pipe-B-plane-3
[287.107401] Dynamic subtest pipe-B-plane-3: SUCCESS (1.643s)
[287.422528] Subtest pixel-format-linear-modifier-source-clamping: SUCCESS (12.064s)
[288.235700] [017/133] (777s left) kms_pm_rpm (basic-rte)
[288.270041] Starting subtest: basic-rte
[291.842000] Subtest basic-rte: SUCCESS (3.571s)
[293.348140] [018/133] (771s left) kms_psr (psr2-cursor-mmap-gtt)
[293.439126] Starting subtest: psr2-cursor-mmap-gtt
[293.441463] Starting dynamic subtest: eDP-1
[295.118183] Dynamic subtest eDP-1: SUCCESS (1.677s)
[295.120485] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.678s)
[295.996824] [019/133] (769s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[296.092802] Starting subtest: psr-rgb565-draw-mmap-cpu
[303.001055] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.908s)
[303.114771] [020/133] (762s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[303.169047] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[303.271409] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.102s)
[303.345961] [021/133] (762s left) kms_vblank (ts-continuation-idle-hang)
[303.448317] Starting subtest: ts-continuation-idle-hang
[303.450238] Starting dynamic subtest: pipe-A-eDP-1
[330.975569] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.512s)
[330.989519] Starting dynamic subtest: pipe-D-eDP-1
[354.958982] Dynamic subtest pipe-D-eDP-1: SUCCESS (23.985s)
[354.961208] Subtest ts-continuation-idle-hang: SUCCESS (51.501s)
[355.817627] [022/133] (709s left) gem_eio (wait-1us)
[356.029481] Starting subtest: wait-1us
[356.331729] Subtest wait-1us: SUCCESS (0.304s)
[356.496464] [023/133] (709s left) kms_mmap_write_crc (main)
[356.598259] Starting subtest: main
[356.600393] Starting dynamic subtest: pipe-A-eDP-1
[360.083521] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.483s)
[360.085406] Subtest main: SUCCESS (3.484s)
[360.946618] [024/133] (704s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[361.042624] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[365.491700] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.452s)
[366.657322] [025/133] (698s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[366.721680] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[366.791186] [026/133] (698s left) kms_atomic (plane-primary-legacy)
[366.877033] Starting subtest: plane-primary-legacy
[366.879249] Starting dynamic subtest: pipe-A-eDP-1
[367.335524] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.458s)
[367.337865] Subtest plane-primary-legacy: SUCCESS (0.459s)
[368.198474] [027/133] (697s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[368.295177] Starting subtest: addfb25-y-tiled-legacy
[368.297258] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[368.382090] [028/133] (697s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[368.483425] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[368.485725] Starting dynamic subtest: pipe-A-eDP-1
[368.508355] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[368.519758] Starting dynamic subtest: pipe-B-eDP-1
[368.540832] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[368.549846] Starting dynamic subtest: pipe-C-eDP-1
[368.571069] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[368.579618] Starting dynamic subtest: pipe-D-eDP-1
[368.601492] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[368.605782] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[370.664712] [029/133] (695s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[370.772892] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[370.777362] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[370.898120] [030/133] (694s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[370.984419] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[370.988785] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[371.076509] [031/133] (694s left) kms_psr (psr-sprite-plane-onoff)
[371.168248] Starting subtest: psr-sprite-plane-onoff
[371.170543] Starting dynamic subtest: eDP-1
[372.903505] Dynamic subtest eDP-1: SUCCESS (1.735s)
[372.905910] Subtest psr-sprite-plane-onoff: SUCCESS (1.736s)
[373.723047] [032/133] (692s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[374.105776] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[374.941695] [033/133] (691s left) gem_exec_schedule (preempt-user)
[375.014590] Starting subtest: preempt-user
[375.016914] Starting dynamic subtest: rcs0
[375.034100] Dynamic subtest rcs0: SUCCESS (0.028s)
[375.047378] Starting dynamic subtest: bcs0
[375.056100] Dynamic subtest bcs0: SUCCESS (0.021s)
[375.066065] Starting dynamic subtest: vcs0
[375.075481] Dynamic subtest vcs0: SUCCESS (0.019s)
[375.086900] Starting dynamic subtest: vcs1
[375.095450] Dynamic subtest vcs1: SUCCESS (0.019s)
[375.104785] Starting dynamic subtest: vecs0
[375.121324] Dynamic subtest vecs0: SUCCESS (0.025s)
[375.130892] Starting dynamic subtest: ccs0
[375.144500] Dynamic subtest ccs0: SUCCESS (0.022s)
[375.146780] Subtest preempt-user: SUCCESS (0.139s)
[376.227019] [034/133] (689s left) i915_hangman (engine-engine-hang)
[376.291791] Starting subtest: engine-engine-hang
[376.293994] Starting dynamic subtest: rcs0
[378.154689] Dynamic subtest rcs0: SUCCESS (1.862s)
[378.167693] Starting dynamic subtest: bcs0
[380.138598] Dynamic subtest bcs0: SUCCESS (1.981s)
[380.153841] Starting dynamic subtest: vcs0
[382.123218] Dynamic subtest vcs0: SUCCESS (1.982s)
[382.137128] Starting dynamic subtest: vcs1
[384.106192] Dynamic subtest vcs1: SUCCESS (1.980s)
[384.119030] Starting dynamic subtest: vecs0
[386.154346] Dynamic subtest vecs0: SUCCESS (2.045s)
[386.169719] Starting dynamic subtest: ccs0
[388.138148] Dynamic subtest ccs0: SUCCESS (1.981s)
[388.141011] Subtest engine-engine-hang: SUCCESS (11.846s)
[388.257786] [035/133] (677s left) i915_pm_rps (waitboost)
[388.315530] Starting subtest: waitboost
[394.418840] Subtest waitboost: SUCCESS (6.104s)
[394.537670] [036/133] (671s left) gem_mmap_offset (oob-read)
[394.590171] Starting subtest: oob-read
[394.592435] Subtest oob-read: SUCCESS (0.000s)
[394.687518] [037/133] (671s left) gem_ccs (block-copy-compressed)
[394.740459] Starting subtest: block-copy-compressed
[394.744509] Subtest block-copy-compressed: SKIP (0.000s)
[394.837096] [038/133] (671s left) gem_softpin (overlap)
[394.897758] Starting subtest: overlap
[395.842729] Subtest overlap: SUCCESS (0.946s)
[395.960671] [039/133] (670s left) perf_pmu (idle-no-semaphores)
[395.998395] Starting subtest: idle-no-semaphores
[396.000560] Starting dynamic subtest: rcs0
[396.544971] Dynamic subtest rcs0: SUCCESS (0.545s)
[396.557930] Starting dynamic subtest: bcs0
[397.059842] Dynamic subtest bcs0: SUCCESS (0.513s)
[397.074219] Starting dynamic subtest: vcs0
[397.576477] Dynamic subtest vcs0: SUCCESS (0.514s)
[397.587982] Starting dynamic subtest: vcs1
[398.111040] Dynamic subtest vcs1: SUCCESS (0.520s)
[398.122966] Starting dynamic subtest: vecs0
[398.617187] Dynamic subtest vecs0: SUCCESS (0.515s)
[398.629864] Starting dynamic subtest: ccs0
[399.143754] Dynamic subtest ccs0: SUCCESS (0.524s)
[399.146563] Subtest idle-no-semaphores: SUCCESS (3.147s)
[399.245309] [040/133] (667s left) kms_feature_discovery (display-1x)
[399.347266] Starting subtest: display-1x
[399.349535] Subtest display-1x: SUCCESS (0.000s)
[399.423436] [041/133] (666s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[399.599883] Starting subtest: cursor-rapid-movement-64x64
[399.602142] Starting dynamic subtest: pipe-A-eDP-1
[400.157590] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.554s)
[400.187902] Starting dynamic subtest: pipe-D-eDP-1
[401.391882] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.214s)
[401.394218] Subtest cursor-rapid-movement-64x64: SUCCESS (1.794s)
[402.241594] [042/133] (664s left) i915_pm_rpm (system-suspend-execbuf)
[404.370577] Starting subtest: system-suspend-execbuf
[462.114499] Subtest system-suspend-execbuf: SUCCESS (42.713s)
[462.502488] [043/133] (603s left) gem_mmap_gtt (cpuset-medium-copy)
[462.562748] Subtest cpuset-medium-copy: SKIP (0.000s)
[462.625439] [044/133] (603s left) gem_pwrite_snooped
[462.765967] [045/133] (603s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[462.858601] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[462.860783] Starting dynamic subtest: pipe-A
[462.923501] Dynamic subtest pipe-A: SUCCESS (0.065s)
[462.943590] Starting dynamic subtest: pipe-B
[464.116360] Dynamic subtest pipe-B: SUCCESS (1.191s)
[464.128085] Starting dynamic subtest: pipe-C
[465.229880] Dynamic subtest pipe-C: SUCCESS (1.112s)
[465.240377] Starting dynamic subtest: pipe-D
[466.349543] Dynamic subtest pipe-D: SUCCESS (1.119s)
[466.351698] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.493s)
[467.507789] [046/133] (599s left) kms_psr2_su (page_flip-xrgb8888)
[467.563736] Subtest page_flip-XRGB8888: SKIP (0.000s)
[467.619956] [047/133] (598s left) kms_flip (basic-flip-vs-dpms)
[467.716189] Starting subtest: basic-flip-vs-dpms
[467.718733] Starting dynamic subtest: A-eDP1
[470.312570] Dynamic subtest A-eDP1: SUCCESS (2.593s)
[470.325749] Starting dynamic subtest: B-eDP1
[472.488111] Dynamic subtest B-eDP1: SUCCESS (2.174s)
[472.498583] Starting dynamic subtest: C-eDP1
[474.576695] Dynamic subtest C-eDP1: SUCCESS (2.085s)
[474.590259] Starting dynamic subtest: D-eDP1
[476.670839] Dynamic subtest D-eDP1: SUCCESS (2.094s)
[476.675891] Subtest basic-flip-vs-dpms: SUCCESS (8.957s)
[477.460121] [048/133] (589s left) gem_exec_balancer (invalid-balancer)
[477.533656] Starting subtest: invalid-balancer
[478.473980] Subtest invalid-balancer: SUCCESS (0.939s)
[478.783913] [049/133] (587s left) kms_setmode (basic-clone-single-crtc)
[478.845851] Starting subtest: basic-clone-single-crtc
[478.852615] Subtest basic-clone-single-crtc: SKIP (0.000s)
[478.953918] [050/133] (587s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[479.044875] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[479.049063] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[479.145931] [051/133] (587s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[479.243784] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[479.255603] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[479.337453] [052/133] (587s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[479.385270] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[479.387590] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[479.498080] [053/133] (587s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[479.588677] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[479.590987] Starting dynamic subtest: pipe-A-eDP-1
[479.613353] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[479.624996] Starting dynamic subtest: pipe-B-eDP-1
[479.645798] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[479.655156] Starting dynamic subtest: pipe-C-eDP-1
[479.676267] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[479.685167] Starting dynamic subtest: pipe-D-eDP-1
[479.702850] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[479.706458] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[481.838020] [054/133] (585s left) kms_content_protection (uevent)
[481.979504] Starting subtest: uevent
[481.984182] Subtest uevent: SKIP (0.000s)
[482.093824] [055/133] (584s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[482.192690] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[489.474799] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.283s)
[489.613112] [056/133] (577s left) kms_plane (plane-position-hole-dpms)
[489.706366] Starting subtest: plane-position-hole-dpms
[490.248761] Starting dynamic subtest: pipe-A-plane-1
[492.671987] Dynamic subtest pipe-A-plane-1: SUCCESS (2.425s)
[492.682641] Starting dynamic subtest: pipe-A-plane-2
[495.009915] Dynamic subtest pipe-A-plane-2: SUCCESS (2.336s)
[495.021178] Starting dynamic subtest: pipe-A-plane-3
[497.383263] Dynamic subtest pipe-A-plane-3: SUCCESS (2.371s)
[497.393917] Starting dynamic subtest: pipe-A-plane-4
[499.727009] Dynamic subtest pipe-A-plane-4: SUCCESS (2.341s)
[499.737699] Starting dynamic subtest: pipe-A-plane-5
[499.750916] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[500.911863] Starting dynamic subtest: pipe-B-plane-1
[503.330335] Dynamic subtest pipe-B-plane-1: SUCCESS (2.426s)
[503.341117] Starting dynamic subtest: pipe-B-plane-2
[505.681719] Dynamic subtest pipe-B-plane-2: SUCCESS (2.350s)
[505.691700] Starting dynamic subtest: pipe-B-plane-3
[508.072938] Dynamic subtest pipe-B-plane-3: SUCCESS (2.387s)
[508.091071] Starting dynamic subtest: pipe-B-plane-4
[510.504156] Dynamic subtest pipe-B-plane-4: SUCCESS (2.431s)
[510.514432] Starting dynamic subtest: pipe-B-plane-5
[510.530303] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[510.532191] Subtest plane-position-hole-dpms: SUCCESS (20.814s)
[511.357342] [057/133] (555s left) gem_mmap_gtt (big-copy)
[511.423556] Subtest big-copy: SKIP (0.000s)
[511.497463] [058/133] (555s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[511.611474] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[511.615597] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[511.726302] [059/133] (555s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[511.835284] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[511.839383] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[511.939017] [060/133] (555s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[512.039401] Starting subtest: crc-primary-suspend-y-tiled-ccs
[512.041641] Starting dynamic subtest: pipe-A-eDP-1
[512.050436] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[512.060965] Starting dynamic subtest: pipe-B-eDP-1
[512.069979] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[512.080020] Starting dynamic subtest: pipe-C-eDP-1
[512.088419] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[512.097917] Starting dynamic subtest: pipe-D-eDP-1
[512.106254] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[512.110254] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[514.314399] [061/133] (552s left) kms_chamelium_audio (dp-audio-edid)
[514.715768] Subtest dp-audio-edid: SKIP (0.000s)
[515.532328] [062/133] (551s left) perf_pmu (interrupts)
[515.575406] Starting subtest: interrupts
[516.668184] Subtest interrupts: SUCCESS (1.092s)
[516.780709] [063/133] (550s left) syncobj_wait (single-wait-all-for-submit-signaled)
[516.844258] Starting subtest: single-wait-all-for-submit-signaled
[516.846133] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[516.960581] [064/133] (550s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[517.071855] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[517.076224] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[517.187297] [065/133] (550s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[517.286364] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[517.290614] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[517.374302] [066/133] (550s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[517.480507] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[517.484631] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[517.578792] [067/133] (549s left) gem_exec_schedule (fairslice)
[517.645049] Starting subtest: fairslice
[517.647257] Starting dynamic subtest: rcs0
[519.664810] Dynamic subtest rcs0: SUCCESS (2.020s)
[519.679872] Starting dynamic subtest: bcs0
[521.681918] Dynamic subtest bcs0: SUCCESS (2.017s)
[521.693288] Starting dynamic subtest: vcs0
[523.704355] Dynamic subtest vcs0: SUCCESS (2.020s)
[523.716175] Starting dynamic subtest: vcs1
[525.723950] Dynamic subtest vcs1: SUCCESS (2.018s)
[525.734753] Starting dynamic subtest: vecs0
[527.743428] Dynamic subtest vecs0: SUCCESS (2.018s)
[527.754839] Starting dynamic subtest: ccs0
[529.762002] Dynamic subtest ccs0: SUCCESS (2.021s)
[529.764128] Subtest fairslice: SUCCESS (12.119s)
[530.063660] [068/133] (537s left) gem_userptr_blits (vma-merge)
[530.134150] Starting subtest: vma-merge
[530.188825] Subtest vma-merge: SUCCESS (0.054s)
[530.320397] [069/133] (537s left) gem_workarounds (suspend-resume-fd)
[530.405330] Starting subtest: suspend-resume-fd
[546.592726] Subtest suspend-resume-fd: SUCCESS (1.565s)
[556.771723] [070/133] (520s left) kms_flip (absolute-wf_vblank-interruptible)
[556.915068] Starting subtest: absolute-wf_vblank-interruptible
[556.920271] Starting dynamic subtest: A-eDP1
[565.678067] Dynamic subtest A-eDP1: SUCCESS (8.581s)
[565.693511] Starting dynamic subtest: B-eDP1
[574.488203] Dynamic subtest B-eDP1: SUCCESS (8.739s)
[574.505205] Starting dynamic subtest: C-eDP1
[583.239350] Dynamic subtest C-eDP1: SUCCESS (8.725s)
[583.251506] Starting dynamic subtest: D-eDP1
[591.980030] Dynamic subtest D-eDP1: SUCCESS (8.730s)
[591.985505] Subtest absolute-wf_vblank-interruptible: SUCCESS (34.789s)
[593.195821] [071/133] (484s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[593.302795] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[593.307241] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[593.424021] [072/133] (484s left) kms_flip (2x-plain-flip)
[593.526194] Starting subtest: 2x-plain-flip
[593.534714] Subtest 2x-plain-flip: SKIP (0.011s)
[593.616170] [073/133] (484s left) gem_workarounds (reset)
[593.683499] Starting subtest: reset
[593.847768] Subtest reset: SUCCESS (0.164s)
[593.982564] [074/133] (483s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[594.084430] Starting subtest: fbcpsr-rgb565-draw-pwrite
[601.265506] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (7.180s)
[601.382988] [075/133] (476s left) prime_mmap (test_reprime)
[601.443183] Starting subtest: test_reprime
[601.445448] Starting dynamic subtest: test_reprime-smem
[601.475418] Dynamic subtest test_reprime-smem: SUCCESS (0.034s)
[601.477782] Subtest test_reprime: SUCCESS (0.035s)
[601.577818] [076/133] (476s left) gem_exec_reloc (basic-gtt-cpu)
[601.654645] Subtest basic-gtt-cpu: SKIP (0.000s)
[601.725370] [077/133] (476s left) sw_sync (sync_busy_fork_unixsocket)
[601.763248] Starting subtest: sync_busy_fork_unixsocket
[601.768965] Subtest sync_busy_fork_unixsocket: SUCCESS (0.007s)
[601.828252] [078/133] (476s left) gem_exec_whisper (basic-sync-all)
[601.889260] Starting subtest: basic-sync-all
[609.566021] Subtest basic-sync-all: SUCCESS (7.671s)
[609.752927] [079/133] (468s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[609.858009] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[616.812935] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.954s)
[616.953701] [080/133] (461s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[617.515390] Starting subtest: syncobj-backward-timeline-chain-engines
[617.641452] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.130s)
[618.661656] [081/133] (459s left) kms_setmode (invalid-clone-single-crtc)
[618.711464] Starting subtest: invalid-clone-single-crtc
[618.718065] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[618.829484] [082/133] (459s left) api_intel_allocator (two-level-inception-interruptible)
[618.894807] Starting subtest: two-level-inception-interruptible
[624.304224] Subtest two-level-inception-interruptible: SUCCESS (5.408s)
[624.449438] [083/133] (453s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[624.570720] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[624.574683] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[624.663528] [084/133] (453s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[624.778449] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[624.782894] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[624.850333] [085/133] (453s left) gem_pxp (create-protected-buffer)
[624.965964] Starting subtest: create-protected-buffer
[624.968436] Subtest create-protected-buffer: SUCCESS (0.000s)
[625.075342] [086/133] (453s left) kms_big_fb (x-tiled-addfb-size-overflow)
[625.184687] Starting subtest: x-tiled-addfb-size-overflow
[625.187061] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[625.305764] [087/133] (453s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier)
[625.417921] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier
[625.421477] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier: SKIP (0.000s)
[625.508564] [088/133] (452s left) kms_force_connector_basic (force-edid)
[625.602243] Starting subtest: force-edid
[625.972765] Subtest force-edid: SUCCESS (0.371s)
[626.116386] [089/133] (452s left) gem_userptr_blits (readonly-unsync)
[626.197029] Subtest readonly-unsync: SKIP (0.000s)
[626.347330] [090/133] (452s left) kms_color (ctm-0-50)
[626.450806] Starting subtest: ctm-0-50
[626.452462] Starting dynamic subtest: pipe-A-eDP-1
[629.952677] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.501s)
[629.963748] Starting dynamic subtest: pipe-B-eDP-1
[634.190084] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.234s)
[634.202456] Starting dynamic subtest: pipe-C-eDP-1
[638.216906] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.026s)
[638.230077] Starting dynamic subtest: pipe-D-eDP-1
[642.314388] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.094s)
[642.317175] Subtest ctm-0-50: SUCCESS (15.857s)
[643.131631] [091/133] (435s left) gem_ctx_persistence (legacy-engines-cleanup)
[643.194888] Starting subtest: legacy-engines-cleanup
[643.197276] Starting dynamic subtest: render
[643.244297] Dynamic subtest render: SUCCESS (0.049s)
[643.257915] Starting dynamic subtest: bsd1
[643.306673] Dynamic subtest bsd1: SUCCESS (0.061s)
[643.323050] Starting dynamic subtest: bsd2
[643.349838] Dynamic subtest bsd2: SUCCESS (0.040s)
[643.363060] Starting dynamic subtest: blt
[643.395630] Dynamic subtest blt: SUCCESS (0.043s)
[643.408915] Starting dynamic subtest: vebox
[643.446084] Dynamic subtest vebox: SUCCESS (0.048s)
[643.448724] Subtest legacy-engines-cleanup: SUCCESS (0.252s)
[643.541111] [092/133] (435s left) gem_exec_reloc (basic-write-gtt-noreloc)
[643.592032] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[643.660257] [093/133] (435s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[643.716273] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[643.720719] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[643.905136] [094/133] (434s left) i915_drm_fdinfo (virtual-busy-all)
[643.946154] Starting subtest: virtual-busy-all
[643.950352] Subtest virtual-busy-all: SKIP (0.000s)
[644.010157] [095/133] (434s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[644.057442] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[644.161767] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.102s)
[644.245355] [096/133] (434s left) gem_eio (create-ext)
[644.491515] Starting subtest: create-ext
[644.841767] Subtest create-ext: SUCCESS (0.352s)
[644.959397] [097/133] (433s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[645.060147] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[645.064021] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[645.166865] [098/133] (433s left) kms_cursor_crc (cursor-alpha-transparent)
[645.264384] Starting subtest: cursor-alpha-transparent
[645.266195] Starting dynamic subtest: pipe-A-eDP-1
[645.873476] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.607s)
[645.885073] Starting dynamic subtest: pipe-D-eDP-1
[647.156825] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.281s)
[647.159097] Subtest cursor-alpha-transparent: SUCCESS (1.892s)
[648.031675] [099/133] (430s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[648.143787] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[655.041427] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.900s)
[655.170884] [100/133] (423s left) kms_vrr (seamless-rr-switch-drrs)
[655.270100] Starting subtest: seamless-rr-switch-drrs
[655.276619] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[655.354086] [101/133] (423s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[655.762499] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[656.604983] [102/133] (422s left) kms_lease (master-vs-lease)
[656.704412] Starting subtest: master-vs-lease
[656.706755] Subtest master-vs-lease: SUCCESS (0.002s)
[656.803460] [103/133] (422s left) kms_getfb (getfb-addfb-different-handles)
[656.887156] Starting subtest: getfb-addfb-different-handles
[656.889484] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[656.982610] [104/133] (422s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[657.076634] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[657.080988] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[657.172020] [105/133] (422s left) kms_joiner (invalid-modeset-force-big-joiner)
[657.260109] Starting subtest: invalid-modeset-force-big-joiner
[657.264572] Subtest invalid-modeset-force-big-joiner: SKIP (0.000s)
[657.346656] [106/133] (421s left) gem_exec_store (pages)
[657.420345] Starting subtest: pages
[657.422658] Starting dynamic subtest: rcs0
[657.514852] Dynamic subtest rcs0: SUCCESS (0.094s)
[657.528326] Starting dynamic subtest: bcs0
[657.592593] Dynamic subtest bcs0: SUCCESS (0.075s)
[657.601967] Starting dynamic subtest: vcs0
[657.671018] Dynamic subtest vcs0: SUCCESS (0.078s)
[657.680311] Starting dynamic subtest: vcs1
[657.744828] Dynamic subtest vcs1: SUCCESS (0.073s)
[657.754185] Starting dynamic subtest: vecs0
[657.816141] Dynamic subtest vecs0: SUCCESS (0.070s)
[657.825502] Starting dynamic subtest: ccs0
[657.884940] Dynamic subtest ccs0: SUCCESS (0.067s)
[657.887147] Subtest pages: SUCCESS (0.465s)
[658.069819] [107/133] (421s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[658.168508] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[658.172591] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[658.243626] [108/133] (421s left) kms_psr (fbc-psr-sprite-plane-move)
[658.333233] Starting subtest: fbc-psr-sprite-plane-move
[658.335514] Starting dynamic subtest: eDP-1
[658.339613] Dynamic subtest eDP-1: SKIP (0.000s)
[658.343625] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[658.411067] [109/133] (420s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[658.506595] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[659.747553] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: FAIL (0.667s)
[659.788427] [110/133] (419s left) syncobj_wait (wait-zero-handles)
[659.851913] Starting subtest: wait-zero-handles
[659.853756] Subtest wait-zero-handles: SUCCESS (0.000s)
[659.966282] [111/133] (419s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[660.087616] Starting subtest: cursor-vs-flip-toggle
[691.320195] Subtest cursor-vs-flip-toggle: SUCCESS (31.235s)
[692.121207] [112/133] (387s left) gem_ctx_engines (execute-one)
[692.183881] Starting subtest: execute-one
[694.842904] Subtest execute-one: SUCCESS (2.659s)
[695.044194] [113/133] (384s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[695.140969] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[702.459883] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.320s)
[702.584600] [114/133] (377s left) dmabuf_sync_file (export-before-signal)
[702.635649] Starting subtest: export-before-signal
[702.637535] Subtest export-before-signal: SUCCESS (0.001s)
[702.710380] [115/133] (376s left) kms_cursor_edge_walk (256x256-top-bottom)
[702.796073] Starting subtest: 256x256-top-bottom
[702.798317] Starting dynamic subtest: pipe-A-eDP-1
[706.253174] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.456s)
[706.263657] Starting dynamic subtest: pipe-D-eDP-1
[709.430044] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.174s)
[709.432327] Subtest 256x256-top-bottom: SUCCESS (6.632s)
[710.305932] [116/133] (369s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[710.403935] Starting subtest: pr-plane-move-sf-dmg-area
[710.407870] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[710.474788] [117/133] (369s left) syncobj_timeline (single-wait-for-submit-signaled)
[710.540736] Starting subtest: single-wait-for-submit-signaled
[710.542657] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[710.643316] [118/133] (369s left) gem_workarounds (basic-read-context)
[710.711227] Starting subtest: basic-read-context
[710.722664] Subtest basic-read-context: SUCCESS (0.010s)
[710.847134] [119/133] (368s left) kms_psr (pr-cursor-mmap-cpu)
[710.938277] Starting subtest: pr-cursor-mmap-cpu
[710.942777] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[711.053127] [120/133] (368s left) kms_psr (fbc-psr2-basic)
[711.147778] Starting subtest: fbc-psr2-basic
[711.149693] Starting dynamic subtest: eDP-1
[711.153770] Dynamic subtest eDP-1: SKIP (0.000s)
[711.157885] Subtest fbc-psr2-basic: SKIP (0.001s)
[711.234791] [121/133] (368s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[711.328966] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[711.330605] Starting dynamic subtest: pipe-A-eDP-1
[711.862769] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.509s)
[711.875120] Starting dynamic subtest: pipe-B-eDP-1
[713.357185] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.488s)
[713.371652] Starting dynamic subtest: pipe-C-eDP-1
[714.785147] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.428s)
[714.797799] Starting dynamic subtest: pipe-D-eDP-1
[716.168545] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.381s)
[716.170838] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.812s)
[717.315361] [122/133] (362s left) gem_ctx_param (non-root-set)
[717.379955] Starting subtest: non-root-set
[717.399096] Subtest non-root-set: SUCCESS (0.019s)
[717.490904] [123/133] (362s left) gem_pread (display)
[717.547257] Subtest display: SKIP (0.000s)
[717.604814] [124/133] (362s left) gem_exec_schedule (preempt-other-chain)
[717.683131] Starting subtest: preempt-other-chain
[717.685177] Starting dynamic subtest: rcs0
[717.770065] Dynamic subtest rcs0: SUCCESS (0.093s)
[717.787257] Starting dynamic subtest: bcs0
[717.856375] Dynamic subtest bcs0: SUCCESS (0.086s)
[717.870434] Starting dynamic subtest: vcs0
[717.933516] Dynamic subtest vcs0: SUCCESS (0.076s)
[717.944445] Starting dynamic subtest: vcs1
[718.019312] Dynamic subtest vcs1: SUCCESS (0.085s)
[718.033277] Starting dynamic subtest: vecs0
[718.106210] Dynamic subtest vecs0: SUCCESS (0.085s)
[718.120951] Starting dynamic subtest: ccs0
[718.193440] Dynamic subtest ccs0: SUCCESS (0.086s)
[718.196340] Subtest preempt-other-chain: SUCCESS (0.517s)
[719.650178] [125/133] (360s left) prime_busy (hang)
[719.712395] Starting subtest: hang
[719.715113] Starting dynamic subtest: rcs0
[746.098484] Dynamic subtest rcs0: SUCCESS (26.376s)
[746.108370] Starting dynamic subtest: bcs0
[753.180294] Dynamic subtest bcs0: SUCCESS (7.077s)
[753.191850] Starting dynamic subtest: vcs0
[760.218124] Dynamic subtest vcs0: SUCCESS (7.035s)
[760.229570] Starting dynamic subtest: vcs1
[767.194037] Dynamic subtest vcs1: SUCCESS (6.976s)
[767.204135] Starting dynamic subtest: vecs0
[774.231471] Dynamic subtest vecs0: SUCCESS (7.033s)
[774.243966] Starting dynamic subtest: ccs0
[788.069671] Dynamic subtest ccs0: SUCCESS (13.833s)
[788.072146] Subtest hang: SUCCESS (68.337s)
[788.196598] [126/133] (291s left) kms_pm_rpm (system-suspend-modeset)
[789.728690] Starting subtest: system-suspend-modeset
[809.811706] Subtest system-suspend-modeset: SUCCESS (4.689s)
[814.210827] [127/133] (269s left) kms_properties (invalid-properties-legacy)
[814.323240] Starting subtest: invalid-properties-legacy
[814.440146] Subtest invalid-properties-legacy: SUCCESS (0.116s)
[814.517481] [128/133] (269s left) gem_fence_thrash (bo-write-verify-y)
[814.548915] Starting subtest: bo-write-verify-y
[814.575272] Subtest bo-write-verify-y: SKIP (0.025s)
[814.641597] [129/133] (269s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[814.704037] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[814.706223] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[814.802775] [130/133] (269s left) kms_cursor_legacy (single-bo)
[814.913311] Starting subtest: single-bo
[814.915503] Starting dynamic subtest: pipe-A
[820.287618] Dynamic subtest pipe-A: SUCCESS (5.380s)
[820.299988] Starting dynamic subtest: pipe-B
[825.664998] Dynamic subtest pipe-B: SUCCESS (5.380s)
[825.677326] Starting dynamic subtest: pipe-C
[831.045778] Dynamic subtest pipe-C: SUCCESS (5.380s)
[831.057363] Starting dynamic subtest: pipe-D
[836.426837] Dynamic subtest pipe-D: SUCCESS (5.379s)
[836.438340] Starting dynamic subtest: all-pipes
[841.814428] Dynamic subtest all-pipes: SUCCESS (5.386s)
[841.816863] Subtest single-bo: SUCCESS (26.919s)
[841.961553] [131/133] (242s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[842.027093] Starting subtest: invalid-bsd1-flag-on-blt
[842.029406] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[842.140711] [132/133] (242s left) gem_close_race (basic-threads)
[842.194716] Starting subtest: basic-threads
[843.591985] Subtest basic-threads: SUCCESS (1.398s)
[843.780691] [133/133] (240s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[843.881075] Starting subtest: y-tiled-32bpp-rotate-0
[843.885565] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[843.978437] Closing watchdogs
results: parsing output: 0/ for test: gem_ctx_persistence
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: kms_universal_plane
results: parsing output: 3/ for test: gem_ctx_param
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: core_debugfs
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: gen9_exec_parse
results: parsing output: 10/ for test: gem_sync
results: parsing output: 11/ for test: gem_render_copy
results: parsing output: 12/ for test: kms_big_fb
results: parsing output: 13/ for test: perf
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: kms_plane
results: parsing output: 16/ for test: kms_pm_rpm
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_vblank
results: parsing output: 21/ for test: gem_eio
results: parsing output: 22/ for test: kms_mmap_write_crc
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_pipe_b_c_ivb
results: parsing output: 25/ for test: kms_atomic
results: parsing output: 26/ for test: kms_addfb_basic
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: i915_hangman
results: parsing output: 34/ for test: i915_pm_rps
results: parsing output: 35/ for test: gem_mmap_offset
results: parsing output: 36/ for test: gem_ccs
results: parsing output: 37/ for test: gem_softpin
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: kms_feature_discovery
results: parsing output: 40/ for test: kms_cursor_crc
results: parsing output: 41/ for test: i915_pm_rpm
results: parsing output: 42/ for test: gem_mmap_gtt
results: parsing output: 43/ for test: gem_pwrite_snooped
results: parsing output: 44/ for test: kms_plane_scaling
results: parsing output: 45/ for test: kms_psr2_su
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: gem_exec_balancer
results: parsing output: 48/ for test: kms_setmode
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: kms_content_protection
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_plane
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_chamelium_audio
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: syncobj_wait
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_flip_scaled_crc
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: gem_userptr_blits
results: parsing output: 68/ for test: gem_workarounds
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_flip
results: parsing output: 72/ for test: gem_workarounds
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: prime_mmap
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: sw_sync
results: parsing output: 77/ for test: gem_exec_whisper
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_fence
results: parsing output: 80/ for test: kms_setmode
results: parsing output: 81/ for test: api_intel_allocator
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_psr2_sf
results: parsing output: 84/ for test: gem_pxp
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_plane
results: parsing output: 87/ for test: kms_force_connector_basic
results: parsing output: 88/ for test: gem_userptr_blits
results: parsing output: 89/ for test: kms_color
results: parsing output: 90/ for test: gem_ctx_persistence
results: parsing output: 91/ for test: gem_exec_reloc
results: parsing output: 92/ for test: gem_render_copy
results: parsing output: 93/ for test: i915_drm_fdinfo
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: gem_eio
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_cursor_crc
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_vrr
results: parsing output: 100/ for test: kms_chamelium_edid
results: parsing output: 101/ for test: kms_lease
results: parsing output: 102/ for test: kms_getfb
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_joiner
results: parsing output: 105/ for test: gem_exec_store
results: parsing output: 106/ for test: kms_flip_scaled_crc
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: syncobj_wait
results: parsing output: 110/ for test: kms_cursor_legacy
results: parsing output: 111/ for test: gem_ctx_engines
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: dmabuf_sync_file
results: parsing output: 114/ for test: kms_cursor_edge_walk
results: parsing output: 115/ for test: kms_psr2_sf
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: gem_workarounds
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: gem_ctx_param
results: parsing output: 122/ for test: gem_pread
results: parsing output: 123/ for test: gem_exec_schedule
results: parsing output: 124/ for test: prime_busy
results: parsing output: 125/ for test: kms_pm_rpm
results: parsing output: 126/ for test: kms_properties
results: parsing output: 127/ for test: gem_fence_thrash
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: kms_cursor_legacy
results: parsing output: 130/ for test: gem_exec_params
results: parsing output: 131/ for test: gem_close_race
results: parsing output: 132/ for test: kms_big_fb
Done.