[57.775327] Initializing watchdogs
[57.775374]   /dev/watchdog0
[57.789419] [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.793632] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.796080] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.799424] [001/155] (960s left) gem_tiled_pread_basic (basic)
[57.879089] Subtest basic: SKIP (0.000s)
[57.943914] [002/155] (960s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-fullscreen)
[58.057312] Starting subtest: hdr-1p-primscrn-spr-indfb-fullscreen
[58.061578] Subtest hdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[58.147594] [003/155] (960s left) i915_query (query-topology-coherent-slice-mask)
[58.192244] Starting subtest: query-topology-coherent-slice-mask
[58.196973] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[58.283182] [004/155] (960s left) gem_exec_await (wide-contexts)
[58.477863] Starting subtest: wide-contexts
[82.150227] Subtest wide-contexts: SUCCESS (23.671s)
[82.351336] [FACT gem_exec_await (wide-contexts)] new: kernel.kmod_is_loaded.vgem: true
[82.355960] [005/155] (936s left) perf_pmu (idle)
[82.431355] Starting subtest: idle
[82.433690] Starting dynamic subtest: rcs0
[82.960564] Dynamic subtest rcs0: SUCCESS (0.528s)
[82.971556] Starting dynamic subtest: bcs0
[83.490325] Dynamic subtest bcs0: SUCCESS (0.528s)
[83.500350] Starting dynamic subtest: vcs0
[84.010872] Dynamic subtest vcs0: SUCCESS (0.519s)
[84.020382] Starting dynamic subtest: vcs1
[84.530107] Dynamic subtest vcs1: SUCCESS (0.517s)
[84.541926] Starting dynamic subtest: vecs0
[85.058035] Dynamic subtest vecs0: SUCCESS (0.527s)
[85.069621] Starting dynamic subtest: ccs0
[85.575458] Dynamic subtest ccs0: SUCCESS (0.516s)
[85.578470] Subtest idle: SUCCESS (3.143s)
[85.679718] [006/155] (932s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[85.796637] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[85.801093] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[85.876828] [007/155] (932s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[85.949209] Starting subtest: etime-multi-wait-all-for-submit-submitted
[86.054797] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.104s)
[86.139198] [008/155] (932s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[86.229425] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[86.233761] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[86.304352] [009/155] (932s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-pgflip-blt)
[86.400948] Starting subtest: fbchdr-1p-primscrn-indfb-pgflip-blt
[86.405517] Subtest fbchdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[86.504476] [010/155] (932s left) gem_softpin (evict-snoop-interruptible)
[86.578464] Starting subtest: evict-snoop-interruptible
[86.583082] Subtest evict-snoop-interruptible: SKIP (0.000s)
[86.703933] [011/155] (931s left) gem_ctx_engines (execute-one)
[86.758139] Starting subtest: execute-one
[88.978999] Subtest execute-one: SUCCESS (2.220s)
[89.319312] [012/155] (929s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[89.412957] Starting subtest: primary-x-tiled-reflect-x-180
[96.302416] Subtest primary-x-tiled-reflect-x-180: SUCCESS (6.891s)
[97.540957] [013/155] (921s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[97.647260] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[97.649718] Starting dynamic subtest: pipe-A-eDP-1
[98.058218] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.382s)
[98.069183] Starting dynamic subtest: pipe-B-eDP-1
[99.432108] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.371s)
[99.445923] Starting dynamic subtest: pipe-C-eDP-1
[100.839726] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.410s)
[100.850321] Starting dynamic subtest: pipe-D-eDP-1
[102.193267] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.355s)
[102.195096] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.521s)
[103.376766] [014/155] (915s left) kms_flip (plain-flip-fb-recreate-interruptible)
[103.470302] Starting subtest: plain-flip-fb-recreate-interruptible
[103.472684] Starting dynamic subtest: A-eDP1
[112.363267] Dynamic subtest A-eDP1: SUCCESS (8.892s)
[112.374786] Starting dynamic subtest: B-eDP1
[121.189080] Dynamic subtest B-eDP1: SUCCESS (8.825s)
[121.198260] Starting dynamic subtest: C-eDP1
[130.014651] Dynamic subtest C-eDP1: SUCCESS (8.824s)
[130.023280] Starting dynamic subtest: D-eDP1
[138.848201] Dynamic subtest D-eDP1: SUCCESS (8.832s)
[138.852488] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.383s)
[140.031141] [015/155] (878s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[140.121078] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[140.125458] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[140.192365] [016/155] (878s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[140.288207] Starting subtest: psr-modesetfrombusy
[146.584819] Subtest psr-modesetfrombusy: SUCCESS (6.303s)
[146.736366] [017/155] (872s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[146.843608] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[146.847171] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[146.951965] [018/155] (872s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[147.036638] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[147.047146] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[147.161909] [019/155] (871s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[147.258060] Starting subtest: psr-1p-pri-indfb-multidraw
[169.906380] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.653s)
[170.044999] [020/155] (849s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[170.162196] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[170.165457] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[170.229872] [021/155] (848s left) gem_exec_flush (basic-wb-pro-default)
[170.290164] Starting subtest: basic-wb-pro-default
[175.753430] Subtest basic-wb-pro-default: SUCCESS (5.465s)
[175.958626] [022/155] (843s left) gem_exec_schedule (independent)
[176.024507] Starting subtest: independent
[176.026513] Starting dynamic subtest: rcs0
[177.273368] Dynamic subtest rcs0: SUCCESS (1.258s)
[177.285768] Starting dynamic subtest: vcs0
[179.256941] Dynamic subtest vcs0: SUCCESS (1.981s)
[179.269875] Starting dynamic subtest: vcs1
[181.304832] Dynamic subtest vcs1: SUCCESS (2.045s)
[181.314752] Starting dynamic subtest: vecs0
[183.299637] Dynamic subtest vecs0: SUCCESS (1.992s)
[183.311940] Starting dynamic subtest: ccs0
[185.273509] Dynamic subtest ccs0: SUCCESS (1.972s)
[185.276076] Subtest independent: SUCCESS (9.260s)
[185.574892] [023/155] (833s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[185.965545] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[186.808632] [024/155] (832s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[186.909949] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[186.914259] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[187.001418] [025/155] (832s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[187.067184] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[187.138365] [026/155] (832s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[187.238671] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[187.243143] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[187.351357] [027/155] (832s left) kms_plane_lowres (tiling-4)
[187.439363] Starting subtest: tiling-4
[187.441667] Starting dynamic subtest: pipe-A-eDP-1
[187.446276] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[187.742351] Starting dynamic subtest: pipe-B-eDP-1
[187.747084] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[187.756073] Starting dynamic subtest: pipe-C-eDP-1
[187.760803] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[187.770087] Starting dynamic subtest: pipe-D-eDP-1
[187.774187] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[187.778129] Subtest tiling-4: SKIP (0.299s)
[188.558777] [028/155] (830s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[188.665594] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[188.669629] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[188.764109] [029/155] (830s left) kms_getfb (getfb-handle-protection)
[188.865769] Starting subtest: getfb-handle-protection
[188.890657] Subtest getfb-handle-protection: SUCCESS (0.027s)
[189.012232] [030/155] (830s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[189.111081] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[189.113414] Starting dynamic subtest: pipe-A-eDP-1
[194.920691] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.813s)
[194.930900] Starting dynamic subtest: pipe-B-eDP-1
[201.594769] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.673s)
[201.597478] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.490s)
[202.484459] [031/155] (817s left) kms_pm_rpm (legacy-planes-dpms)
[204.436366] Starting subtest: legacy-planes-dpms
[204.438668] Starting dynamic subtest: plane-65
[212.741032] Dynamic subtest plane-65: SUCCESS (8.308s)
[212.753423] Starting dynamic subtest: plane-95
[221.101540] Dynamic subtest plane-95: SUCCESS (8.357s)
[221.113846] Starting dynamic subtest: plane-125
[229.712316] Dynamic subtest plane-125: SUCCESS (8.608s)
[229.724832] Starting dynamic subtest: plane-135
[238.328197] Dynamic subtest plane-135: SUCCESS (8.614s)
[238.330682] Subtest legacy-planes-dpms: SUCCESS (33.897s)
[238.738867] [032/155] (780s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[238.847661] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[238.851667] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[238.965783] [033/155] (780s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[239.054097] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[239.056580] Starting dynamic subtest: pipe-A-eDP-1
[239.066551] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[239.082342] Starting dynamic subtest: pipe-B-eDP-1
[239.091110] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[239.099904] Starting dynamic subtest: pipe-C-eDP-1
[239.108156] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[239.117091] Starting dynamic subtest: pipe-D-eDP-1
[239.125184] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[239.129124] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[241.361216] [034/155] (778s left) gem_pxp (reject-modify-context-protection-off-2)
[241.487922] Starting subtest: reject-modify-context-protection-off-2
[241.490431] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[241.596121] [035/155] (778s left) kms_plane_multiple (2x-tiling-x)
[241.691181] Starting subtest: 2x-tiling-x
[241.695516] Subtest 2x-tiling-x: SKIP (0.000s)
[241.765983] [036/155] (777s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-pgflip-blt)
[241.868653] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-pgflip-blt
[241.872938] Subtest fbcpsrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[241.969483] [037/155] (777s left) i915_hangman (gt-engine-hang)
[242.026844] Starting subtest: gt-engine-hang
[242.028767] Starting dynamic subtest: rcs0
[243.289353] Dynamic subtest rcs0: SUCCESS (1.261s)
[243.302938] Starting dynamic subtest: bcs0
[245.272447] Dynamic subtest bcs0: SUCCESS (1.980s)
[245.284361] Starting dynamic subtest: vcs0
[247.257100] Dynamic subtest vcs0: SUCCESS (1.982s)
[247.269531] Starting dynamic subtest: vcs1
[249.239592] Dynamic subtest vcs1: SUCCESS (1.980s)
[249.251336] Starting dynamic subtest: vecs0
[251.224467] Dynamic subtest vecs0: SUCCESS (1.982s)
[251.236209] Starting dynamic subtest: ccs0
[253.271328] Dynamic subtest ccs0: SUCCESS (2.044s)
[253.274065] Subtest gt-engine-hang: SUCCESS (11.245s)
[253.353305] [038/155] (766s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[253.407371] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[253.409735] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[253.530121] [039/155] (766s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[253.631298] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt
[253.635293] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[253.748137] [040/155] (766s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[254.292905] Starting subtest: syncobj-stationary-timeline-chain-engines
[254.392470] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.100s)
[255.396886] [041/155] (764s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[255.458354] Starting subtest: yf-tiled-to-vebox-yf-tiled
[255.462774] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[255.655405] [042/155] (764s left) dmabuf_sync_file (export-before-signal)
[255.698073] Starting subtest: export-before-signal
[255.700300] Subtest export-before-signal: SUCCESS (0.001s)
[255.750909] [043/155] (764s left) gem_vm_create (isolation)
[255.803502] Starting subtest: isolation
[255.814362] Subtest isolation: SUCCESS (0.011s)
[255.949756] [044/155] (764s left) gem_exec_reloc (basic-write-cpu)
[256.015242] Subtest basic-write-cpu: SKIP (0.000s)
[256.085903] [045/155] (764s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[256.197995] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[257.146132] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.948s)
[257.295344] [046/155] (762s left) kms_psr2_su (page_flip-p010)
[257.348306] Subtest page_flip-P010: SKIP (0.000s)
[257.404913] [047/155] (762s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[257.501218] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[257.504511] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[257.605476] [048/155] (762s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-pwrite)
[257.700871] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-pwrite
[257.704263] Subtest hdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[257.791494] [049/155] (762s left) kms_vblank (crtc-id)
[257.887378] Starting subtest: crtc-id
[257.889253] Starting dynamic subtest: pipe-A-eDP-1
[258.349782] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.462s)
[258.363658] Starting dynamic subtest: pipe-D-eDP-1
[259.496908] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.142s)
[259.499190] Subtest crtc-id: SUCCESS (1.608s)
[260.336905] [050/155] (759s left) kms_chamelium_edid (dp-mode-timings)
[260.717679] Subtest dp-mode-timings: SKIP (0.000s)
[261.556170] [051/155] (758s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[261.648235] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[261.650506] Starting dynamic subtest: pipe-A-eDP-1
[261.673240] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[261.684830] Starting dynamic subtest: pipe-B-eDP-1
[261.705876] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[261.714707] Starting dynamic subtest: pipe-C-eDP-1
[261.735760] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[261.744703] Starting dynamic subtest: pipe-D-eDP-1
[261.766506] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[261.771060] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[263.934643] [052/155] (756s left) kms_psr (psr2-no-drrs)
[264.042306] Starting subtest: psr2-no-drrs
[264.044610] Starting dynamic subtest: eDP-1
[265.689448] Dynamic subtest eDP-1: SUCCESS (1.647s)
[265.691854] Subtest psr2-no-drrs: SUCCESS (1.648s)
[266.518303] [053/155] (753s left) gem_exec_balancer (parallel-contexts)
[266.781211] Starting subtest: parallel-contexts
[269.544344] Subtest parallel-contexts: SUCCESS (2.762s)
[270.392787] [054/155] (750s left) i915_power (sanity)
[270.448674] Starting subtest: sanity
[274.501189] Subtest sanity: SUCCESS (4.047s)
[276.304693] [055/155] (744s left) gem_basic (bad-close)
[276.363119] Starting subtest: bad-close
[276.367582] Subtest bad-close: SUCCESS (0.000s)
[276.473877] [056/155] (744s left) prime_vgem (busy)
[276.539698] Starting subtest: busy
[276.542149] Starting dynamic subtest: rcs0
[276.602350] Dynamic subtest rcs0: SUCCESS (0.063s)
[276.613502] Starting dynamic subtest: bcs0
[276.666096] Dynamic subtest bcs0: SUCCESS (0.063s)
[276.675762] Starting dynamic subtest: vcs0
[276.715756] Dynamic subtest vcs0: SUCCESS (0.049s)
[276.725612] Starting dynamic subtest: vcs1
[276.761144] Dynamic subtest vcs1: SUCCESS (0.044s)
[276.770722] Starting dynamic subtest: vecs0
[276.807092] Dynamic subtest vecs0: SUCCESS (0.045s)
[276.816610] Starting dynamic subtest: ccs0
[276.856365] Dynamic subtest ccs0: SUCCESS (0.048s)
[276.858701] Subtest busy: SUCCESS (0.318s)
[276.975861] [057/155] (743s left) gem_mmap_wc (bad-object)
[277.031221] Subtest bad-object: SKIP (0.000s)
[277.088220] [058/155] (743s left) gem_exec_schedule (semaphore-power)
[277.269426] Subtest semaphore-power: SKIP (0.000s)
[277.450581] [059/155] (743s left) kms_psr (pr-suspend)
[277.547077] Starting subtest: pr-suspend
[277.551535] Subtest pr-suspend: SKIP (0.000s)
[277.631436] [060/155] (743s left) gem_partial_pwrite_pread (reads-display)
[277.699471] Subtest reads-display: SKIP (0.000s)
[277.776688] [061/155] (743s left) sw_sync (alloc_fence)
[277.812599] Starting subtest: alloc_fence
[277.814822] Subtest alloc_fence: SUCCESS (0.000s)
[277.869064] [062/155] (742s left) gem_exec_store (pages)
[277.930449] Starting subtest: pages
[277.932471] Starting dynamic subtest: rcs0
[278.038051] Dynamic subtest rcs0: SUCCESS (0.108s)
[278.050786] Starting dynamic subtest: bcs0
[278.112360] Dynamic subtest bcs0: SUCCESS (0.072s)
[278.123800] Starting dynamic subtest: vcs0
[278.189299] Dynamic subtest vcs0: SUCCESS (0.076s)
[278.201078] Starting dynamic subtest: vcs1
[278.262860] Dynamic subtest vcs1: SUCCESS (0.072s)
[278.274577] Starting dynamic subtest: vecs0
[278.335322] Dynamic subtest vecs0: SUCCESS (0.071s)
[278.346481] Starting dynamic subtest: ccs0
[278.402880] Dynamic subtest ccs0: SUCCESS (0.067s)
[278.405355] Subtest pages: SUCCESS (0.475s)
[278.598744] [063/155] (742s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[278.708189] Starting subtest: y-tiled-32bpp-rotate-180
[278.712898] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[278.813044] [064/155] (742s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[278.939220] Starting subtest: basic-busy-flip-before-cursor-atomic
[278.943892] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[279.096186] [065/155] (741s left) kms_pm_backlight (fade)
[279.193272] Starting subtest: fade
[279.245655] Starting dynamic subtest: eDP-1
[282.246639] Dynamic subtest eDP-1: SUCCESS (3.002s)
[282.249064] Subtest fade: SUCCESS (3.053s)
[283.126651] [066/155] (737s left) gen7_exec_parse (chained-batch)
[283.177732] Subtest chained-batch: SKIP (0.000s)
[283.250571] [067/155] (737s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[283.362437] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[283.366540] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[283.461170] [068/155] (737s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[283.556054] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[290.163405] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.612s)
[290.298957] [069/155] (730s left) kms_async_flips (invalid-async-flip)
[290.392717] Starting subtest: invalid-async-flip
[290.780527] Starting dynamic subtest: pipe-A-eDP-1
[291.580468] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.801s)
[291.940669] Starting dynamic subtest: pipe-B-eDP-1
[292.813275] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.881s)
[293.176651] Starting dynamic subtest: pipe-C-eDP-1
[293.960073] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.793s)
[294.315291] Starting dynamic subtest: pipe-D-eDP-1
[295.085161] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.779s)
[295.087586] Subtest invalid-async-flip: SUCCESS (4.694s)
[296.240273] [070/155] (724s left) gem_lmem_swapping (verify-ccs)
[296.287606] Subtest verify-ccs: SKIP (0.000s)
[296.334794] [071/155] (724s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[296.433591] Starting subtest: fbc-tiling-linear
[300.153831] Subtest fbc-tiling-linear: SUCCESS (3.725s)
[301.360537] [072/155] (719s left) kms_rotation_crc (multiplane-rotation)
[301.457228] Starting subtest: multiplane-rotation
[318.949300] Subtest multiplane-rotation: SUCCESS (17.493s)
[319.068312] [073/155] (702s left) syncobj_wait (multi-wait-all-signaled)
[319.125172] Starting subtest: multi-wait-all-signaled
[319.126974] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[319.209237] [074/155] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[319.303996] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[319.307518] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[319.405673] [075/155] (701s left) gem_eio (wait-wedge-immediate)
[319.616041] Starting subtest: wait-wedge-immediate
[321.337086] Subtest wait-wedge-immediate: SUCCESS (1.720s)
[321.477685] [076/155] (699s left) i915_pm_rps (engine-order)
[321.533045] Starting subtest: engine-order
[326.432064] Subtest engine-order: SUCCESS (4.898s)
[326.528770] [077/155] (694s left) kms_cursor_crc (cursor-onscreen-64x21)
[326.738819] Starting subtest: cursor-onscreen-64x21
[326.783959] Subtest cursor-onscreen-64x21: SKIP (0.040s)
[327.050968] [078/155] (694s left) perf_pmu (invalid-init)
[327.088940] Starting subtest: invalid-init
[327.091499] Subtest invalid-init: SUCCESS (0.000s)
[327.190530] [079/155] (694s left) kms_addfb_basic (size-max)
[327.288161] Starting subtest: size-max
[327.290439] Subtest size-max: SUCCESS (0.000s)
[327.391686] [080/155] (694s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[327.478803] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[327.482245] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[327.581054] [081/155] (694s left) kms_content_protection (uevent)
[327.719245] Starting subtest: uevent
[327.722984] Subtest uevent: SKIP (0.000s)
[327.839054] [082/155] (693s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[327.939332] Starting subtest: yf-tiled-16bpp-rotate-270
[327.942771] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[328.018306] [083/155] (693s left) gem_exec_create (forked)
[328.085137] Starting subtest: forked
[328.087078] Starting dynamic subtest: smem
[349.306338] Dynamic subtest smem: SUCCESS (21.217s)
[349.309377] Subtest forked: SUCCESS (21.221s)
[349.493621] [084/155] (672s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[349.618662] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[350.570624] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.950s)
[351.374446] [085/155] (670s left) syncobj_timeline (multi-wait-available-submitted)
[351.434883] Starting subtest: multi-wait-available-submitted
[351.436904] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[351.553660] [086/155] (670s left) kms_cursor_edge_walk (256x256-top-edge)
[351.655195] Starting subtest: 256x256-top-edge
[351.657299] Starting dynamic subtest: pipe-A-eDP-1
[355.140282] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.483s)
[355.150302] Starting dynamic subtest: pipe-D-eDP-1
[358.276371] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.136s)
[358.278631] Subtest 256x256-top-edge: SUCCESS (6.622s)
[359.075985] [087/155] (662s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[359.181686] Starting subtest: x-tiled-64bpp-rotate-0
[360.804783] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.623s)
[360.977481] [088/155] (660s left) api_intel_bb (purge-bb)
[361.042831] Starting subtest: purge-bb
[361.045257] Subtest purge-bb: SUCCESS (0.003s)
[361.173324] [089/155] (660s left) kms_frontbuffer_tracking (hdr-suspend)
[361.268781] Starting subtest: hdr-suspend
[361.272448] Subtest hdr-suspend: SKIP (0.000s)
[361.361083] [090/155] (660s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[361.450951] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[361.454361] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[361.578291] [091/155] (660s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-move)
[361.665869] Starting subtest: hdr-2p-scndscrn-cur-indfb-move
[361.669477] Subtest hdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[361.760292] [092/155] (660s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[361.865341] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[361.869071] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[361.962200] [093/155] (660s left) gem_exec_params (invalid-ring)
[362.011275] Starting subtest: invalid-ring
[362.013187] Subtest invalid-ring: SUCCESS (0.000s)
[362.115837] [094/155] (660s left) gem_exec_balancer (semaphore)
[362.177239] Starting subtest: semaphore
[364.277166] Subtest semaphore: SUCCESS (2.097s)
[364.602043] [095/155] (657s left) perf (rc6-disable)
[364.741335] Starting subtest: rc6-disable
[364.919312] Subtest rc6-disable: SUCCESS (0.179s)
[364.968291] [096/155] (657s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[365.062153] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[365.064412] Starting dynamic subtest: pipe-A
[365.173677] Dynamic subtest pipe-A: SKIP (0.109s)
[365.184458] Starting dynamic subtest: pipe-B
[366.350763] Dynamic subtest pipe-B: SKIP (1.176s)
[366.362080] Starting dynamic subtest: pipe-C
[367.574302] Dynamic subtest pipe-C: SUCCESS (1.228s)
[367.585099] Starting dynamic subtest: pipe-D
[368.805434] Dynamic subtest pipe-D: SUCCESS (1.230s)
[368.807875] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.746s)
[370.008737] [097/155] (652s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-plflip-blt)
[370.106350] Starting subtest: psrhdr-1p-primscrn-shrfb-plflip-blt
[370.110756] Subtest psrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[370.210502] [098/155] (652s left) gem_mmap_offset (blt-coherency)
[370.263302] Starting subtest: blt-coherency
[370.271619] Subtest blt-coherency: SUCCESS (0.009s)
[370.400329] [099/155] (651s left) kms_flip (2x-plain-flip-ts-check)
[370.492022] Starting subtest: 2x-plain-flip-ts-check
[370.502971] Subtest 2x-plain-flip-ts-check: SKIP (0.010s)
[370.582426] [100/155] (651s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[370.665604] Starting subtest: fb-8bpc-vs-panel-6bpc
[370.667821] Starting dynamic subtest: pipe-A-eDP-1
[372.079737] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.413s)
[372.082081] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.415s)
[372.899137] [101/155] (649s left) i915_drm_fdinfo (isolation)
[372.937682] Starting subtest: isolation
[372.939875] Starting dynamic subtest: rcs0
[372.975057] Dynamic subtest rcs0: SKIP (0.036s)
[372.986065] Starting dynamic subtest: bcs0
[372.990195] Dynamic subtest bcs0: SKIP (0.000s)
[373.000269] Starting dynamic subtest: vcs0
[373.004145] Dynamic subtest vcs0: SKIP (0.000s)
[373.014016] Starting dynamic subtest: vcs1
[373.018304] Dynamic subtest vcs1: SKIP (0.000s)
[373.027764] Starting dynamic subtest: vecs0
[373.032061] Dynamic subtest vecs0: SKIP (0.000s)
[373.041520] Starting dynamic subtest: ccs0
[373.045949] Dynamic subtest ccs0: SKIP (0.000s)
[373.050356] Subtest isolation: SKIP (0.043s)
[373.087919] [102/155] (649s left) gem_exec_whisper (basic-queues-forked)
[373.151096] Starting subtest: basic-queues-forked
[397.318466] Subtest basic-queues-forked: SUCCESS (24.163s)
[397.499514] [103/155] (625s left) gem_exec_params (mmapped)
[397.559898] Starting subtest: mmapped
[397.566121] Subtest mmapped: SUCCESS (0.006s)
[397.689366] [104/155] (624s left) kms_prop_blob (blob-prop-core)
[397.744079] Starting subtest: blob-prop-core
[397.746349] Subtest blob-prop-core: SUCCESS (0.000s)
[397.840424] [105/155] (624s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[397.929840] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[397.933812] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[398.014550] [106/155] (624s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[398.081968] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[398.163949] [107/155] (624s left) gem_ctx_persistence (engines-persistence)
[398.223989] Starting subtest: engines-persistence
[398.226350] Starting dynamic subtest: rcs0
[399.036761] Dynamic subtest rcs0: SUCCESS (0.812s)
[399.047955] Starting dynamic subtest: bcs0
[399.804058] Dynamic subtest bcs0: SUCCESS (0.765s)
[399.816037] Starting dynamic subtest: vcs0
[400.565941] Dynamic subtest vcs0: SUCCESS (0.760s)
[400.578879] Starting dynamic subtest: vcs1
[401.336037] Dynamic subtest vcs1: SUCCESS (0.768s)
[401.348609] Starting dynamic subtest: vecs0
[402.102058] Dynamic subtest vecs0: SUCCESS (0.763s)
[402.114785] Starting dynamic subtest: ccs0
[402.877066] Dynamic subtest ccs0: SUCCESS (0.773s)
[402.879911] Subtest engines-persistence: SUCCESS (4.654s)
[402.973326] [108/155] (619s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[403.092782] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[403.097210] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[403.187618] [109/155] (619s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[403.281858] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[403.286226] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[403.380938] [110/155] (619s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-wc)
[403.474474] Starting subtest: fbchdr-rgb565-draw-mmap-wc
[403.478599] Subtest fbchdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[403.580966] [111/155] (619s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-move)
[403.675904] Starting subtest: psrhdr-1p-primscrn-cur-indfb-move
[403.680071] Subtest psrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[403.777100] [112/155] (619s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite)
[403.879903] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite
[403.883278] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[403.979154] [113/155] (618s left) kms_feature_discovery (display-3x)
[404.073395] Starting subtest: display-3x
[404.077162] Subtest display-3x: SKIP (0.000s)
[404.160194] [114/155] (618s left) kms_lease (lease-unleased-connector)
[404.258454] Starting subtest: lease-unleased-connector
[404.260394] Starting dynamic subtest: pipe-A-eDP-1
[404.268077] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.008s)
[404.278914] Starting dynamic subtest: pipe-B-eDP-1
[404.281451] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[404.291896] Starting dynamic subtest: pipe-C-eDP-1
[404.293678] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[404.303144] Starting dynamic subtest: pipe-D-eDP-1
[404.304975] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[404.306739] Subtest lease-unleased-connector: SUCCESS (0.032s)
[404.396811] [115/155] (618s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[404.495879] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[408.476524] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (3.985s)
[409.687718] [116/155] (613s left) prime_mmap_coherency (write)
[409.740756] Starting subtest: write
[415.120737] Subtest write: SUCCESS (5.378s)
[415.274033] [117/155] (607s left) kms_chamelium_audio (dp-audio)
[415.657488] Subtest dp-audio: SKIP (0.000s)
[416.482996] [118/155] (606s left) kms_flip (basic-flip-vs-wf_vblank)
[416.580384] Starting subtest: basic-flip-vs-wf_vblank
[416.582761] Starting dynamic subtest: A-eDP1
[418.550117] Dynamic subtest A-eDP1: SUCCESS (1.971s)
[418.560732] Starting dynamic subtest: B-eDP1
[420.476998] Dynamic subtest B-eDP1: SUCCESS (1.925s)
[420.489654] Starting dynamic subtest: C-eDP1
[422.395407] Dynamic subtest C-eDP1: SUCCESS (1.917s)
[422.407919] Starting dynamic subtest: D-eDP1
[424.323515] Dynamic subtest D-eDP1: SUCCESS (1.926s)
[424.325943] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.748s)
[425.546134] [119/155] (597s left) gem_ctx_shared (disjoint-timelines)
[425.608045] Starting subtest: disjoint-timelines
[425.638259] Subtest disjoint-timelines: SUCCESS (0.030s)
[425.885970] [120/155] (597s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[425.978994] Starting subtest: bad-rotation-90-yf-tiled-ccs
[425.981318] Starting dynamic subtest: pipe-A-eDP-1
[426.003670] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[426.015051] Starting dynamic subtest: pipe-B-eDP-1
[426.036227] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[426.046021] Starting dynamic subtest: pipe-C-eDP-1
[426.067926] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[426.077747] Starting dynamic subtest: pipe-D-eDP-1
[426.100061] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[426.104097] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[428.310537] [121/155] (594s left) kms_addfb_basic (legacy-format)
[428.363491] Starting subtest: legacy-format
[428.387670] Subtest legacy-format: SUCCESS (0.024s)
[428.539724] [122/155] (594s left) kms_psr (pr-basic)
[428.642152] Starting subtest: pr-basic
[428.645916] Subtest pr-basic: SKIP (0.000s)
[428.721390] [123/155] (594s left) perf_pmu (busy)
[428.767389] Starting subtest: busy
[428.769579] Starting dynamic subtest: rcs0
[429.373204] Dynamic subtest rcs0: SUCCESS (0.604s)
[429.387439] Starting dynamic subtest: bcs0
[429.936265] Dynamic subtest bcs0: SUCCESS (0.561s)
[429.950820] Starting dynamic subtest: vcs0
[431.288894] Dynamic subtest vcs0: SUCCESS (1.350s)
[431.302040] Starting dynamic subtest: vcs1
[433.275840] Dynamic subtest vcs1: SUCCESS (1.982s)
[433.287036] Starting dynamic subtest: vecs0
[435.322423] Dynamic subtest vecs0: SUCCESS (2.045s)
[435.333706] Starting dynamic subtest: ccs0
[435.894094] Dynamic subtest ccs0: SUCCESS (0.570s)
[435.897041] Subtest busy: SUCCESS (7.127s)
[436.017352] [124/155] (587s left) kms_properties (invalid-properties-legacy)
[436.116708] Starting subtest: invalid-properties-legacy
[436.217808] Subtest invalid-properties-legacy: SUCCESS (0.101s)
[436.290463] [125/155] (587s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[436.385192] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[436.389530] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[436.488284] [126/155] (587s left) syncobj_wait (reset-signaled)
[436.535029] Starting subtest: reset-signaled
[436.537343] Subtest reset-signaled: SUCCESS (0.000s)
[436.636898] [127/155] (586s left) kms_addfb_basic (invalid-get-prop)
[436.689497] Starting subtest: invalid-get-prop
[436.691922] Subtest invalid-get-prop: SUCCESS (0.000s)
[436.827187] [128/155] (586s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[436.915807] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[436.920147] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[436.996084] [129/155] (586s left) kms_frontbuffer_tracking (basic)
[437.092737] Starting subtest: basic
[440.311165] Subtest basic: SUCCESS (3.224s)
[440.450569] [130/155] (583s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[440.541869] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[444.617201] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.078s)
[445.830335] [131/155] (577s left) gem_ctx_persistence (process)
[445.900676] Starting subtest: process
[446.103745] Subtest process: SUCCESS (0.203s)
[446.186760] [132/155] (577s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[446.276606] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc
[446.280872] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[446.365591] [133/155] (577s left) kms_flip (2x-plain-flip)
[446.462368] Starting subtest: 2x-plain-flip
[446.470657] Subtest 2x-plain-flip: SKIP (0.010s)
[446.552777] [134/155] (577s left) prime_vgem (fence-write-hang)
[446.619644] Subtest fence-write-hang: SKIP (0.000s)
[446.717157] [135/155] (577s left) kms_lease (empty-lease)
[446.798402] Starting subtest: empty-lease
[446.800364] Starting dynamic subtest: pipe-A-eDP-1
[446.802370] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[446.813094] Starting dynamic subtest: pipe-B-eDP-1
[446.814662] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[446.824312] Starting dynamic subtest: pipe-C-eDP-1
[446.825883] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[446.835072] Starting dynamic subtest: pipe-D-eDP-1
[446.836674] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[446.838251] Subtest empty-lease: SUCCESS (0.008s)
[446.920334] [136/155] (577s left) kms_busy (extended-pageflip-hang-newfb)
[447.014551] Starting subtest: extended-pageflip-hang-newfb
[447.016495] Starting dynamic subtest: pipe-A
[473.100247] Dynamic subtest pipe-A: SUCCESS (26.084s)
[473.116326] Starting dynamic subtest: pipe-D
[497.036218] Dynamic subtest pipe-D: SUCCESS (23.934s)
[497.038696] Subtest extended-pageflip-hang-newfb: SUCCESS (50.022s)
[497.857218] [137/155] (526s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-cpu)
[497.964729] Starting subtest: hdr-rgb101010-draw-mmap-cpu
[497.969323] Subtest hdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[498.082405] [138/155] (525s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-pwrite)
[498.179081] Starting subtest: fbcpsrhdr-rgb565-draw-pwrite
[498.183258] Subtest fbcpsrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[498.281959] [139/155] (525s left) vgem_basic (mmap)
[498.324383] Starting subtest: mmap
[498.340061] Subtest mmap: SUCCESS (0.017s)
[498.392349] [140/155] (525s left) kms_color (ctm-0-75)
[498.485136] Starting subtest: ctm-0-75
[498.487225] Starting dynamic subtest: pipe-A-eDP-1
[503.496666] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.010s)
[503.507815] Starting dynamic subtest: pipe-B-eDP-1
[509.142784] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.648s)
[509.153384] Starting dynamic subtest: pipe-C-eDP-1
[514.527648] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.384s)
[514.540409] Starting dynamic subtest: pipe-D-eDP-1
[519.839660] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.311s)
[519.842105] Subtest ctm-0-75: SUCCESS (21.357s)
[520.680441] [141/155] (503s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[520.776057] Starting subtest: fbc-psr2-sprite-mmap-cpu
[520.778323] Starting dynamic subtest: eDP-1
[520.782518] Dynamic subtest eDP-1: SKIP (0.000s)
[520.786826] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[520.848552] [142/155] (503s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[520.950384] Starting subtest: 4-tiled-32bpp-rotate-270
[520.971323] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.021s)
[521.094058] [143/155] (503s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[521.149795] Starting subtest: wait-all-for-submit-delayed-submit
[521.254240] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[521.342550] [144/155] (502s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[521.443074] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[521.447118] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[521.557735] [145/155] (502s left) kms_vblank (accuracy-idle)
[521.643350] Starting subtest: accuracy-idle
[521.645675] Starting dynamic subtest: pipe-A-eDP-1
[523.413465] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.765s)
[523.429102] Starting dynamic subtest: pipe-D-eDP-1
[525.828324] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.414s)
[525.830766] Subtest accuracy-idle: SUCCESS (4.183s)
[526.653523] [146/155] (497s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[526.749702] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[526.754146] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[526.874305] [147/155] (497s left) kms_cursor_crc (cursor-random-128x42)
[527.102336] Starting subtest: cursor-random-128x42
[527.147694] Subtest cursor-random-128x42: SKIP (0.041s)
[527.362573] [148/155] (497s left) kms_vrr (lobf)
[527.457980] Starting subtest: lobf
[527.461950] Subtest lobf: SKIP (0.000s)
[527.534289] [149/155] (496s left) gem_reg_read (timestamp-moving)
[527.586451] Starting subtest: timestamp-moving
[528.589445] Subtest timestamp-moving: SUCCESS (1.001s)
[528.665895] [150/155] (495s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[528.784338] Starting subtest: flip-vs-cursor-crc-legacy
[529.444369] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.658s)
[530.294502] [151/155] (494s left) syncobj_basic (bad-create-flags)
[530.356139] Starting subtest: bad-create-flags
[530.358413] Subtest bad-create-flags: SUCCESS (0.000s)
[530.459616] [152/155] (494s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[530.515945] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[530.518193] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[530.607678] [153/155] (494s left) gem_busy (parallel)
[530.793985] Starting subtest: parallel
[530.796552] Starting dynamic subtest: rcs0
[532.283552] Dynamic subtest rcs0: SUCCESS (1.486s)
[532.296913] Starting dynamic subtest: bcs0
[534.268053] Dynamic subtest bcs0: SUCCESS (1.981s)
[534.281686] Starting dynamic subtest: vcs0
[536.313816] Dynamic subtest vcs0: SUCCESS (2.042s)
[536.326794] Starting dynamic subtest: vcs1
[538.298803] Dynamic subtest vcs1: SUCCESS (1.983s)
[538.313635] Starting dynamic subtest: vecs0
[540.282831] Dynamic subtest vecs0: SUCCESS (1.981s)
[540.296217] Starting dynamic subtest: ccs0
[542.267017] Dynamic subtest ccs0: SUCCESS (1.981s)
[542.269756] Subtest parallel: SUCCESS (11.472s)
[542.516662] [154/155] (482s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[542.628788] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[549.911696] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.287s)
[550.036618] [155/155] (474s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-blt)
[550.132632] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-blt
[550.137012] Subtest fbchdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[550.232803] 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.