[57.110967] Initializing watchdogs
[57.111026]   /dev/watchdog0
[57.124837] [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.129362] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.131930] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.135115] [001/133] (960s left) gem_exec_flush (basic-wb-ro-before-default)
[57.211820] Starting subtest: basic-wb-ro-before-default
[62.680212] Subtest basic-wb-ro-before-default: SUCCESS (5.468s)
[62.874215] [002/133] (954s left) kms_plane_multiple (tiling-4)
[62.979098] Starting subtest: tiling-4
[62.981525] Starting dynamic subtest: pipe-A-eDP-1
[66.699527] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.722s)
[66.708884] Starting dynamic subtest: pipe-B-eDP-1
[71.136688] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.435s)
[71.146075] Starting dynamic subtest: pipe-C-eDP-1
[75.549292] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.413s)
[75.558080] Starting dynamic subtest: pipe-D-eDP-1
[79.873071] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.323s)
[79.875460] Subtest tiling-4: SUCCESS (16.897s)
[80.704408] [003/133] (937s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[80.807959] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[87.874848] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.071s)
[89.072906] [004/133] (928s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[89.180599] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[93.703628] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.527s)
[94.869558] [005/133] (922s left) kms_vblank (wait-busy)
[94.965848] Starting subtest: wait-busy
[94.968176] Starting dynamic subtest: pipe-A-eDP-1
[96.845543] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[96.858518] Starting dynamic subtest: pipe-D-eDP-1
[99.422611] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.577s)
[99.425056] Subtest wait-busy: SUCCESS (4.458s)
[100.221831] [006/133] (917s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[100.320239] Starting subtest: primary-4-tiled-reflect-x-180
[107.309723] Subtest primary-4-tiled-reflect-x-180: SUCCESS (6.991s)
[108.470855] [007/133] (909s left) kms_rotation_crc (sprite-rotation-180)
[108.575328] Starting subtest: sprite-rotation-180
[115.358440] Subtest sprite-rotation-180: SUCCESS (6.786s)
[116.543988] [008/133] (901s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[116.634069] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[116.638381] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[116.723767] [009/133] (901s left) syncobj_timeline (host-signal-ordered)
[116.779733] Starting subtest: host-signal-ordered
[116.782078] Subtest host-signal-ordered: SUCCESS (0.001s)
[116.891251] [010/133] (901s left) gem_exec_reloc (basic-gtt-read-active)
[116.955950] Subtest basic-gtt-read-active: SKIP (0.000s)
[117.022207] [011/133] (901s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[117.115079] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[121.722964] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (4.610s)
[122.855200] [012/133] (895s left) i915_selftest (live)
[126.175088] Starting subtest: live
[126.177469] Starting dynamic subtest: sanitycheck
[128.909929] Dynamic subtest sanitycheck: SUCCESS (2.734s)
[128.918772] Starting dynamic subtest: uncore
[133.201617] Dynamic subtest uncore: SUCCESS (4.291s)
[133.210795] Starting dynamic subtest: workarounds
[152.862045] Dynamic subtest workarounds: FAIL (19.471s)
[152.869751] Starting dynamic subtest: gt_engines
[157.196233] Dynamic subtest gt_engines: SUCCESS (4.519s)
[157.204675] Starting dynamic subtest: gt_timelines
[171.546474] Dynamic subtest gt_timelines: SUCCESS (14.348s)
[171.555843] Starting dynamic subtest: gt_contexts
[174.297959] Dynamic subtest gt_contexts: SUCCESS (2.750s)
[174.305492] Starting dynamic subtest: gt_lrc
[178.637838] Dynamic subtest gt_lrc: SUCCESS (4.338s)
[178.647043] Starting dynamic subtest: gt_mocs
[181.577292] Dynamic subtest gt_mocs: SUCCESS (2.939s)
[181.589979] Starting dynamic subtest: gt_pm
[188.202193] Dynamic subtest gt_pm: SUCCESS (6.623s)
[188.210699] Starting dynamic subtest: gt_heartbeat
[192.483382] Dynamic subtest gt_heartbeat: SUCCESS (4.280s)
[192.491898] Starting dynamic subtest: gt_tlb
[202.294953] Dynamic subtest gt_tlb: SUCCESS (9.811s)
[202.303883] Starting dynamic subtest: requests
[215.709890] Dynamic subtest requests: SUCCESS (13.414s)
[215.718480] Starting dynamic subtest: migrate
[221.425904] Dynamic subtest migrate: SUCCESS (5.712s)
[221.435606] Starting dynamic subtest: active
[225.991710] Dynamic subtest active: SUCCESS (4.564s)
[226.001163] Starting dynamic subtest: objects
[230.568529] Dynamic subtest objects: SUCCESS (4.575s)
[230.578171] Starting dynamic subtest: mman
[235.285380] Dynamic subtest mman: SUCCESS (4.714s)
[235.294714] Starting dynamic subtest: dmabuf
[239.641036] Dynamic subtest dmabuf: SUCCESS (4.354s)
[239.650085] Starting dynamic subtest: vma
[243.968022] Dynamic subtest vma: SUCCESS (4.326s)
[243.976677] Starting dynamic subtest: coherency
[248.935819] Dynamic subtest coherency: SUCCESS (4.965s)
[248.944855] Starting dynamic subtest: gtt
[261.056900] Dynamic subtest gtt: SUCCESS (12.120s)
[261.065762] Starting dynamic subtest: gem
[268.557240] Dynamic subtest gem: SUCCESS (7.499s)
[268.565943] Starting dynamic subtest: evict
[273.921358] Dynamic subtest evict: SUCCESS (5.362s)
[273.930901] Starting dynamic subtest: hugepages
[285.126744] Dynamic subtest hugepages: SUCCESS (11.203s)
[285.133500] Starting dynamic subtest: gem_contexts
[312.990879] Dynamic subtest gem_contexts: SUCCESS (27.861s)
[312.999684] Starting dynamic subtest: client
[317.337972] Dynamic subtest client: SUCCESS (4.345s)
[317.346895] Starting dynamic subtest: gem_migrate
[321.646533] Dynamic subtest gem_migrate: SUCCESS (4.307s)
[321.656044] Starting dynamic subtest: reset
[326.063120] Dynamic subtest reset: SUCCESS (4.414s)
[326.071515] Starting dynamic subtest: memory_region
[330.320938] Dynamic subtest memory_region: SUCCESS (4.256s)
[330.329843] Starting dynamic subtest: hangcheck
[344.059055] Dynamic subtest hangcheck: SUCCESS (13.736s)
[344.072128] Starting dynamic subtest: execlists
[348.327081] Dynamic subtest execlists: SUCCESS (4.267s)
[348.335930] Starting dynamic subtest: ring_submission
[352.658353] Dynamic subtest ring_submission: SUCCESS (4.330s)
[352.667086] Starting dynamic subtest: perf
[356.992370] Dynamic subtest perf: SUCCESS (4.332s)
[357.002789] Starting dynamic subtest: slpc
[371.513628] Dynamic subtest slpc: SUCCESS (14.517s)
[371.522068] Starting dynamic subtest: guc
[376.819350] Dynamic subtest guc: SUCCESS (5.303s)
[376.828670] Starting dynamic subtest: guc_multi_lrc
[381.131586] Dynamic subtest guc_multi_lrc: SUCCESS (4.311s)
[381.141155] Starting dynamic subtest: guc_hang
[385.981335] Dynamic subtest guc_hang: SUCCESS (4.848s)
[385.991032] Starting dynamic subtest: late_gt_pm
[390.243611] Dynamic subtest late_gt_pm: SUCCESS (4.259s)
[390.246529] Subtest live: FAIL (264.057s)
[390.275690] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[390.278833] [013/133] (627s left) kms_chamelium_frames (dp-crc-multiple)
[392.056670] Subtest dp-crc-multiple: SKIP (0.000s)
[392.897179] [FACT kms_chamelium_frames (dp-crc-multiple)] new: kernel.kmod_is_loaded.i915: true
[392.901775] [014/133] (625s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[393.002021] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[393.006510] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[394.143187] [015/133] (624s left) gem_sync (basic-store-all)
[394.209313] Starting subtest: basic-store-all
[397.122530] Subtest basic-store-all: SUCCESS (2.923s)
[397.311910] [016/133] (620s left) gen9_exec_parse (allowed-single)
[397.376021] Subtest allowed-single: SKIP (0.000s)
[397.451822] [017/133] (620s left) gem_exec_reloc (basic-softpin)
[397.512923] Subtest basic-softpin: SKIP (0.000s)
[397.570783] [018/133] (620s left) gem_exec_schedule (manyslice)
[397.650598] Starting subtest: manyslice
[397.652620] Starting dynamic subtest: rcs0
[403.882865] Dynamic subtest rcs0: SUCCESS (6.237s)
[403.897951] Starting dynamic subtest: bcs0
[409.508971] Dynamic subtest bcs0: SUCCESS (5.623s)
[409.521022] Starting dynamic subtest: vcs0
[415.597899] Dynamic subtest vcs0: SUCCESS (6.088s)
[415.610324] Starting dynamic subtest: vcs1
[421.689596] Dynamic subtest vcs1: SUCCESS (6.090s)
[421.702349] Starting dynamic subtest: vecs0
[427.774907] Dynamic subtest vecs0: SUCCESS (6.084s)
[427.787967] Starting dynamic subtest: ccs0
[433.949482] Dynamic subtest ccs0: SUCCESS (6.171s)
[433.951846] Subtest manyslice: SUCCESS (36.302s)
[434.272665] [019/133] (584s left) kms_psr (fbc-psr-primary-mmap-gtt)
[434.373943] Starting subtest: fbc-psr-primary-mmap-gtt
[434.376639] Starting dynamic subtest: eDP-1
[434.381206] Dynamic subtest eDP-1: SKIP (0.000s)
[434.385544] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[434.455925] [020/133] (583s left) kms_big_fb (4-tiled-addfb)
[434.544398] Starting subtest: 4-tiled-addfb
[434.546674] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[434.656577] [021/133] (583s left) sw_sync (sync_multi_producer_single_consumer)
[434.703557] Starting subtest: sync_multi_producer_single_consumer
[435.778755] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[435.838423] [022/133] (582s left) fbdev (unaligned-write)
[435.880575] Starting subtest: unaligned-write
[435.980632] Subtest unaligned-write: SUCCESS (0.099s)
[436.039401] [023/133] (582s left) perf (invalid-oa-exponent)
[436.161790] Starting subtest: invalid-oa-exponent
[436.178554] Subtest invalid-oa-exponent: SUCCESS (0.016s)
[436.218392] [024/133] (582s left) gem_exec_whisper (basic-normal-all)
[436.275274] Starting subtest: basic-normal-all
[439.184852] Subtest basic-normal-all: SUCCESS (2.907s)
[439.366169] [025/133] (579s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[439.472341] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[439.476689] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[439.568538] [026/133] (579s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[439.663263] Starting subtest: modeset-transition-nonblocking-fencing
[439.665146] Starting dynamic subtest: 1x-outputs
[450.804325] Dynamic subtest 1x-outputs: SUCCESS (11.141s)
[450.806868] Subtest modeset-transition-nonblocking-fencing: SUCCESS (11.143s)
[451.668661] [027/133] (566s left) syncobj_timeline (transfer-timeline-point)
[451.736358] Starting subtest: transfer-timeline-point
[451.738633] Subtest transfer-timeline-point: SUCCESS (0.000s)
[451.850091] [028/133] (566s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[451.944356] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[474.531526] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (22.590s)
[474.653102] [029/133] (544s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[474.748523] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[474.750463] Starting dynamic subtest: pipe-A
[474.767824] Dynamic subtest pipe-A: SKIP (0.016s)
[474.776165] Starting dynamic subtest: pipe-B
[474.796909] Dynamic subtest pipe-B: SKIP (0.028s)
[474.805749] Starting dynamic subtest: pipe-C
[474.825503] Dynamic subtest pipe-C: SKIP (0.028s)
[474.833821] Starting dynamic subtest: pipe-D
[474.858236] Dynamic subtest pipe-D: SKIP (0.032s)
[474.861776] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.106s)
[474.946302] [030/133] (543s left) gem_ctx_shared (q-in-order)
[475.008314] Starting subtest: Q-in-order
[475.010338] Starting dynamic subtest: rcs0
[475.155434] Dynamic subtest rcs0: SUCCESS (0.145s)
[475.170487] Starting dynamic subtest: vcs0
[475.230984] Dynamic subtest vcs0: SUCCESS (0.076s)
[475.247667] Starting dynamic subtest: vcs1
[475.307568] Dynamic subtest vcs1: SUCCESS (0.075s)
[475.321957] Starting dynamic subtest: vecs0
[475.380616] Dynamic subtest vecs0: SUCCESS (0.071s)
[475.396628] Starting dynamic subtest: ccs0
[475.457721] Dynamic subtest ccs0: SUCCESS (0.076s)
[475.460301] Subtest Q-in-order: SUCCESS (0.449s)
[477.028317] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[477.031596] [031/133] (541s left) sysfs_timeslice_duration (invalid)
[477.108152] Starting subtest: invalid
[477.110495] Starting dynamic subtest: vecs0
[477.112785] Dynamic subtest vecs0: SUCCESS (0.000s)
[477.122071] Starting dynamic subtest: ccs0
[477.123909] Dynamic subtest ccs0: SUCCESS (0.000s)
[477.135218] Starting dynamic subtest: vcs0
[477.137063] Dynamic subtest vcs0: SUCCESS (0.000s)
[477.145477] Starting dynamic subtest: bcs0
[477.147595] Dynamic subtest bcs0: SUCCESS (0.000s)
[477.156063] Starting dynamic subtest: rcs0
[477.158226] Dynamic subtest rcs0: SUCCESS (0.000s)
[477.166134] Starting dynamic subtest: vcs1
[477.167958] Dynamic subtest vcs1: SUCCESS (0.000s)
[477.169798] Subtest invalid: SUCCESS (0.006s)
[477.206689] [032/133] (541s left) vgem_basic (create)
[477.258141] Starting subtest: create
[477.260475] Subtest create: SUCCESS (0.000s)
[477.314703] [033/133] (541s left) gem_lmem_swapping (basic)
[477.356769] Subtest basic: SKIP (0.000s)
[477.405893] [034/133] (541s left) gem_exec_reloc (basic-range-active)
[477.455837] Subtest basic-range-active: SKIP (0.000s)
[477.519241] [035/133] (541s left) kms_draw_crc (draw-method-mmap-wc)
[477.609986] Starting subtest: draw-method-mmap-wc
[477.612134] Starting dynamic subtest: xrgb8888-untiled
[477.819324] Dynamic subtest xrgb8888-untiled: SUCCESS (0.210s)
[477.828712] Starting dynamic subtest: rgb565-untiled
[478.051995] Dynamic subtest rgb565-untiled: SUCCESS (0.231s)
[478.060965] Starting dynamic subtest: xrgb2101010-untiled
[478.252076] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.199s)
[478.260378] Starting dynamic subtest: xbgr16161616f-untiled
[478.463078] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[478.471530] Starting dynamic subtest: xrgb8888-xtiled
[478.585465] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[478.593886] Starting dynamic subtest: rgb565-xtiled
[478.707743] Dynamic subtest rgb565-xtiled: SUCCESS (0.120s)
[478.716212] Starting dynamic subtest: xrgb2101010-xtiled
[478.829943] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[478.838123] Starting dynamic subtest: xbgr16161616f-xtiled
[478.941194] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[478.949712] Starting dynamic subtest: xrgb8888-4tiled
[479.085648] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[479.094330] Starting dynamic subtest: rgb565-4tiled
[479.219079] Dynamic subtest rgb565-4tiled: SUCCESS (0.133s)
[479.227616] Starting dynamic subtest: xrgb2101010-4tiled
[479.352260] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.132s)
[479.361257] Starting dynamic subtest: xbgr16161616f-4tiled
[479.530013] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[479.532082] Subtest draw-method-mmap-wc: SUCCESS (1.923s)
[479.623910] [036/133] (539s left) gem_userptr_blits (userfault)
[479.683460] Starting subtest: userfault
[479.687794] Subtest userfault: SUCCESS (0.009s)
[479.823333] [037/133] (539s left) gem_pxp (reject-modify-context-protection-off-2)
[479.960995] Starting subtest: reject-modify-context-protection-off-2
[479.963085] Subtest reject-modify-context-protection-off-2: SUCCESS (0.003s)
[480.063687] [038/133] (539s left) kms_plane_scaling (planes-upscale-factor-0-25)
[480.161582] Starting subtest: planes-upscale-factor-0-25
[480.163535] Starting dynamic subtest: pipe-A
[480.222074] Dynamic subtest pipe-A: SUCCESS (0.062s)
[480.232572] Starting dynamic subtest: pipe-B
[481.317606] Dynamic subtest pipe-B: SUCCESS (1.094s)
[481.329248] Starting dynamic subtest: pipe-C
[482.421363] Dynamic subtest pipe-C: SUCCESS (1.103s)
[482.431879] Starting dynamic subtest: pipe-D
[483.556908] Dynamic subtest pipe-D: SUCCESS (1.135s)
[483.559253] Subtest planes-upscale-factor-0-25: SUCCESS (3.398s)
[484.722344] [039/133] (534s left) kms_flip (2x-flip-vs-fences)
[484.813156] Starting subtest: 2x-flip-vs-fences
[484.817420] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[484.928430] [040/133] (534s left) gem_exec_capture (userptr)
[484.996886] Starting subtest: userptr
[485.835168] Subtest userptr: SUCCESS (0.838s)
[485.949578] [041/133] (533s left) sw_sync (sync_multi_consumer)
[485.990272] Starting subtest: sync_multi_consumer
[486.457032] Subtest sync_multi_consumer: SUCCESS (0.466s)
[486.520453] [042/133] (532s left) kms_pm_rpm (pm-tiling)
[488.049073] Starting subtest: pm-tiling
[488.054300] Subtest pm-tiling: SKIP (0.000s)
[488.775799] [043/133] (530s left) perf_pmu (multi-client)
[488.816454] Starting subtest: multi-client
[488.818455] Starting dynamic subtest: rcs0
[489.881947] Dynamic subtest rcs0: SUCCESS (1.064s)
[489.894047] Starting dynamic subtest: bcs0
[490.935857] Dynamic subtest bcs0: SUCCESS (1.050s)
[490.947858] Starting dynamic subtest: vcs0
[491.988662] Dynamic subtest vcs0: SUCCESS (1.050s)
[491.999333] Starting dynamic subtest: vcs1
[493.901632] Dynamic subtest vcs1: SUCCESS (1.909s)
[493.912307] Starting dynamic subtest: vecs0
[495.946064] Dynamic subtest vecs0: SUCCESS (2.042s)
[495.957115] Starting dynamic subtest: ccs0
[497.934290] Dynamic subtest ccs0: SUCCESS (1.985s)
[497.936881] Subtest multi-client: SUCCESS (9.119s)
[498.052313] [044/133] (521s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[498.160459] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[504.984318] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.827s)
[505.099368] [045/133] (514s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[505.194383] Starting subtest: psr-rgb565-draw-blt
[512.172782] Subtest psr-rgb565-draw-blt: SUCCESS (6.981s)
[512.295870] [046/133] (507s left) kms_cursor_crc (cursor-sliding-128x128)
[512.504826] Starting subtest: cursor-sliding-128x128
[512.509519] Starting dynamic subtest: pipe-A-eDP-1
[515.704904] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.197s)
[515.734046] Starting dynamic subtest: pipe-D-eDP-1
[519.600280] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.871s)
[519.602580] Subtest cursor-sliding-128x128: SUCCESS (7.092s)
[520.460708] [047/133] (499s left) gem_mmap_gtt (basic-small-bo)
[520.524966] Subtest basic-small-bo: SKIP (0.000s)
[520.585761] [048/133] (498s left) kms_flip (2x-flip-vs-suspend-interruptible)
[520.676105] Starting subtest: 2x-flip-vs-suspend-interruptible
[520.687754] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.010s)
[520.760409] [049/133] (498s left) i915_drm_fdinfo (memory-info-active)
[520.800494] Starting subtest: memory-info-active
[520.802744] Starting dynamic subtest: smem0
[523.079596] Dynamic subtest smem0: SUCCESS (2.281s)
[523.082370] Subtest memory-info-active: SUCCESS (2.282s)
[523.153539] [050/133] (496s left) kms_cursor_crc (cursor-onscreen-32x10)
[523.322417] Starting subtest: cursor-onscreen-32x10
[523.365088] Subtest cursor-onscreen-32x10: SKIP (0.041s)
[523.628050] [051/133] (495s left) api_intel_bb (blit-reloc-keep-cache)
[523.681235] Starting subtest: blit-reloc-keep-cache
[523.684789] Subtest blit-reloc-keep-cache: SKIP (0.000s)
[523.806625] [052/133] (495s left) gem_mmap_gtt (big-copy-xy)
[523.862427] Subtest big-copy-XY: SKIP (0.000s)
[523.928077] [053/133] (495s left) kms_lease (lease-uevent)
[524.012427] Starting subtest: lease-uevent
[525.021948] Subtest lease-uevent: SUCCESS (1.010s)
[525.094948] [054/133] (494s left) kms_pipe_stress (stress-xrgb8888-untiled)
[525.190346] Starting subtest: stress-xrgb8888-untiled
[531.051595] Subtest stress-xrgb8888-untiled: SUCCESS (5.863s)
[531.102021] [055/133] (488s left) gem_exec_balancer (nop)
[531.174340] Starting subtest: nop
[577.880932] Subtest nop: SUCCESS (46.704s)
[578.176428] [056/133] (441s left) kms_psr (fbc-pr-cursor-render)
[578.270976] Starting subtest: fbc-pr-cursor-render
[578.275423] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[578.368504] [057/133] (441s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[578.462966] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[585.288577] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.828s)
[585.424631] [058/133] (434s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[585.493616] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[585.498216] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[585.680461] [059/133] (434s left) kms_cursor_crc (cursor-sliding-32x32)
[585.827760] Starting subtest: cursor-sliding-32x32
[585.881990] Subtest cursor-sliding-32x32: SKIP (0.050s)
[586.188834] [060/133] (433s left) kms_psr (psr2-sprite-mmap-gtt)
[586.287391] Starting subtest: psr2-sprite-mmap-gtt
[586.289718] Starting dynamic subtest: eDP-1
[587.759951] Dynamic subtest eDP-1: SKIP (1.470s)
[587.764520] Subtest psr2-sprite-mmap-gtt: SKIP (1.472s)
[587.949142] [061/133] (432s left) gem_ctx_isolation (clean)
[588.006595] Starting subtest: clean
[588.008850] Starting dynamic subtest: rcs0
[588.057310] Dynamic subtest rcs0: SUCCESS (0.050s)
[588.068538] Starting dynamic subtest: bcs0
[588.117764] Dynamic subtest bcs0: SUCCESS (0.059s)
[588.128600] Starting dynamic subtest: vcs0
[588.174996] Dynamic subtest vcs0: SUCCESS (0.056s)
[588.185679] Starting dynamic subtest: vcs1
[589.924096] Dynamic subtest vcs1: SUCCESS (1.748s)
[589.935110] Starting dynamic subtest: vecs0
[591.913089] Dynamic subtest vecs0: SUCCESS (1.988s)
[591.924140] Starting dynamic subtest: ccs0
[593.975500] Dynamic subtest ccs0: SUCCESS (2.061s)
[593.978106] Subtest clean: SUCCESS (5.969s)
[594.201764] [062/133] (425s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[594.314974] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[594.317139] Starting dynamic subtest: pipe-A-eDP-1
[595.123860] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.802s)
[595.133614] Starting dynamic subtest: pipe-B-eDP-1
[596.768622] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.640s)
[596.778914] Starting dynamic subtest: pipe-C-eDP-1
[598.322316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.554s)
[598.331894] Starting dynamic subtest: pipe-D-eDP-1
[599.856677] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.531s)
[599.858626] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.535s)
[601.029153] [063/133] (419s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[601.135454] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[601.140263] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[602.251082] [064/133] (417s left) sriov_basic (enable-vfs-autoprobe-off)
[602.296012] Starting subtest: enable-vfs-autoprobe-off
[602.298715] Starting dynamic subtest: numvfs-1
[602.372325] Dynamic subtest numvfs-1: FAIL (0.005s)
[602.384718] Starting dynamic subtest: numvfs-2
[602.455223] Dynamic subtest numvfs-2: FAIL (0.004s)
[602.464181] Starting dynamic subtest: numvfs-3
[602.532888] Dynamic subtest numvfs-3: FAIL (0.003s)
[602.541662] Starting dynamic subtest: numvfs-4
[602.609882] Dynamic subtest numvfs-4: FAIL (0.004s)
[602.620114] Starting dynamic subtest: numvfs-5
[602.689519] Dynamic subtest numvfs-5: FAIL (0.004s)
[602.698036] Starting dynamic subtest: numvfs-6
[602.766934] Dynamic subtest numvfs-6: FAIL (0.004s)
[602.776109] Starting dynamic subtest: numvfs-7
[602.845461] Dynamic subtest numvfs-7: FAIL (0.003s)
[602.854647] Starting dynamic subtest: numvfs-random
[602.922801] Dynamic subtest numvfs-random: FAIL (0.003s)
[602.933191] Starting dynamic subtest: numvfs-all
[603.001866] Dynamic subtest numvfs-all: FAIL (0.487s)
[603.004050] Subtest enable-vfs-autoprobe-off: FAIL (0.524s)
[603.041532] [065/133] (417s left) syncobj_wait (invalid-wait-illegal-handle)
[603.106063] Starting subtest: invalid-wait-illegal-handle
[603.108319] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[603.208649] [066/133] (417s left) gem_exec_schedule (fairslice)
[603.268375] Starting subtest: fairslice
[603.270188] Starting dynamic subtest: rcs0
[605.285976] Dynamic subtest rcs0: SUCCESS (2.019s)
[605.295898] Starting dynamic subtest: bcs0
[607.309526] Dynamic subtest bcs0: SUCCESS (2.024s)
[607.319062] Starting dynamic subtest: vcs0
[609.331327] Dynamic subtest vcs0: SUCCESS (2.021s)
[609.340983] Starting dynamic subtest: vcs1
[611.351811] Dynamic subtest vcs1: SUCCESS (2.020s)
[611.362469] Starting dynamic subtest: vecs0
[613.371247] Dynamic subtest vecs0: SUCCESS (2.017s)
[613.380123] Starting dynamic subtest: ccs0
[615.395374] Dynamic subtest ccs0: SUCCESS (2.025s)
[615.397255] Subtest fairslice: SUCCESS (12.130s)
[615.695259] [067/133] (404s left) kms_pm_rpm (basic-pci-d3-state)
[617.228048] Starting subtest: basic-pci-d3-state
[618.720371] Subtest basic-pci-d3-state: SUCCESS (1.492s)
[618.827294] [068/133] (401s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[618.942915] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[618.946967] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[619.033986] [069/133] (401s left) gem_mmap_gtt (basic-write-read)
[619.082429] Subtest basic-write-read: SKIP (0.000s)
[619.150487] [070/133] (401s left) syncobj_wait (wait-all-snapshot)
[619.207684] Starting subtest: wait-all-snapshot
[619.288986] Subtest wait-all-snapshot: SUCCESS (0.082s)
[619.386253] [071/133] (401s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[619.491565] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[619.495666] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[619.588062] [072/133] (400s left) perf_pmu (busy-double-start)
[619.630346] Starting subtest: busy-double-start
[619.632711] Starting dynamic subtest: rcs0
[623.269758] Dynamic subtest rcs0: FAIL (3.571s)
[623.280845] Starting dynamic subtest: bcs0
[626.779058] Dynamic subtest bcs0: SUCCESS (3.579s)
[626.791220] Starting dynamic subtest: vcs0
[630.416780] Dynamic subtest vcs0: SUCCESS (3.636s)
[630.427211] Starting dynamic subtest: vcs1
[634.408637] Dynamic subtest vcs1: SUCCESS (3.990s)
[634.419925] Starting dynamic subtest: vecs0
[638.428845] Dynamic subtest vecs0: SUCCESS (4.018s)
[638.440785] Starting dynamic subtest: ccs0
[642.063686] Dynamic subtest ccs0: FAIL (3.565s)
[642.065655] Subtest busy-double-start: FAIL (22.369s)
[642.107679] [073/133] (378s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[642.243273] Starting subtest: fbcpsr-modesetfrombusy
[648.468046] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.227s)
[648.610151] [074/133] (372s left) gem_lmem_swapping (random-engines)
[648.652902] Subtest random-engines: SKIP (0.000s)
[648.689512] [075/133] (371s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[648.772092] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[648.775500] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[648.855836] [076/133] (371s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[648.958438] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[648.961975] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[649.047486] [077/133] (371s left) kms_invalid_mode (bad-vsync-start)
[649.150956] Starting subtest: bad-vsync-start
[649.152941] Starting dynamic subtest: pipe-A-eDP-1
[649.438409] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[649.450516] Starting dynamic subtest: pipe-B-eDP-1
[649.452774] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[649.461823] Starting dynamic subtest: pipe-C-eDP-1
[649.463829] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[649.473193] Starting dynamic subtest: pipe-D-eDP-1
[649.475084] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[649.476937] Subtest bad-vsync-start: SUCCESS (0.294s)
[650.289130] [078/133] (370s left) gem_eio (execbuf)
[650.524878] Starting subtest: execbuf
[650.839560] Subtest execbuf: SUCCESS (0.314s)
[650.953696] [079/133] (369s left) gem_exec_params (rsvd2-dirt)
[651.009426] Starting subtest: rsvd2-dirt
[651.013958] Subtest rsvd2-dirt: SKIP (0.000s)
[651.100862] [080/133] (369s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[651.190619] Starting subtest: bad-pixel-format-yf-tiled-ccs
[651.192493] Starting dynamic subtest: pipe-A-eDP-1
[651.211658] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[651.222810] Starting dynamic subtest: pipe-B-eDP-1
[651.240773] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[651.249345] Starting dynamic subtest: pipe-C-eDP-1
[651.267135] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[651.275687] Starting dynamic subtest: pipe-D-eDP-1
[651.293723] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[651.297221] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.004s)
[653.356178] [081/133] (367s left) gem_exec_create (forked)
[653.421110] Starting subtest: forked
[653.423488] Starting dynamic subtest: smem
[674.902330] Dynamic subtest smem: SUCCESS (21.480s)
[674.905134] Subtest forked: SUCCESS (21.483s)
[675.089881] [082/133] (345s left) gem_ctx_engines (execute-allforone)
[675.159353] Starting subtest: execute-allforone
[675.183766] Subtest execute-allforone: SUCCESS (0.025s)
[676.965059] [083/133] (344s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier)
[677.065947] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier
[677.070115] Subtest pixel-format-4-tiled-lnl-ccs-modifier: SKIP (0.000s)
[677.151041] [084/133] (343s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[677.249265] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[677.253755] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[677.317942] [085/133] (343s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[677.438624] Starting subtest: short-flip-after-cursor-atomic-transitions
[678.396320] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.954s)
[679.193499] [086/133] (341s left) kms_big_fb (linear-64bpp-rotate-270)
[679.297414] Starting subtest: linear-64bpp-rotate-270
[679.316870] Subtest linear-64bpp-rotate-270: SKIP (0.019s)
[679.441581] [087/133] (341s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[679.532839] Starting subtest: psr-shrfb-scaledprimary
[685.299821] Subtest psr-shrfb-scaledprimary: SUCCESS (5.770s)
[685.426946] [088/133] (335s left) gem_exec_schedule (u-lateslice)
[685.495310] Starting subtest: u-lateslice
[685.497470] Starting dynamic subtest: rcs0
[685.505103] Dynamic subtest rcs0: SUCCESS (0.020s)
[685.515390] Starting dynamic subtest: bcs0
[685.521194] Dynamic subtest bcs0: SUCCESS (0.015s)
[685.530341] Starting dynamic subtest: vcs0
[685.538395] Dynamic subtest vcs0: SUCCESS (0.017s)
[685.547243] Starting dynamic subtest: vcs1
[685.557467] Dynamic subtest vcs1: SUCCESS (0.019s)
[685.566708] Starting dynamic subtest: vecs0
[685.574666] Dynamic subtest vecs0: SUCCESS (0.016s)
[685.583776] Starting dynamic subtest: ccs0
[685.592208] Dynamic subtest ccs0: SUCCESS (0.017s)
[685.594424] Subtest u-lateslice: SUCCESS (0.108s)
[687.016737] [089/133] (334s left) gem_mmap_wc (read)
[687.068768] Subtest read: SKIP (0.000s)
[687.131518] [090/133] (334s left) gem_spin_batch (engines)
[687.189392] Starting subtest: engines
[687.191849] Starting dynamic subtest: rcs0
[690.429986] Dynamic subtest rcs0: SUCCESS (3.235s)
[690.442461] Starting dynamic subtest: bcs0
[693.667991] Dynamic subtest bcs0: SUCCESS (3.234s)
[693.680063] Starting dynamic subtest: vcs0
[696.900089] Dynamic subtest vcs0: SUCCESS (3.229s)
[696.912456] Starting dynamic subtest: vcs1
[700.137999] Dynamic subtest vcs1: SUCCESS (3.235s)
[700.148328] Starting dynamic subtest: vecs0
[703.376342] Dynamic subtest vecs0: SUCCESS (3.235s)
[703.388024] Starting dynamic subtest: ccs0
[706.616242] Dynamic subtest ccs0: SUCCESS (3.237s)
[706.618507] Subtest engines: SUCCESS (19.424s)
[706.854416] [091/133] (314s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[706.909569] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[707.016963] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.107s)
[707.110873] [092/133] (314s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[707.218522] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[707.222747] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[707.332888] [093/133] (314s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[707.428752] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[714.293057] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.866s)
[714.419726] [094/133] (307s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[714.519682] Starting subtest: y-tiled-16bpp-rotate-0
[714.524084] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[714.618928] [095/133] (306s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[714.728064] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[714.732469] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[714.821914] [096/133] (306s left) kms_content_protection (legacy)
[714.961064] Starting subtest: legacy
[714.971066] Subtest legacy: SKIP (0.000s)
[715.088633] [097/133] (306s left) syncobj_timeline (multi-wait-signaled)
[715.149112] Starting subtest: multi-wait-signaled
[715.151289] Subtest multi-wait-signaled: SUCCESS (0.000s)
[715.258268] [098/133] (306s left) gem_userptr_blits (coherency-unsync)
[715.318421] Subtest coherency-unsync: SKIP (0.000s)
[715.438037] [099/133] (306s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[715.558595] Starting subtest: flip-vs-cursor-atomic
[722.978970] Subtest flip-vs-cursor-atomic: SUCCESS (7.419s)
[723.790079] [100/133] (297s left) gem_mmap_offset (basic-uaf)
[723.847056] Starting subtest: basic-uaf
[723.849249] Subtest basic-uaf: SUCCESS (0.001s)
[723.958431] [101/133] (297s left) gem_exec_capture (capture-recoverable)
[724.025085] Starting subtest: capture-recoverable
[724.027776] Subtest capture-recoverable: SUCCESS (0.003s)
[724.144228] [102/133] (297s left) gem_ctx_persistence (saturated-hostile)
[724.201177] Starting subtest: saturated-hostile
[724.203578] Starting dynamic subtest: rcs0
[725.024865] Dynamic subtest rcs0: SUCCESS (0.823s)
[725.037444] Starting dynamic subtest: bcs0
[725.833485] Dynamic subtest bcs0: SUCCESS (0.807s)
[725.845482] Starting dynamic subtest: vcs0
[726.631259] Dynamic subtest vcs0: SUCCESS (0.796s)
[726.643195] Starting dynamic subtest: vcs1
[727.427884] Dynamic subtest vcs1: SUCCESS (0.795s)
[727.440501] Starting dynamic subtest: vecs0
[728.246613] Dynamic subtest vecs0: SUCCESS (0.816s)
[728.258914] Starting dynamic subtest: ccs0
[729.084535] Dynamic subtest ccs0: SUCCESS (0.836s)
[729.087059] Subtest saturated-hostile: SUCCESS (4.884s)
[729.164797] [103/133] (292s left) kms_vblank (query-busy)
[729.259096] Starting subtest: query-busy
[729.261315] Starting dynamic subtest: pipe-A-eDP-1
[731.132923] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.874s)
[731.149594] Starting dynamic subtest: pipe-D-eDP-1
[733.667706] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.529s)
[733.669695] Subtest query-busy: SUCCESS (4.407s)
[734.487138] [104/133] (287s left) gem_pxp (create-protected-buffer)
[734.614761] Starting subtest: create-protected-buffer
[734.617161] Subtest create-protected-buffer: SUCCESS (0.000s)
[734.721928] [105/133] (287s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[734.823613] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[735.925051] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.102s)
[736.046873] [106/133] (285s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[736.142125] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[736.145666] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[736.223216] [107/133] (285s left) perf (i915-ref-count)
[736.302895] Starting subtest: i915-ref-count
[736.331131] Subtest i915-ref-count: SUCCESS (0.026s)
[736.389512] [108/133] (285s left) gem_exec_reloc (basic-wc-cpu-active)
[736.444283] Subtest basic-wc-cpu-active: SKIP (0.000s)
[736.516438] [109/133] (285s left) kms_async_flips (async-flip-suspend-resume)
[736.607380] Starting subtest: async-flip-suspend-resume
[736.978102] Starting dynamic subtest: pipe-A-eDP-1
[754.794299] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.013s)
[755.172535] Starting dynamic subtest: pipe-B-eDP-1
[772.802155] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.919s)
[773.181104] Starting dynamic subtest: pipe-C-eDP-1
[790.819863] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.897s)
[791.192405] Starting dynamic subtest: pipe-D-eDP-1
[808.821447] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.915s)
[808.823815] Subtest async-flip-suspend-resume: SUCCESS (13.230s)
[810.007274] [110/133] (212s left) kms_chamelium_color (ctm-0-50)
[810.113655] Subtest ctm-0-50: SKIP (0.000s)
[810.164276] [111/133] (211s left) syncobj_timeline (multi-wait-all-available-signaled)
[810.220519] Starting subtest: multi-wait-all-available-signaled
[810.223214] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[810.320807] [112/133] (211s left) kms_properties (get_properties-sanity-atomic)
[810.407456] Starting subtest: get_properties-sanity-atomic
[810.409892] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[810.508172] [113/133] (211s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[810.599438] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[810.604243] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[810.696671] [114/133] (211s left) syncobj_basic (bad-handle-to-fd)
[810.746805] Starting subtest: bad-handle-to-fd
[810.749395] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[810.849968] [115/133] (211s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[810.944411] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[810.948211] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[811.059282] [116/133] (211s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[811.439737] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[812.219273] [117/133] (210s left) kms_color (ctm-negative)
[812.316639] Starting subtest: ctm-negative
[812.319009] Starting dynamic subtest: pipe-A-eDP-1
[813.053808] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.736s)
[813.064423] Starting dynamic subtest: pipe-B-eDP-1
[814.454269] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.398s)
[814.464870] Starting dynamic subtest: pipe-C-eDP-1
[815.888878] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.432s)
[815.901501] Starting dynamic subtest: pipe-D-eDP-1
[817.235463] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.346s)
[817.237912] Subtest ctm-negative: SUCCESS (4.917s)
[818.206831] [118/133] (204s left) gem_exec_alignment (single)
[818.274932] Starting subtest: single
[818.277773] Subtest single: SUCCESS (0.002s)
[818.359940] [119/133] (204s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[818.456544] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[818.460889] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[818.552857] [120/133] (203s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[818.652457] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[818.656737] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[818.743758] [121/133] (203s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[818.828712] Starting subtest: 2x-flip-vs-rmfb-interruptible
[818.839543] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.010s)
[818.918497] [122/133] (203s left) syncobj_wait (invalid-wait-bad-flags)
[818.970070] Starting subtest: invalid-wait-bad-flags
[818.972502] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[819.073172] [123/133] (203s left) kms_psr (fbc-psr2-cursor-blt)
[819.166746] Starting subtest: fbc-psr2-cursor-blt
[819.169116] Starting dynamic subtest: eDP-1
[819.173186] Dynamic subtest eDP-1: SKIP (0.000s)
[819.177179] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[819.240974] [124/133] (203s left) api_intel_bb (object-reloc-keep-cache)
[819.294538] Starting subtest: object-reloc-keep-cache
[819.298467] Subtest object-reloc-keep-cache: SKIP (0.004s)
[819.415803] [125/133] (203s left) dumb_buffer (invalid-bpp)
[819.470921] Starting subtest: invalid-bpp
[819.473019] Subtest invalid-bpp: SUCCESS (0.000s)
[819.551402] [126/133] (203s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[819.645366] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[824.491414] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (4.846s)
[825.672484] [127/133] (197s left) i915_pm_rpm (system-suspend-execbuf)
[828.016714] Starting subtest: system-suspend-execbuf
[860.431097] Subtest system-suspend-execbuf: FAIL (17.613s)
[860.686690] [128/133] (162s left) gem_exec_fence (syncobj-invalid-wait)
[861.161024] Starting subtest: syncobj-invalid-wait
[861.163349] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[862.658991] [129/133] (160s left) kms_dp_link_training (non-uhbr-sst)
[862.754333] Starting subtest: non-uhbr-sst
[862.762841] Subtest non-uhbr-sst: SKIP (0.000s)
[862.828416] [130/133] (160s left) i915_module_load (reload-no-display)
[862.872619] Starting subtest: reload-no-display
[867.169451] Subtest reload-no-display: SUCCESS (4.298s)
[867.199029] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[867.199780] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[867.203018] [131/133] (155s left) kms_psr (pr-cursor-mmap-cpu)
[868.773333] Starting subtest: pr-cursor-mmap-cpu
[868.777921] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[868.878977] [FACT kms_psr (pr-cursor-mmap-cpu)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[868.880156] [FACT kms_psr (pr-cursor-mmap-cpu)] new: kernel.kmod_is_loaded.i915: true
[868.884041] [132/133] (154s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[869.006818] Starting subtest: cursorB-vs-flipB-varying-size
[869.011252] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[869.151008] [133/133] (153s left) syncobj_wait (invalid-signal-zero-handles)
[869.203488] Starting subtest: invalid-signal-zero-handles
[869.205787] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[869.293609] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_flush
results: parsing output: 1/ for test: kms_plane_multiple
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_vblank
results: parsing output: 5/ for test: kms_rotation_crc
results: parsing output: 6/ for test: kms_rotation_crc
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: i915_selftest
results: parsing output: 12/ for test: kms_chamelium_frames
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: gem_sync
results: parsing output: 15/ for test: gen9_exec_parse
results: parsing output: 16/ for test: gem_exec_reloc
results: parsing output: 17/ for test: gem_exec_schedule
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: sw_sync
results: parsing output: 21/ for test: fbdev
results: parsing output: 22/ for test: perf
results: parsing output: 23/ for test: gem_exec_whisper
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_atomic_transition
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_plane_scaling
results: parsing output: 29/ for test: gem_ctx_shared
results: parsing output: 30/ for test: sysfs_timeslice_duration
results: parsing output: 31/ for test: vgem_basic
results: parsing output: 32/ for test: gem_lmem_swapping
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: kms_draw_crc
results: parsing output: 35/ for test: gem_userptr_blits
results: parsing output: 36/ for test: gem_pxp
results: parsing output: 37/ for test: kms_plane_scaling
results: parsing output: 38/ for test: kms_flip
results: parsing output: 39/ for test: gem_exec_capture
results: parsing output: 40/ for test: sw_sync
results: parsing output: 41/ for test: kms_pm_rpm
results: parsing output: 42/ for test: perf_pmu
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_cursor_crc
results: parsing output: 46/ for test: gem_mmap_gtt
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: i915_drm_fdinfo
results: parsing output: 49/ for test: kms_cursor_crc
results: parsing output: 50/ for test: api_intel_bb
results: parsing output: 51/ for test: gem_mmap_gtt
results: parsing output: 52/ for test: kms_lease
results: parsing output: 53/ for test: kms_pipe_stress
results: parsing output: 54/ for test: gem_exec_balancer
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_render_copy
results: parsing output: 58/ for test: kms_cursor_crc
results: parsing output: 59/ for test: kms_psr
results: parsing output: 60/ for test: gem_ctx_isolation
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: sriov_basic
results: parsing output: 64/ for test: syncobj_wait
results: parsing output: 65/ for test: gem_exec_schedule
results: parsing output: 66/ for test: kms_pm_rpm
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: syncobj_wait
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_lmem_swapping
results: parsing output: 74/ for test: kms_flip_scaled_crc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_invalid_mode
results: parsing output: 77/ for test: gem_eio
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: gem_exec_create
results: parsing output: 81/ for test: gem_ctx_engines
results: parsing output: 82/ for test: kms_plane
results: parsing output: 83/ for test: kms_psr2_sf
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_exec_schedule
results: parsing output: 88/ for test: gem_mmap_wc
results: parsing output: 89/ for test: gem_spin_batch
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_content_protection
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: gem_userptr_blits
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: gem_mmap_offset
results: parsing output: 100/ for test: gem_exec_capture
results: parsing output: 101/ for test: gem_ctx_persistence
results: parsing output: 102/ for test: kms_vblank
results: parsing output: 103/ for test: gem_pxp
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_psr2_sf
results: parsing output: 106/ for test: perf
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: kms_async_flips
results: parsing output: 109/ for test: kms_chamelium_color
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_properties
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: syncobj_basic
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_chamelium_hpd
results: parsing output: 116/ for test: kms_color
results: parsing output: 117/ for test: gem_exec_alignment
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: syncobj_wait
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: api_intel_bb
results: parsing output: 124/ for test: dumb_buffer
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: i915_pm_rpm
results: parsing output: 127/ for test: gem_exec_fence
results: parsing output: 128/ for test: kms_dp_link_training
results: parsing output: 129/ for test: i915_module_load
results: parsing output: 130/ for test: kms_psr
results: parsing output: 131/ for test: kms_cursor_legacy
results: parsing output: 132/ for test: syncobj_wait
Done.