[56.448530] Initializing watchdogs
[56.448574]   /dev/watchdog0
[56.462518] [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]
[56.466884] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.469124] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.472245] [001/155] (960s left) gem_tiled_pread_basic (basic)
[56.559108] Subtest basic: SKIP (0.000s)
[56.614908] [002/155] (960s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-fullscreen)
[56.714409] Starting subtest: hdr-1p-primscrn-spr-indfb-fullscreen
[56.718781] Subtest hdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[56.827783] [003/155] (960s left) i915_query (query-topology-coherent-slice-mask)
[56.884830] Starting subtest: query-topology-coherent-slice-mask
[56.889348] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[56.982294] [004/155] (960s left) gem_exec_await (wide-contexts)
[57.179244] Starting subtest: wide-contexts
[80.016316] Subtest wide-contexts: SUCCESS (22.982s)
[80.198942] [FACT gem_exec_await (wide-contexts)] new: kernel.kmod_is_loaded.vgem: true
[80.203545] [005/155] (936s left) perf_pmu (idle)
[80.282538] Starting subtest: idle
[80.284805] Starting dynamic subtest: rcs0
[80.856438] Dynamic subtest rcs0: SUCCESS (0.569s)
[80.866623] Starting dynamic subtest: bcs0
[81.375542] Dynamic subtest bcs0: SUCCESS (0.520s)
[81.384871] Starting dynamic subtest: vcs0
[81.895812] Dynamic subtest vcs0: SUCCESS (0.520s)
[81.907384] Starting dynamic subtest: vcs1
[82.420191] Dynamic subtest vcs1: SUCCESS (0.524s)
[82.431274] Starting dynamic subtest: vecs0
[82.944078] Dynamic subtest vecs0: SUCCESS (0.522s)
[82.954929] Starting dynamic subtest: ccs0
[83.458618] Dynamic subtest ccs0: SUCCESS (0.515s)
[83.461509] Subtest idle: SUCCESS (3.177s)
[83.568080] [006/155] (933s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[83.677571] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[83.681921] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[83.783531] [007/155] (933s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[83.842173] Starting subtest: etime-multi-wait-all-for-submit-submitted
[83.943846] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[84.017045] [008/155] (933s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[84.112042] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[84.116690] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[84.184645] [009/155] (933s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-pgflip-blt)
[84.285691] Starting subtest: fbchdr-1p-primscrn-indfb-pgflip-blt
[84.290227] Subtest fbchdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[84.365704] [010/155] (932s left) gem_softpin (evict-snoop-interruptible)
[84.423332] Starting subtest: evict-snoop-interruptible
[84.428205] Subtest evict-snoop-interruptible: SKIP (0.000s)
[84.540069] [011/155] (932s left) gem_ctx_engines (execute-one)
[84.602605] Starting subtest: execute-one
[86.930714] Subtest execute-one: SUCCESS (2.327s)
[87.345474] [012/155] (930s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[87.446955] Starting subtest: primary-x-tiled-reflect-x-180
[94.342157] Subtest primary-x-tiled-reflect-x-180: SUCCESS (6.896s)
[95.558707] [013/155] (921s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[95.654084] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[95.656565] Starting dynamic subtest: pipe-A-eDP-1
[96.100366] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.417s)
[96.111619] Starting dynamic subtest: pipe-B-eDP-1
[97.494588] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.392s)
[97.505883] Starting dynamic subtest: pipe-C-eDP-1
[98.911053] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.417s)
[98.921105] Starting dynamic subtest: pipe-D-eDP-1
[100.310761] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.399s)
[100.312816] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.628s)
[101.492674] [014/155] (915s left) kms_flip (plain-flip-fb-recreate-interruptible)
[101.587702] Starting subtest: plain-flip-fb-recreate-interruptible
[101.590033] Starting dynamic subtest: A-eDP1
[110.486075] Dynamic subtest A-eDP1: SUCCESS (8.897s)
[110.497127] Starting dynamic subtest: B-eDP1
[119.320306] Dynamic subtest B-eDP1: SUCCESS (8.833s)
[119.332352] Starting dynamic subtest: C-eDP1
[128.141780] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[128.150625] Starting dynamic subtest: D-eDP1
[136.981531] Dynamic subtest D-eDP1: SUCCESS (8.837s)
[136.983476] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.395s)
[138.167264] [015/155] (879s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[138.258219] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[138.262700] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[138.329595] [016/155] (879s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[138.428648] Starting subtest: psr-modesetfrombusy
[144.632375] Subtest psr-modesetfrombusy: SUCCESS (6.210s)
[144.771385] [017/155] (872s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[144.866658] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[144.870267] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[144.977012] [018/155] (872s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[145.077604] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[145.088691] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[145.166187] [019/155] (872s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[145.269931] Starting subtest: psr-1p-pri-indfb-multidraw
[167.949053] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.683s)
[168.096914] [020/155] (849s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[168.197301] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[168.200717] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[168.264476] [021/155] (849s left) gem_exec_flush (basic-wb-pro-default)
[168.323171] Starting subtest: basic-wb-pro-default
[173.782229] Subtest basic-wb-pro-default: SUCCESS (5.458s)
[173.967195] [022/155] (843s left) gem_exec_schedule (independent)
[174.050488] Starting subtest: independent
[174.052564] Starting dynamic subtest: rcs0
[175.290953] Dynamic subtest rcs0: SUCCESS (1.249s)
[175.301577] Starting dynamic subtest: vcs0
[177.281471] Dynamic subtest vcs0: SUCCESS (1.988s)
[177.296069] Starting dynamic subtest: vcs1
[179.339066] Dynamic subtest vcs1: SUCCESS (2.057s)
[179.351179] Starting dynamic subtest: vecs0
[181.319374] Dynamic subtest vecs0: SUCCESS (1.978s)
[181.332092] Starting dynamic subtest: ccs0
[183.292606] Dynamic subtest ccs0: SUCCESS (1.970s)
[183.295638] Subtest independent: SUCCESS (9.253s)
[183.597419] [023/155] (834s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[184.002290] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[184.860205] [024/155] (833s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[184.971307] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[184.975441] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[185.074112] [025/155] (832s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[185.127778] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[185.198150] [026/155] (832s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[185.301931] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[185.306126] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[185.401496] [027/155] (832s left) kms_plane_lowres (tiling-4)
[185.493006] Starting subtest: tiling-4
[185.495257] Starting dynamic subtest: pipe-A-eDP-1
[185.499584] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[185.789625] Starting dynamic subtest: pipe-B-eDP-1
[185.794086] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[185.803300] Starting dynamic subtest: pipe-C-eDP-1
[185.807756] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[185.816882] Starting dynamic subtest: pipe-D-eDP-1
[185.821196] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[185.825569] Subtest tiling-4: SKIP (0.294s)
[186.611685] [028/155] (831s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[186.724205] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[186.728460] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[186.823643] [029/155] (831s left) kms_getfb (getfb-handle-protection)
[186.911581] Starting subtest: getfb-handle-protection
[186.935047] Subtest getfb-handle-protection: SUCCESS (0.024s)
[187.067644] [030/155] (831s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[187.163072] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[187.165368] Starting dynamic subtest: pipe-A-eDP-1
[193.089126] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.931s)
[193.099752] Starting dynamic subtest: pipe-B-eDP-1
[199.735478] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.644s)
[199.737953] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.578s)
[200.611112] [031/155] (817s left) kms_pm_rpm (legacy-planes-dpms)
[202.439297] Starting subtest: legacy-planes-dpms
[202.442400] Starting dynamic subtest: plane-65
[211.013236] Dynamic subtest plane-65: SUCCESS (8.573s)
[211.025228] Starting dynamic subtest: plane-95
[219.430797] Dynamic subtest plane-95: SUCCESS (8.414s)
[219.443634] Starting dynamic subtest: plane-125
[228.071445] Dynamic subtest plane-125: SUCCESS (8.637s)
[228.084028] Starting dynamic subtest: plane-135
[236.400340] Dynamic subtest plane-135: SUCCESS (8.326s)
[236.402753] Subtest legacy-planes-dpms: SUCCESS (33.961s)
[236.807820] [032/155] (781s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[236.910023] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[236.914072] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[237.015963] [033/155] (781s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[237.107170] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[237.109468] Starting dynamic subtest: pipe-A-eDP-1
[237.118251] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[237.128562] Starting dynamic subtest: pipe-B-eDP-1
[237.137315] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[237.147096] Starting dynamic subtest: pipe-C-eDP-1
[237.155388] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[237.165313] Starting dynamic subtest: pipe-D-eDP-1
[237.173749] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[237.177973] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[239.440556] [034/155] (778s left) gem_pxp (reject-modify-context-protection-off-2)
[239.566556] Starting subtest: reject-modify-context-protection-off-2
[239.569088] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[239.696366] [035/155] (778s left) kms_plane_multiple (2x-tiling-x)
[239.791277] Starting subtest: 2x-tiling-x
[239.795221] Subtest 2x-tiling-x: SKIP (0.000s)
[239.865908] [036/155] (778s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-pgflip-blt)
[239.974466] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-pgflip-blt
[239.978816] Subtest fbcpsrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[240.064185] [037/155] (778s left) i915_hangman (gt-engine-hang)
[240.137463] Starting subtest: gt-engine-hang
[240.139876] Starting dynamic subtest: rcs0
[241.244290] Dynamic subtest rcs0: SUCCESS (1.106s)
[241.255036] Starting dynamic subtest: bcs0
[243.225895] Dynamic subtest bcs0: SUCCESS (1.978s)
[243.239177] Starting dynamic subtest: vcs0
[245.273038] Dynamic subtest vcs0: SUCCESS (2.044s)
[245.284747] Starting dynamic subtest: vcs1
[247.257438] Dynamic subtest vcs1: SUCCESS (1.981s)
[247.272590] Starting dynamic subtest: vecs0
[249.241497] Dynamic subtest vecs0: SUCCESS (1.980s)
[249.253728] Starting dynamic subtest: ccs0
[251.288487] Dynamic subtest ccs0: SUCCESS (2.044s)
[251.291523] Subtest gt-engine-hang: SUCCESS (11.152s)
[251.369605] [038/155] (767s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[251.426063] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[251.428479] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[251.517992] [039/155] (766s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[251.624582] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt
[251.628623] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[251.723417] [040/155] (766s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[252.286756] Starting subtest: syncobj-stationary-timeline-chain-engines
[252.375784] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.090s)
[253.387703] [041/155] (765s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[253.452390] Starting subtest: yf-tiled-to-vebox-yf-tiled
[253.456662] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[253.648324] [042/155] (764s left) dmabuf_sync_file (export-before-signal)
[253.691934] Starting subtest: export-before-signal
[253.694354] Subtest export-before-signal: SUCCESS (0.001s)
[253.746587] [043/155] (764s left) gem_vm_create (isolation)
[253.793390] Starting subtest: isolation
[253.800204] Subtest isolation: SUCCESS (0.009s)
[253.930466] [044/155] (764s left) gem_exec_reloc (basic-write-cpu)
[253.989524] Subtest basic-write-cpu: SKIP (0.000s)
[254.066927] [045/155] (764s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[254.161800] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[255.252892] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.090s)
[255.366445] [046/155] (763s left) kms_psr2_su (page_flip-p010)
[255.427921] Subtest page_flip-P010: SKIP (0.000s)
[255.493161] [047/155] (763s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[255.603049] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[255.606545] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[255.710039] [048/155] (763s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-pwrite)
[255.798567] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-pwrite
[255.802530] Subtest hdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[255.885955] [049/155] (763s left) kms_vblank (crtc-id)
[255.984598] Starting subtest: crtc-id
[255.986208] Starting dynamic subtest: pipe-A-eDP-1
[256.436285] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.450s)
[256.452686] Starting dynamic subtest: pipe-D-eDP-1
[257.578251] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.139s)
[257.580129] Subtest crtc-id: SUCCESS (1.593s)
[258.401439] [050/155] (760s left) kms_chamelium_edid (dp-mode-timings)
[258.776897] Subtest dp-mode-timings: SKIP (0.000s)
[259.614370] [051/155] (759s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[259.705825] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[259.708289] Starting dynamic subtest: pipe-A-eDP-1
[259.731885] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[259.743777] Starting dynamic subtest: pipe-B-eDP-1
[259.765650] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[259.774635] Starting dynamic subtest: pipe-C-eDP-1
[259.795596] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[259.804347] Starting dynamic subtest: pipe-D-eDP-1
[259.825264] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[259.829090] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[261.990583] [052/155] (757s left) kms_psr (psr2-no-drrs)
[262.091592] Starting subtest: psr2-no-drrs
[262.093832] Starting dynamic subtest: eDP-1
[263.754537] Dynamic subtest eDP-1: SUCCESS (1.664s)
[263.756772] Subtest psr2-no-drrs: SUCCESS (1.665s)
[264.632763] [053/155] (754s left) gem_exec_balancer (parallel-contexts)
[264.908050] Starting subtest: parallel-contexts
[267.621082] Subtest parallel-contexts: SUCCESS (2.713s)
[268.335310] [054/155] (750s left) i915_power (sanity)
[268.385067] Starting subtest: sanity
[272.438655] Subtest sanity: SUCCESS (4.047s)
[274.386817] [055/155] (744s left) gem_basic (bad-close)
[274.441932] Starting subtest: bad-close
[274.446285] Subtest bad-close: SUCCESS (0.000s)
[274.539285] [056/155] (744s left) prime_vgem (busy)
[274.605348] Starting subtest: busy
[274.607480] Starting dynamic subtest: rcs0
[274.681740] Dynamic subtest rcs0: SUCCESS (0.076s)
[274.691781] Starting dynamic subtest: bcs0
[274.754032] Dynamic subtest bcs0: SUCCESS (0.071s)
[274.763554] Starting dynamic subtest: vcs0
[274.801939] Dynamic subtest vcs0: SUCCESS (0.047s)
[274.811380] Starting dynamic subtest: vcs1
[274.850254] Dynamic subtest vcs1: SUCCESS (0.047s)
[274.859684] Starting dynamic subtest: vecs0
[274.897991] Dynamic subtest vecs0: SUCCESS (0.047s)
[274.907419] Starting dynamic subtest: ccs0
[274.949111] Dynamic subtest ccs0: SUCCESS (0.050s)
[274.951601] Subtest busy: SUCCESS (0.344s)
[275.081678] [057/155] (744s left) gem_mmap_wc (bad-object)
[275.139183] Subtest bad-object: SKIP (0.000s)
[275.205804] [058/155] (744s left) gem_exec_schedule (semaphore-power)
[275.393105] Subtest semaphore-power: SKIP (0.000s)
[275.572239] [059/155] (743s left) kms_psr (pr-suspend)
[275.670953] Starting subtest: pr-suspend
[275.675327] Subtest pr-suspend: SKIP (0.000s)
[275.745047] [060/155] (743s left) gem_partial_pwrite_pread (reads-display)
[275.816531] Subtest reads-display: SKIP (0.000s)
[275.888900] [061/155] (743s left) sw_sync (alloc_fence)
[275.929430] Starting subtest: alloc_fence
[275.931732] Subtest alloc_fence: SUCCESS (0.000s)
[275.988475] [062/155] (743s left) gem_exec_store (pages)
[276.052575] Starting subtest: pages
[276.054878] Starting dynamic subtest: rcs0
[276.143894] Dynamic subtest rcs0: SUCCESS (0.093s)
[276.155282] Starting dynamic subtest: bcs0
[276.212931] Dynamic subtest bcs0: SUCCESS (0.068s)
[276.223786] Starting dynamic subtest: vcs0
[276.278811] Dynamic subtest vcs0: SUCCESS (0.065s)
[276.289436] Starting dynamic subtest: vcs1
[276.347301] Dynamic subtest vcs1: SUCCESS (0.068s)
[276.358563] Starting dynamic subtest: vecs0
[276.410497] Dynamic subtest vecs0: SUCCESS (0.062s)
[276.420733] Starting dynamic subtest: ccs0
[276.483740] Dynamic subtest ccs0: SUCCESS (0.071s)
[276.486153] Subtest pages: SUCCESS (0.435s)
[276.671928] [063/155] (742s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[276.760733] Starting subtest: y-tiled-32bpp-rotate-180
[276.765413] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[276.853483] [064/155] (742s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[276.972409] Starting subtest: basic-busy-flip-before-cursor-atomic
[276.976819] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[277.119998] [065/155] (742s left) kms_pm_backlight (fade)
[277.218313] Starting subtest: fade
[277.266884] Starting dynamic subtest: eDP-1
[280.277676] Dynamic subtest eDP-1: SUCCESS (3.009s)
[280.280321] Subtest fade: SUCCESS (3.060s)
[281.174913] [066/155] (738s left) gen7_exec_parse (chained-batch)
[281.239731] Subtest chained-batch: SKIP (0.000s)
[281.306353] [067/155] (738s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[281.407189] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[281.411315] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[281.514196] [068/155] (738s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[281.616242] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[288.232168] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.621s)
[288.355996] [069/155] (731s left) kms_async_flips (invalid-async-flip)
[288.456168] Starting subtest: invalid-async-flip
[288.831747] Starting dynamic subtest: pipe-A-eDP-1
[289.618589] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.787s)
[289.982767] Starting dynamic subtest: pipe-B-eDP-1
[290.849643] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.876s)
[291.209228] Starting dynamic subtest: pipe-C-eDP-1
[292.071566] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.870s)
[292.428797] Starting dynamic subtest: pipe-D-eDP-1
[293.196154] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.778s)
[293.198519] Subtest invalid-async-flip: SUCCESS (4.742s)
[294.349314] [070/155] (725s left) gem_lmem_swapping (verify-ccs)
[294.394657] Subtest verify-ccs: SKIP (0.000s)
[294.436289] [071/155] (725s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[294.525695] Starting subtest: fbc-tiling-linear
[298.243190] Subtest fbc-tiling-linear: SUCCESS (3.722s)
[299.447604] [072/155] (720s left) kms_rotation_crc (multiplane-rotation)
[299.540096] Starting subtest: multiplane-rotation
[316.943057] Subtest multiplane-rotation: SUCCESS (17.404s)
[317.057701] [073/155] (702s left) syncobj_wait (multi-wait-all-signaled)
[317.119568] Starting subtest: multi-wait-all-signaled
[317.121597] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[317.227661] [074/155] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[317.317500] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[317.321106] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[317.395557] [075/155] (702s left) gem_eio (wait-wedge-immediate)
[317.617740] Starting subtest: wait-wedge-immediate
[319.359643] Subtest wait-wedge-immediate: SUCCESS (1.741s)
[319.508021] [076/155] (700s left) i915_pm_rps (engine-order)
[319.563297] Starting subtest: engine-order
[324.453016] Subtest engine-order: SUCCESS (4.891s)
[324.592306] [077/155] (695s left) kms_cursor_crc (cursor-onscreen-64x21)
[324.802740] Starting subtest: cursor-onscreen-64x21
[324.851290] Subtest cursor-onscreen-64x21: SKIP (0.043s)
[325.101280] [078/155] (695s left) perf_pmu (invalid-init)
[325.140192] Starting subtest: invalid-init
[325.142480] Subtest invalid-init: SUCCESS (0.000s)
[325.234493] [079/155] (694s left) kms_addfb_basic (size-max)
[325.326187] Starting subtest: size-max
[325.328382] Subtest size-max: SUCCESS (0.000s)
[325.454840] [080/155] (694s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[325.545913] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[325.549294] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[325.638112] [081/155] (694s left) kms_content_protection (uevent)
[325.770642] Starting subtest: uevent
[325.774688] Subtest uevent: SKIP (0.000s)
[325.896711] [082/155] (694s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[325.988844] Starting subtest: yf-tiled-16bpp-rotate-270
[325.992608] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[326.083292] [083/155] (694s left) gem_exec_create (forked)
[326.140874] Starting subtest: forked
[326.142996] Starting dynamic subtest: smem
[347.323654] Dynamic subtest smem: SUCCESS (21.179s)
[347.326792] Subtest forked: SUCCESS (21.181s)
[347.514439] [084/155] (672s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[347.630179] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[348.582772] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.952s)
[349.455329] [085/155] (670s left) syncobj_timeline (multi-wait-available-submitted)
[349.501108] Starting subtest: multi-wait-available-submitted
[349.503412] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[349.605478] [086/155] (670s left) kms_cursor_edge_walk (256x256-top-edge)
[349.699055] Starting subtest: 256x256-top-edge
[349.701382] Starting dynamic subtest: pipe-A-eDP-1
[353.122522] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.422s)
[353.133831] Starting dynamic subtest: pipe-D-eDP-1
[356.325844] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.203s)
[356.328118] Subtest 256x256-top-edge: SUCCESS (6.628s)
[357.147089] [087/155] (663s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[357.243853] Starting subtest: x-tiled-64bpp-rotate-0
[358.888812] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.646s)
[359.033957] [088/155] (661s left) api_intel_bb (purge-bb)
[359.089634] Starting subtest: purge-bb
[359.091576] Subtest purge-bb: SUCCESS (0.004s)
[359.218354] [089/155] (661s left) kms_frontbuffer_tracking (hdr-suspend)
[359.319810] Starting subtest: hdr-suspend
[359.323297] Subtest hdr-suspend: SKIP (0.000s)
[359.427830] [090/155] (661s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[359.515910] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[359.519470] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[359.616200] [091/155] (661s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-move)
[359.715258] Starting subtest: hdr-2p-scndscrn-cur-indfb-move
[359.718875] Subtest hdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[359.799251] [092/155] (660s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[359.891954] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[359.895863] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[360.011936] [093/155] (660s left) gem_exec_params (invalid-ring)
[360.066309] Starting subtest: invalid-ring
[360.068192] Subtest invalid-ring: SUCCESS (0.000s)
[360.211913] [094/155] (660s left) gem_exec_balancer (semaphore)
[360.271469] Starting subtest: semaphore
[362.298742] Subtest semaphore: SUCCESS (2.024s)
[362.619733] [095/155] (658s left) perf (rc6-disable)
[362.767129] Starting subtest: rc6-disable
[362.947323] Subtest rc6-disable: SUCCESS (0.182s)
[362.996685] [096/155] (657s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[363.089839] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[363.092332] Starting dynamic subtest: pipe-A
[363.206334] Dynamic subtest pipe-A: SKIP (0.112s)
[363.217151] Starting dynamic subtest: pipe-B
[364.416856] Dynamic subtest pipe-B: SKIP (1.211s)
[364.427765] Starting dynamic subtest: pipe-C
[365.648145] Dynamic subtest pipe-C: SUCCESS (1.236s)
[365.658462] Starting dynamic subtest: pipe-D
[366.880904] Dynamic subtest pipe-D: SUCCESS (1.232s)
[366.883273] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.794s)
[368.088341] [097/155] (652s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-plflip-blt)
[368.181719] Starting subtest: psrhdr-1p-primscrn-shrfb-plflip-blt
[368.185878] Subtest psrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[368.277155] [098/155] (652s left) gem_mmap_offset (blt-coherency)
[368.325706] Starting subtest: blt-coherency
[368.334082] Subtest blt-coherency: SUCCESS (0.011s)
[368.473264] [099/155] (652s left) kms_flip (2x-plain-flip-ts-check)
[368.571262] Starting subtest: 2x-plain-flip-ts-check
[368.580398] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[368.663418] [100/155] (652s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[368.763268] Starting subtest: fb-8bpc-vs-panel-6bpc
[368.765594] Starting dynamic subtest: pipe-A-eDP-1
[370.180638] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.418s)
[370.182927] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.419s)
[371.047999] [101/155] (650s left) i915_drm_fdinfo (isolation)
[371.085432] Starting subtest: isolation
[371.087792] Starting dynamic subtest: rcs0
[371.122260] Dynamic subtest rcs0: SKIP (0.034s)
[371.134724] Starting dynamic subtest: bcs0
[371.138835] Dynamic subtest bcs0: SKIP (0.000s)
[371.149093] Starting dynamic subtest: vcs0
[371.153180] Dynamic subtest vcs0: SKIP (0.000s)
[371.163341] Starting dynamic subtest: vcs1
[371.167396] Dynamic subtest vcs1: SKIP (0.000s)
[371.176831] Starting dynamic subtest: vecs0
[371.181063] Dynamic subtest vecs0: SKIP (0.000s)
[371.190551] Starting dynamic subtest: ccs0
[371.194679] Dynamic subtest ccs0: SKIP (0.000s)
[371.198770] Subtest isolation: SKIP (0.041s)
[371.237747] [102/155] (649s left) gem_exec_whisper (basic-queues-forked)
[371.306771] Starting subtest: basic-queues-forked
[394.348039] Subtest basic-queues-forked: SUCCESS (23.034s)
[394.530441] [103/155] (626s left) gem_exec_params (mmapped)
[394.592574] Starting subtest: mmapped
[394.598241] Subtest mmapped: SUCCESS (0.006s)
[394.721700] [104/155] (626s left) kms_prop_blob (blob-prop-core)
[394.773298] Starting subtest: blob-prop-core
[394.775643] Subtest blob-prop-core: SUCCESS (0.000s)
[394.886987] [105/155] (626s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[394.987919] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[394.992071] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[395.072306] [106/155] (626s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[395.153911] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[395.224016] [107/155] (626s left) gem_ctx_persistence (engines-persistence)
[395.291293] Starting subtest: engines-persistence
[395.293186] Starting dynamic subtest: rcs0
[396.103212] Dynamic subtest rcs0: SUCCESS (0.814s)
[396.115398] Starting dynamic subtest: bcs0
[396.852946] Dynamic subtest bcs0: SUCCESS (0.748s)
[396.864601] Starting dynamic subtest: vcs0
[397.636599] Dynamic subtest vcs0: SUCCESS (0.782s)
[397.653954] Starting dynamic subtest: vcs1
[398.387491] Dynamic subtest vcs1: SUCCESS (0.747s)
[398.400148] Starting dynamic subtest: vecs0
[399.161054] Dynamic subtest vecs0: SUCCESS (0.772s)
[399.173974] Starting dynamic subtest: ccs0
[399.924408] Dynamic subtest ccs0: SUCCESS (0.760s)
[399.927597] Subtest engines-persistence: SUCCESS (4.637s)
[400.022714] [108/155] (621s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[400.119602] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[400.123777] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[400.219395] [109/155] (621s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[400.311082] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[400.315250] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[400.446597] [110/155] (621s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-wc)
[400.548687] Starting subtest: fbchdr-rgb565-draw-mmap-wc
[400.553195] Subtest fbchdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[400.652340] [111/155] (620s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-move)
[400.745729] Starting subtest: psrhdr-1p-primscrn-cur-indfb-move
[400.750069] Subtest psrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[400.828394] [112/155] (620s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite)
[400.920574] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite
[400.923777] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[401.010917] [113/155] (620s left) kms_feature_discovery (display-3x)
[401.095565] Starting subtest: display-3x
[401.099356] Subtest display-3x: SKIP (0.000s)
[401.168374] [114/155] (620s left) kms_lease (lease-unleased-connector)
[401.263547] Starting subtest: lease-unleased-connector
[401.265411] Starting dynamic subtest: pipe-A-eDP-1
[401.269510] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[401.280633] Starting dynamic subtest: pipe-B-eDP-1
[401.282502] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[401.296054] Starting dynamic subtest: pipe-C-eDP-1
[401.298098] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[401.307896] Starting dynamic subtest: pipe-D-eDP-1
[401.309659] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[401.311596] Subtest lease-unleased-connector: SUCCESS (0.033s)
[401.406446] [115/155] (620s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[401.506157] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[405.601035] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.098s)
[406.771466] [116/155] (614s left) prime_mmap_coherency (write)
[406.832214] Starting subtest: write
[412.208819] Subtest write: SUCCESS (5.377s)
[412.350847] [117/155] (609s left) kms_chamelium_audio (dp-audio)
[412.747330] Subtest dp-audio: SKIP (0.000s)
[413.566088] [118/155] (608s left) kms_flip (basic-flip-vs-wf_vblank)
[413.657276] Starting subtest: basic-flip-vs-wf_vblank
[413.659498] Starting dynamic subtest: A-eDP1
[415.537252] Dynamic subtest A-eDP1: SUCCESS (1.881s)
[415.550396] Starting dynamic subtest: B-eDP1
[417.462380] Dynamic subtest B-eDP1: SUCCESS (1.923s)
[417.475793] Starting dynamic subtest: C-eDP1
[419.380951] Dynamic subtest C-eDP1: SUCCESS (1.917s)
[419.392377] Starting dynamic subtest: D-eDP1
[421.312893] Dynamic subtest D-eDP1: SUCCESS (1.930s)
[421.318873] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.663s)
[422.554289] [119/155] (599s left) gem_ctx_shared (disjoint-timelines)
[422.606636] Starting subtest: disjoint-timelines
[422.636588] Subtest disjoint-timelines: SUCCESS (0.030s)
[422.885328] [120/155] (599s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[422.976819] Starting subtest: bad-rotation-90-yf-tiled-ccs
[422.979177] Starting dynamic subtest: pipe-A-eDP-1
[423.002144] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[423.012595] Starting dynamic subtest: pipe-B-eDP-1
[423.033697] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[423.043132] Starting dynamic subtest: pipe-C-eDP-1
[423.064367] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[423.073937] Starting dynamic subtest: pipe-D-eDP-1
[423.096140] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[423.100553] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[425.311834] [121/155] (596s left) kms_addfb_basic (legacy-format)
[425.368467] Starting subtest: legacy-format
[425.394579] Subtest legacy-format: SUCCESS (0.023s)
[425.530467] [122/155] (596s left) kms_psr (pr-basic)
[425.620671] Starting subtest: pr-basic
[425.625230] Subtest pr-basic: SKIP (0.000s)
[425.711068] [123/155] (596s left) perf_pmu (busy)
[425.749959] Starting subtest: busy
[425.752197] Starting dynamic subtest: rcs0
[426.362333] Dynamic subtest rcs0: SUCCESS (0.613s)
[426.375672] Starting dynamic subtest: bcs0
[426.921126] Dynamic subtest bcs0: SUCCESS (0.557s)
[426.936205] Starting dynamic subtest: vcs0
[428.283242] Dynamic subtest vcs0: SUCCESS (1.358s)
[428.298292] Starting dynamic subtest: vcs1
[430.266348] Dynamic subtest vcs1: SUCCESS (1.981s)
[430.278064] Starting dynamic subtest: vecs0
[432.316671] Dynamic subtest vecs0: SUCCESS (2.047s)
[432.328121] Starting dynamic subtest: ccs0
[432.879477] Dynamic subtest ccs0: SUCCESS (0.562s)
[432.882152] Subtest busy: SUCCESS (7.132s)
[432.984865] [124/155] (589s left) kms_properties (invalid-properties-legacy)
[433.082741] Starting subtest: invalid-properties-legacy
[433.184979] Subtest invalid-properties-legacy: SUCCESS (0.103s)
[433.257401] [125/155] (588s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[433.359124] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[433.363309] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[433.456233] [126/155] (588s left) syncobj_wait (reset-signaled)
[433.503773] Starting subtest: reset-signaled
[433.506068] Subtest reset-signaled: SUCCESS (0.000s)
[433.596468] [127/155] (588s left) kms_addfb_basic (invalid-get-prop)
[433.648069] Starting subtest: invalid-get-prop
[433.650350] Subtest invalid-get-prop: SUCCESS (0.000s)
[433.774289] [128/155] (588s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[433.874602] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[433.878665] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[433.986248] [129/155] (588s left) kms_frontbuffer_tracking (basic)
[434.088175] Starting subtest: basic
[437.317144] Subtest basic: SUCCESS (3.235s)
[437.447970] [130/155] (584s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[437.542888] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[441.551391] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.011s)
[442.711823] [131/155] (579s left) gem_ctx_persistence (process)
[442.781733] Starting subtest: process
[442.975103] Subtest process: SUCCESS (0.196s)
[443.058776] [132/155] (579s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[443.163421] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc
[443.167977] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[443.256261] [133/155] (579s left) kms_flip (2x-plain-flip)
[443.348171] Starting subtest: 2x-plain-flip
[443.356836] Subtest 2x-plain-flip: SKIP (0.010s)
[443.438345] [134/155] (579s left) prime_vgem (fence-write-hang)
[443.502809] Subtest fence-write-hang: SKIP (0.000s)
[443.582067] [135/155] (578s left) kms_lease (empty-lease)
[443.663521] Starting subtest: empty-lease
[443.665378] Starting dynamic subtest: pipe-A-eDP-1
[443.667272] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[443.677570] Starting dynamic subtest: pipe-B-eDP-1
[443.679204] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[443.688506] Starting dynamic subtest: pipe-C-eDP-1
[443.690365] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[443.699722] Starting dynamic subtest: pipe-D-eDP-1
[443.701401] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[443.703073] Subtest empty-lease: SUCCESS (0.008s)
[443.769665] [136/155] (578s left) kms_busy (extended-pageflip-hang-newfb)
[443.858522] Starting subtest: extended-pageflip-hang-newfb
[443.860244] Starting dynamic subtest: pipe-A
[470.098184] Dynamic subtest pipe-A: SUCCESS (26.238s)
[470.109509] Starting dynamic subtest: pipe-D
[485.068470] Dynamic subtest pipe-D: SUCCESS (14.967s)
[485.070746] Subtest extended-pageflip-hang-newfb: SUCCESS (41.209s)
[485.965837] [137/155] (536s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-cpu)
[486.067418] Starting subtest: hdr-rgb101010-draw-mmap-cpu
[486.072052] Subtest hdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[486.170523] [138/155] (536s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-pwrite)
[486.269808] Starting subtest: fbcpsrhdr-rgb565-draw-pwrite
[486.273928] Subtest fbcpsrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[486.363295] [139/155] (536s left) vgem_basic (mmap)
[486.403403] Starting subtest: mmap
[486.416125] Subtest mmap: SUCCESS (0.015s)
[486.473651] [140/155] (536s left) kms_color (ctm-0-75)
[486.568035] Starting subtest: ctm-0-75
[486.569945] Starting dynamic subtest: pipe-A-eDP-1
[491.448659] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.882s)
[491.459730] Starting dynamic subtest: pipe-B-eDP-1
[497.153407] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.702s)
[497.164399] Starting dynamic subtest: pipe-C-eDP-1
[502.542532] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.390s)
[502.553250] Starting dynamic subtest: pipe-D-eDP-1
[507.878803] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.335s)
[507.881467] Subtest ctm-0-75: SUCCESS (21.312s)
[508.719265] [141/155] (514s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[508.811173] Starting subtest: fbc-psr2-sprite-mmap-cpu
[508.813499] Starting dynamic subtest: eDP-1
[508.817642] Dynamic subtest eDP-1: SKIP (0.000s)
[508.821860] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[508.885215] [142/155] (513s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[508.976716] Starting subtest: 4-tiled-32bpp-rotate-270
[508.995321] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[509.111817] [143/155] (513s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[509.159632] Starting subtest: wait-all-for-submit-delayed-submit
[509.262972] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[509.354204] [144/155] (513s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[509.450075] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[509.454846] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[509.537610] [145/155] (513s left) kms_vblank (accuracy-idle)
[509.647225] Starting subtest: accuracy-idle
[509.649433] Starting dynamic subtest: pipe-A-eDP-1
[511.403741] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.755s)
[511.421996] Starting dynamic subtest: pipe-D-eDP-1
[513.859500] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.455s)
[513.861895] Subtest accuracy-idle: SUCCESS (4.213s)
[514.675937] [146/155] (508s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[514.771042] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[514.775207] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[514.872590] [147/155] (508s left) kms_cursor_crc (cursor-random-128x42)
[515.106901] Starting subtest: cursor-random-128x42
[515.153536] Subtest cursor-random-128x42: SKIP (0.042s)
[515.370563] [148/155] (507s left) kms_vrr (lobf)
[515.469175] Starting subtest: lobf
[515.473275] Subtest lobf: SKIP (0.000s)
[515.542380] [149/155] (507s left) gem_reg_read (timestamp-moving)
[515.606052] Starting subtest: timestamp-moving
[516.608074] Subtest timestamp-moving: SUCCESS (1.001s)
[516.685941] [150/155] (506s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[516.814461] Starting subtest: flip-vs-cursor-crc-legacy
[517.462864] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.646s)
[518.313660] [151/155] (504s left) syncobj_basic (bad-create-flags)
[518.361090] Starting subtest: bad-create-flags
[518.363483] Subtest bad-create-flags: SUCCESS (0.000s)
[518.459422] [152/155] (504s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[518.534487] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[518.536888] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.001s)
[518.650930] [153/155] (504s left) gem_busy (parallel)
[518.822587] Starting subtest: parallel
[518.824758] Starting dynamic subtest: rcs0
[520.315357] Dynamic subtest rcs0: SUCCESS (1.494s)
[520.328995] Starting dynamic subtest: bcs0
[522.299744] Dynamic subtest bcs0: SUCCESS (1.981s)
[522.313782] Starting dynamic subtest: vcs0
[524.284034] Dynamic subtest vcs0: SUCCESS (1.981s)
[524.297796] Starting dynamic subtest: vcs1
[526.267843] Dynamic subtest vcs1: SUCCESS (1.981s)
[526.287427] Starting dynamic subtest: vecs0
[528.314912] Dynamic subtest vecs0: SUCCESS (2.044s)
[528.328370] Starting dynamic subtest: ccs0
[530.299579] Dynamic subtest ccs0: SUCCESS (1.982s)
[530.302725] Subtest parallel: SUCCESS (11.481s)
[530.553785] [154/155] (492s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[530.654253] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[537.997213] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.346s)
[538.132094] [155/155] (485s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-blt)
[538.239069] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-blt
[538.243265] Subtest fbchdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[538.358179] Closing watchdogs
results: parsing output: 0/ for test: gem_tiled_pread_basic
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: i915_query
results: parsing output: 3/ for test: gem_exec_await
results: parsing output: 4/ for test: perf_pmu
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_plane
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_softpin
results: parsing output: 10/ for test: gem_ctx_engines
results: parsing output: 11/ for test: kms_rotation_crc
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: kms_flip_scaled_crc
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: gem_exec_flush
results: parsing output: 21/ for test: gem_exec_schedule
results: parsing output: 22/ for test: kms_chamelium_hpd
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_fenced_exec_thrash
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_plane_lowres
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_getfb
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: kms_pm_rpm
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_ccs
results: parsing output: 33/ for test: gem_pxp
results: parsing output: 34/ for test: kms_plane_multiple
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: i915_hangman
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_exec_fence
results: parsing output: 40/ for test: gem_render_copy
results: parsing output: 41/ for test: dmabuf_sync_file
results: parsing output: 42/ for test: gem_vm_create
results: parsing output: 43/ for test: gem_exec_reloc
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_psr2_su
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_vblank
results: parsing output: 49/ for test: kms_chamelium_edid
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: gem_exec_balancer
results: parsing output: 53/ for test: i915_power
results: parsing output: 54/ for test: gem_basic
results: parsing output: 55/ for test: prime_vgem
results: parsing output: 56/ for test: gem_mmap_wc
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: gem_partial_pwrite_pread
results: parsing output: 60/ for test: sw_sync
results: parsing output: 61/ for test: gem_exec_store
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: kms_pm_backlight
results: parsing output: 65/ for test: gen7_exec_parse
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_async_flips
results: parsing output: 69/ for test: gem_lmem_swapping
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_rotation_crc
results: parsing output: 72/ for test: syncobj_wait
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_eio
results: parsing output: 75/ for test: i915_pm_rps
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: perf_pmu
results: parsing output: 78/ for test: kms_addfb_basic
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_content_protection
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: gem_exec_create
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: syncobj_timeline
results: parsing output: 85/ for test: kms_cursor_edge_walk
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: api_intel_bb
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: gem_exec_params
results: parsing output: 93/ for test: gem_exec_balancer
results: parsing output: 94/ for test: perf
results: parsing output: 95/ for test: kms_plane_scaling
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: gem_mmap_offset
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: kms_dither
results: parsing output: 100/ for test: i915_drm_fdinfo
results: parsing output: 101/ for test: gem_exec_whisper
results: parsing output: 102/ for test: gem_exec_params
results: parsing output: 103/ for test: kms_prop_blob
results: parsing output: 104/ for test: kms_flip_scaled_crc
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: gem_ctx_persistence
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_feature_discovery
results: parsing output: 113/ for test: kms_lease
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: prime_mmap_coherency
results: parsing output: 116/ for test: kms_chamelium_audio
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: gem_ctx_shared
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: kms_addfb_basic
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: kms_properties
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: kms_addfb_basic
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_ctx_persistence
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_flip
results: parsing output: 133/ for test: prime_vgem
results: parsing output: 134/ for test: kms_lease
results: parsing output: 135/ for test: kms_busy
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: vgem_basic
results: parsing output: 139/ for test: kms_color
results: parsing output: 140/ for test: kms_psr
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: syncobj_timeline
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_vblank
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_cursor_crc
results: parsing output: 147/ for test: kms_vrr
results: parsing output: 148/ for test: gem_reg_read
results: parsing output: 149/ for test: kms_cursor_legacy
results: parsing output: 150/ for test: syncobj_basic
results: parsing output: 151/ for test: syncobj_timeline
results: parsing output: 152/ for test: gem_busy
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_frontbuffer_tracking
Done.