[58.674350] Initializing watchdogs
[58.674403]   /dev/watchdog0
[58.688728] [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]
[58.693422] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.695811] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.699220] [001/132] (960s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-mc-ccs)
[58.836922] Starting subtest: crc-primary-suspend-y-tiled-gen12-mc-ccs
[58.839267] Starting dynamic subtest: pipe-A-eDP-1
[58.848325] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[58.856208] Starting dynamic subtest: pipe-B-eDP-1
[58.865327] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[58.873445] Starting dynamic subtest: pipe-C-eDP-1
[58.882395] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[58.889470] Starting dynamic subtest: pipe-D-eDP-1
[58.897922] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[58.902034] Subtest crc-primary-suspend-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[61.092236] [002/132] (958s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[61.181698] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[61.183955] Starting dynamic subtest: pipe-A-eDP-1
[61.206830] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[61.215491] Starting dynamic subtest: pipe-B-eDP-1
[61.236957] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[61.243416] Starting dynamic subtest: pipe-C-eDP-1
[61.264785] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[61.271345] Starting dynamic subtest: pipe-D-eDP-1
[61.293432] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[61.297826] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.003s)
[63.372111] [003/132] (955s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[63.434993] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[63.437399] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[63.555371] [004/132] (955s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[63.649813] Starting subtest: y-tiled-64bpp-rotate-180
[63.654674] Subtest y-tiled-64bpp-rotate-180: SKIP (0.001s)
[63.743719] [005/132] (955s left) syncobj_wait (invalid-reset-zero-handles)
[63.808285] Starting subtest: invalid-reset-zero-handles
[63.810595] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[63.916714] [006/132] (955s left) i915_drm_fdinfo (most-busy-idle-check-all)
[63.956924] Starting subtest: most-busy-idle-check-all
[63.959261] Starting dynamic subtest: rcs0
[63.963926] Dynamic subtest rcs0: SKIP (0.004s)
[63.973367] Starting dynamic subtest: bcs0
[63.977696] Dynamic subtest bcs0: SKIP (0.000s)
[63.986519] Starting dynamic subtest: vcs0
[63.990478] Dynamic subtest vcs0: SKIP (0.000s)
[63.996975] Starting dynamic subtest: vcs1
[64.000892] Dynamic subtest vcs1: SKIP (0.000s)
[64.007370] Starting dynamic subtest: vecs0
[64.011366] Dynamic subtest vecs0: SKIP (0.000s)
[64.017907] Starting dynamic subtest: ccs0
[64.021983] Dynamic subtest ccs0: SKIP (0.000s)
[64.026035] Subtest most-busy-idle-check-all: SKIP (0.007s)
[64.059238] [007/132] (955s left) gem_mmap (basic-small-bo)
[64.162401] Subtest basic-small-bo: SKIP (0.000s)
[64.221698] [008/132] (955s left) gem_exec_fence (basic-await)
[64.406032] Starting subtest: basic-await
[64.408732] Starting dynamic subtest: rcs0
[64.482744] Dynamic subtest rcs0: SUCCESS (0.076s)
[64.494764] Starting dynamic subtest: bcs0
[64.555366] Dynamic subtest bcs0: SUCCESS (0.072s)
[64.565260] Starting dynamic subtest: vcs0
[64.628436] Dynamic subtest vcs0: SUCCESS (0.072s)
[64.639033] Starting dynamic subtest: vcs1
[64.701413] Dynamic subtest vcs1: SUCCESS (0.073s)
[64.711458] Starting dynamic subtest: vecs0
[64.775061] Dynamic subtest vecs0: SUCCESS (0.072s)
[64.786116] Starting dynamic subtest: ccs0
[64.849344] Dynamic subtest ccs0: SUCCESS (0.073s)
[64.852215] Subtest basic-await: SUCCESS (0.443s)
[66.975833] [FACT gem_exec_fence (basic-await)] new: kernel.kmod_is_loaded.vgem: true
[66.979222] [009/132] (952s left) gem_pxp (hw-rejects-pxp-buffer)
[67.205047] Starting subtest: hw-rejects-pxp-buffer
[67.209974] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[67.567724] [010/132] (952s left) kms_hdr (bpc-switch-dpms)
[67.727788] Starting subtest: bpc-switch-dpms
[68.858337] Starting dynamic subtest: pipe-A-eDP-1
[73.393642] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.535s)
[73.396182] Subtest bpc-switch-dpms: SUCCESS (5.665s)
[73.520611] [011/132] (946s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[73.616186] Starting subtest: fbc-rgb565-draw-mmap-wc
[77.673448] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.059s)
[78.852652] [012/132] (940s left) perf (non-zero-reason)
[78.981100] Starting subtest: non-zero-reason
[78.983440] Starting dynamic subtest: 0-rcs0
[84.395783] Dynamic subtest 0-rcs0: SUCCESS (5.413s)
[84.406229] Starting dynamic subtest: 1-vcs1
[88.305227] Dynamic subtest 1-vcs1: SUCCESS (3.908s)
[88.308210] Subtest non-zero-reason: SUCCESS (9.322s)
[88.351679] [013/132] (931s left) kms_async_flips (crc-atomic)
[88.457801] Starting subtest: crc-atomic
[88.832736] Starting dynamic subtest: pipe-A-eDP-1
[91.769567] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.938s)
[92.181959] Starting dynamic subtest: pipe-B-eDP-1
[95.105290] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.931s)
[95.502555] Starting dynamic subtest: pipe-C-eDP-1
[98.419195] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.930s)
[98.794608] Starting dynamic subtest: pipe-D-eDP-1
[101.652563] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.868s)
[101.654810] Subtest crc-atomic: SUCCESS (13.194s)
[102.825495] [014/132] (916s left) gem_exec_balancer (nop)
[102.886421] Starting subtest: nop
[149.513129] Subtest nop: SUCCESS (46.624s)
[149.806497] [015/132] (869s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[149.914771] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[149.918820] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[150.008731] [016/132] (869s left) syncobj_timeline (wait-all-interrupted)
[150.072925] Starting subtest: wait-all-interrupted
[150.177758] Subtest wait-all-interrupted: SUCCESS (0.102s)
[150.250543] [017/132] (869s left) kms_invalid_mode (bad-htotal)
[150.362629] Starting subtest: bad-htotal
[150.364956] Starting dynamic subtest: pipe-A-eDP-1
[150.651154] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[150.659506] Starting dynamic subtest: pipe-B-eDP-1
[150.661380] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[150.668754] Starting dynamic subtest: pipe-C-eDP-1
[150.670640] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[150.678491] Starting dynamic subtest: pipe-D-eDP-1
[150.680643] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[150.682779] Subtest bad-htotal: SUCCESS (0.294s)
[151.446199] [018/132] (868s left) kms_big_fb (linear-8bpp-rotate-0)
[151.557574] Starting subtest: linear-8bpp-rotate-0
[152.826275] Subtest linear-8bpp-rotate-0: SUCCESS (1.270s)
[152.959960] [019/132] (866s left) kms_cursor_edge_walk (128x128-top-bottom)
[153.052356] Starting subtest: 128x128-top-bottom
[153.054641] Starting dynamic subtest: pipe-A-eDP-1
[156.512045] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.460s)
[156.521560] Starting dynamic subtest: pipe-D-eDP-1
[159.622633] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.110s)
[159.624976] Subtest 128x128-top-bottom: SUCCESS (6.572s)
[160.450720] [020/132] (859s left) api_intel_bb (render)
[160.509074] Starting subtest: render
[160.511050] Starting dynamic subtest: render-none-512
[160.607558] Dynamic subtest render-none-512: SUCCESS (0.099s)
[160.618057] Starting dynamic subtest: render-none-1024
[161.245098] Dynamic subtest render-none-1024: SUCCESS (0.635s)
[161.255821] Starting dynamic subtest: render-x-512
[161.329975] Dynamic subtest render-x-512: SUCCESS (0.086s)
[161.340619] Starting dynamic subtest: render-x-1024
[161.677416] Dynamic subtest render-x-1024: SUCCESS (0.345s)
[161.687731] Starting dynamic subtest: render-y-512
[161.763896] Dynamic subtest render-y-512: SUCCESS (0.087s)
[161.773877] Starting dynamic subtest: render-y-1024
[162.111845] Dynamic subtest render-y-1024: SUCCESS (0.346s)
[162.114724] Subtest render: SUCCESS (1.603s)
[162.243166] [021/132] (857s left) kms_cursor_legacy (torture-bo)
[162.395935] Starting subtest: torture-bo
[162.398322] Starting dynamic subtest: pipe-A
[168.206656] Dynamic subtest pipe-A: SUCCESS (5.808s)
[168.220834] Starting dynamic subtest: pipe-B
[174.840657] Dynamic subtest pipe-B: SUCCESS (6.634s)
[174.849054] Starting dynamic subtest: pipe-C
[181.741916] Dynamic subtest pipe-C: SUCCESS (6.901s)
[181.753711] Starting dynamic subtest: pipe-D
[187.997692] Dynamic subtest pipe-D: SUCCESS (6.255s)
[188.005839] Starting dynamic subtest: all-pipes
[194.167198] Dynamic subtest all-pipes: SUCCESS (6.169s)
[194.169562] Subtest torture-bo: SUCCESS (31.770s)
[194.319732] [022/132] (825s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[194.433957] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[194.438330] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[194.539154] [023/132] (825s left) kms_plane_lowres (tiling-y)
[194.636199] Starting subtest: tiling-y
[194.640188] Subtest tiling-y: SKIP (0.000s)
[194.706248] [024/132] (825s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[194.745624] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[194.795233] [025/132] (825s left) kms_sequence (get-busy)
[194.884928] Starting subtest: get-busy
[194.887272] Starting dynamic subtest: pipe-A-eDP-1
[196.825792] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.939s)
[196.837286] Starting dynamic subtest: pipe-B-eDP-1
[199.438819] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.611s)
[199.448042] Starting dynamic subtest: pipe-C-eDP-1
[202.067147] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.628s)
[202.076563] Starting dynamic subtest: pipe-D-eDP-1
[204.708433] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.640s)
[204.710677] Subtest get-busy: SUCCESS (9.823s)
[205.515957] [026/132] (814s left) kms_flip (2x-blocking-absolute-wf_vblank)
[205.626318] Starting subtest: 2x-blocking-absolute-wf_vblank
[205.635514] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.011s)
[205.752744] [027/132] (814s left) intel_hwmon (hwmon-write)
[205.810572] Subtest hwmon-write: SKIP (0.000s)
[205.879503] [028/132] (814s left) gem_eio (wait-wedge-10ms)
[206.125303] Starting subtest: wait-wedge-10ms
[207.966184] Subtest wait-wedge-10ms: SUCCESS (1.841s)
[208.093882] [029/132] (812s left) kms_flip (2x-busy-flip)
[208.189032] Starting subtest: 2x-busy-flip
[208.198168] Subtest 2x-busy-flip: SKIP (0.011s)
[208.273130] [030/132] (812s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[208.376855] Starting subtest: pr-cursor-plane-move-continuous-sf
[208.381459] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[208.454317] [031/132] (811s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[208.558766] Starting subtest: fbc-rgb101010-draw-blt
[213.116786] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.562s)
[214.324997] [032/132] (806s left) kms_psr (pr-sprite-mmap-gtt)
[214.426252] Starting subtest: pr-sprite-mmap-gtt
[214.430858] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[214.517038] [033/132] (806s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[214.621922] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[214.624173] Starting dynamic subtest: pipe-A-eDP-1
[214.705797] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[214.717176] Starting dynamic subtest: pipe-B-eDP-1
[215.876624] Dynamic subtest pipe-B-eDP-1: SKIP (1.170s)
[215.881218] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.260s)
[217.058953] [034/132] (803s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[217.157882] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[217.162287] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[217.247742] [035/132] (803s left) kms_chamelium_edid (dp-edid-read)
[217.636940] Subtest dp-edid-read: SKIP (0.000s)
[218.481928] [036/132] (802s left) kms_rotation_crc (sprite-rotation-180)
[218.571023] Starting subtest: sprite-rotation-180
[225.416232] Subtest sprite-rotation-180: SUCCESS (6.848s)
[226.588985] [037/132] (794s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[226.697310] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[227.841843] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.146s)
[227.953647] [038/132] (792s left) gem_ctx_shared (disjoint-timelines)
[228.017948] Starting subtest: disjoint-timelines
[228.041708] Subtest disjoint-timelines: SUCCESS (0.026s)
[228.277232] [039/132] (792s left) gem_sync (basic-each)
[228.361350] Starting subtest: basic-each
[230.874524] Subtest basic-each: SUCCESS (2.524s)
[231.062682] [040/132] (789s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[231.161606] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[231.165891] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[231.251210] [041/132] (789s left) gem_sync (basic-many-each)
[231.326681] Starting subtest: basic-many-each
[233.603122] Subtest basic-many-each: SUCCESS (2.287s)
[233.786067] [042/132] (787s left) gem_exec_schedule (u-semaphore-user)
[233.846283] Starting subtest: u-semaphore-user
[233.899369] Subtest u-semaphore-user: SUCCESS (0.061s)
[235.965982] [043/132] (784s left) gem_softpin (evict-snoop)
[236.016559] Starting subtest: evict-snoop
[236.020984] Subtest evict-snoop: SKIP (0.000s)
[236.163008] [044/132] (784s left) gen9_exec_parse (bb-start-far)
[236.231565] Subtest bb-start-far: SKIP (0.000s)
[236.298773] [045/132] (784s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[236.398884] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[236.402984] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[236.493747] [046/132] (784s left) perf_pmu (event-wait)
[236.535624] Starting subtest: event-wait
[236.538052] Starting dynamic subtest: rcs0
[236.542618] Dynamic subtest rcs0: SKIP (0.000s)
[236.546821] Subtest event-wait: SKIP (0.000s)
[236.667795] [047/132] (784s left) gem_mmap_offset (perf)
[236.715048] Starting subtest: perf
[236.716913] Starting dynamic subtest: smem0
[237.082863] Dynamic subtest smem0: SUCCESS (0.367s)
[237.085330] Subtest perf: SUCCESS (0.367s)
[237.189818] [048/132] (783s left) kms_cursor_crc (cursor-onscreen-64x21)
[237.384305] Starting subtest: cursor-onscreen-64x21
[237.428584] Subtest cursor-onscreen-64x21: SKIP (0.042s)
[237.654819] [049/132] (783s left) gem_mmap_gtt (basic-write-read-distinct)
[237.707919] Subtest basic-write-read-distinct: SKIP (0.000s)
[237.762652] [050/132] (783s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[237.860308] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[237.864642] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[237.961047] [051/132] (783s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[238.066083] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[238.067969] Starting dynamic subtest: pipe-A
[238.087837] Dynamic subtest pipe-A: SKIP (0.018s)
[238.096581] Starting dynamic subtest: pipe-B
[238.120998] Dynamic subtest pipe-B: SKIP (0.032s)
[238.129317] Starting dynamic subtest: pipe-C
[238.153032] Dynamic subtest pipe-C: SKIP (0.032s)
[238.161085] Starting dynamic subtest: pipe-D
[238.182770] Dynamic subtest pipe-D: SKIP (0.031s)
[238.186153] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.115s)
[238.294569] [052/132] (783s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[238.396055] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[238.399518] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[238.494857] [053/132] (782s left) i915_pm_rps (thresholds-idle-park)
[238.545674] Starting subtest: thresholds-idle-park
[238.549649] Subtest thresholds-idle-park: SKIP (0.001s)
[238.662612] [054/132] (782s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[238.722936] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[238.724975] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[238.822759] [055/132] (782s left) kms_chamelium_color (ctm-0-75)
[238.914651] Subtest ctm-0-75: SKIP (0.000s)
[238.964092] [056/132] (782s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[239.049532] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[239.053623] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[239.120185] [057/132] (782s left) kms_psr (fbc-psr-no-drrs)
[239.210889] Starting subtest: fbc-psr-no-drrs
[239.212963] Starting dynamic subtest: eDP-1
[239.216674] Dynamic subtest eDP-1: SKIP (0.000s)
[239.220324] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[239.281310] [058/132] (782s left) prime_mmap_coherency (write)
[239.330092] Starting subtest: write
[244.709376] Subtest write: SUCCESS (5.378s)
[244.841276] [059/132] (776s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[244.937546] Starting subtest: plane-lut1d-ctm3x4
[245.083331] Starting dynamic subtest: pipe-A-plane-0
[245.240643] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[245.249706] Starting dynamic subtest: pipe-A-plane-1
[245.394838] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[245.404668] Starting dynamic subtest: pipe-A-plane-2
[245.539188] Dynamic subtest pipe-A-plane-2: SUCCESS (0.144s)
[246.680229] Starting dynamic subtest: pipe-B-plane-0
[246.839111] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[246.848562] Starting dynamic subtest: pipe-B-plane-1
[247.028369] Dynamic subtest pipe-B-plane-1: SUCCESS (0.188s)
[247.037528] Starting dynamic subtest: pipe-B-plane-2
[247.195055] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[248.437216] Starting dynamic subtest: pipe-C-plane-0
[248.562609] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[248.571782] Starting dynamic subtest: pipe-C-plane-1
[248.707299] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[248.716385] Starting dynamic subtest: pipe-C-plane-2
[248.862991] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[250.053162] Starting dynamic subtest: pipe-D-plane-0
[250.211621] Dynamic subtest pipe-D-plane-0: SUCCESS (0.166s)
[250.220412] Starting dynamic subtest: pipe-D-plane-1
[250.355937] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[250.364718] Starting dynamic subtest: pipe-D-plane-2
[250.511485] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[250.802051] Subtest plane-lut1d-ctm3x4: SUCCESS (5.866s)
[251.623223] [060/132] (770s left) gem_vm_create (invalid-destroy)
[251.679175] Starting subtest: invalid-destroy
[251.684435] Subtest invalid-destroy: SUCCESS (0.003s)
[251.806229] [061/132] (769s left) api_intel_allocator (reloc-allocator)
[251.857127] Starting subtest: reloc-allocator
[251.859608] Starting dynamic subtest: basic
[251.861965] Dynamic subtest basic: SUCCESS (0.003s)
[251.873324] Starting dynamic subtest: parallel-one
[251.875543] Dynamic subtest parallel-one: SUCCESS (0.007s)
[251.884085] Starting dynamic subtest: print
[251.886541] Dynamic subtest print: SUCCESS (0.000s)
[251.894826] Starting dynamic subtest: reuse
[251.896954] Dynamic subtest reuse: SUCCESS (0.000s)
[251.905184] Starting dynamic subtest: fork-reopen-allocator
[251.907321] Dynamic subtest fork-reopen-allocator: SUCCESS (0.011s)
[251.909464] Subtest reloc-allocator: SUCCESS (0.026s)
[252.005803] [062/132] (769s left) perf (disabled-read-error)
[252.109370] Starting subtest: disabled-read-error
[252.122930] Subtest disabled-read-error: SUCCESS (0.016s)
[252.171092] [063/132] (769s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[252.268301] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[253.316532] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.047s)
[253.447850] [064/132] (768s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[253.536528] Starting subtest: primary-y-tiled-reflect-x-0
[253.596330] Subtest primary-y-tiled-reflect-x-0: SKIP (0.060s)
[253.698688] [065/132] (768s left) gem_exec_fence (syncobj-channel)
[254.156501] Starting subtest: syncobj-channel
[255.175314] Subtest syncobj-channel: SUCCESS (1.015s)
[255.967488] [066/132] (765s left) gem_mmap_wc (pf-nonblock)
[256.031104] Subtest pf-nonblock: SKIP (0.000s)
[256.109156] [067/132] (765s left) gem_mmap_wc (coherency)
[256.170221] Subtest coherency: SKIP (0.000s)
[256.241261] [068/132] (765s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[256.335068] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[256.339070] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[256.432385] [069/132] (765s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[256.535972] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[256.540410] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[256.636640] [070/132] (765s left) gem_exec_schedule (preempt-other-chain)
[256.701380] Starting subtest: preempt-other-chain
[256.703556] Starting dynamic subtest: rcs0
[256.781648] Dynamic subtest rcs0: SUCCESS (0.087s)
[256.792761] Starting dynamic subtest: bcs0
[256.861896] Dynamic subtest bcs0: SUCCESS (0.079s)
[256.874344] Starting dynamic subtest: vcs0
[256.949054] Dynamic subtest vcs0: SUCCESS (0.087s)
[256.958790] Starting dynamic subtest: vcs1
[257.036095] Dynamic subtest vcs1: SUCCESS (0.086s)
[257.045923] Starting dynamic subtest: vecs0
[257.121632] Dynamic subtest vecs0: SUCCESS (0.085s)
[257.132321] Starting dynamic subtest: ccs0
[257.204801] Dynamic subtest ccs0: SUCCESS (0.082s)
[257.207292] Subtest preempt-other-chain: SUCCESS (0.511s)
[257.975211] [071/132] (764s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[258.087641] Starting subtest: cursorA-vs-flipA-legacy
[269.209320] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.120s)
[270.051444] [072/132] (752s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[270.169562] Starting subtest: basic-flip-after-cursor-varying-size
[271.102882] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.932s)
[271.925945] [073/132] (750s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[272.023674] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[272.027839] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[272.096748] [074/132] (750s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[272.197351] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[272.201597] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[272.276729] [075/132] (749s left) gem_exec_whisper (basic-normal-all)
[272.356550] Starting subtest: basic-normal-all
[274.747438] Subtest basic-normal-all: SUCCESS (2.388s)
[274.927083] [076/132] (747s left) kms_busy (extended-pageflip-hang-oldfb)
[275.030849] Starting subtest: extended-pageflip-hang-oldfb
[275.033752] Starting dynamic subtest: pipe-A
[275.439233] Dynamic subtest pipe-A: SUCCESS (0.405s)
[275.448386] Starting dynamic subtest: pipe-D
[276.532064] Dynamic subtest pipe-D: SUCCESS (1.091s)
[276.534459] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.502s)
[277.366103] [077/132] (744s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[277.466787] Starting subtest: stress-xrgb8888-ytiled
[277.471256] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[277.543624] [078/132] (744s left) kms_frontbuffer_tracking (basic)
[277.635616] Starting subtest: basic
[280.967066] Subtest basic: SUCCESS (3.335s)
[281.084974] [079/132] (741s left) gem_exec_fence (syncobj-unused-fence)
[281.526262] Starting subtest: syncobj-unused-fence
[281.532266] Subtest syncobj-unused-fence: SUCCESS (0.006s)
[282.986969] [080/132] (739s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[283.129298] Starting subtest: flip-vs-cursor-atomic-transitions
[291.123230] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (7.995s)
[291.999742] [081/132] (730s left) gem_spin_batch (legacy-resubmit)
[292.065064] Starting subtest: legacy-resubmit
[292.067434] Starting dynamic subtest: default
[292.073587] Dynamic subtest default: SUCCESS (0.007s)
[292.084303] Starting dynamic subtest: render
[292.086647] Dynamic subtest render: SUCCESS (0.001s)
[292.104747] Starting dynamic subtest: bsd
[292.107205] Dynamic subtest bsd: SUCCESS (0.003s)
[292.125116] Starting dynamic subtest: bsd1
[292.127580] Dynamic subtest bsd1: SUCCESS (0.003s)
[292.145374] Starting dynamic subtest: bsd2
[292.147860] Dynamic subtest bsd2: SUCCESS (0.001s)
[292.167190] Starting dynamic subtest: blt
[292.169616] Dynamic subtest blt: SUCCESS (0.002s)
[292.178777] Starting dynamic subtest: vebox
[292.180974] Dynamic subtest vebox: SUCCESS (0.003s)
[292.183210] Subtest legacy-resubmit: SUCCESS (0.026s)
[292.358027] [082/132] (730s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[292.462366] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[292.466349] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[292.548511] [083/132] (730s left) kms_prop_blob (basic)
[292.613676] Starting subtest: basic
[292.615960] Subtest basic: SUCCESS (0.000s)
[292.710594] [084/132] (729s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[292.806981] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[292.809203] Starting dynamic subtest: pipe-A-eDP-1
[292.960684] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.145s)
[292.971560] Starting dynamic subtest: pipe-B-eDP-1
[292.988406] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[292.999068] Starting dynamic subtest: pipe-C-eDP-1
[293.015964] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[293.024580] Starting dynamic subtest: pipe-D-eDP-1
[293.040525] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[293.042333] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.181s)
[295.269504] [085/132] (727s left) syncobj_timeline (invalid-query-illegal-handle)
[295.333949] Starting subtest: invalid-query-illegal-handle
[295.336242] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[295.428051] [086/132] (727s left) gem_mmap_gtt (cpuset-big-copy-xy)
[295.488083] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[295.554502] [087/132] (727s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[295.655390] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[295.659455] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[295.745249] [088/132] (727s left) gem_exec_params (secure-non-root)
[295.801809] Starting subtest: secure-non-root
[295.806284] Subtest secure-non-root: SKIP (0.000s)
[295.899071] [089/132] (726s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[295.954390] Starting subtest: etime-multi-wait-for-submit-submitted
[296.057352] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.103s)
[296.134944] [090/132] (726s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[296.230044] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[303.096218] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.869s)
[303.220290] [091/132] (719s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[303.333723] Starting subtest: psr-2p-primscrn-cur-indfb-move
[303.337788] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[303.427176] [092/132] (719s left) syncobj_basic (bad-flags-fd-to-handle)
[303.477077] Starting subtest: bad-flags-fd-to-handle
[303.479419] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[303.574801] [093/132] (719s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[303.671639] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[303.675738] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[303.781027] [094/132] (719s left) kms_plane (plane-panning-bottom-right)
[303.886246] Starting subtest: plane-panning-bottom-right
[303.888569] Starting dynamic subtest: pipe-A
[305.551631] Dynamic subtest pipe-A: SUCCESS (1.665s)
[305.561628] Starting dynamic subtest: pipe-B
[307.942213] Dynamic subtest pipe-B: SUCCESS (2.390s)
[307.944489] Subtest plane-panning-bottom-right: SUCCESS (4.057s)
[308.746708] [095/132] (714s left) gem_render_linear_blits (basic)
[308.811437] Starting subtest: basic
[308.835504] Subtest basic: SUCCESS (0.024s)
[308.923264] [096/132] (714s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[309.013956] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[309.025977] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[309.105739] [097/132] (714s left) i915_query (query-topology-coherent-slice-mask)
[309.167720] Starting subtest: query-topology-coherent-slice-mask
[309.172272] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[309.277049] [098/132] (713s left) gem_create (create-massive)
[309.339683] Starting subtest: create-massive
[309.342072] Subtest create-massive: SUCCESS (0.000s)
[309.524540] [099/132] (713s left) sysfs_heartbeat_interval (precise)
[309.594637] Starting subtest: precise
[309.596870] Starting dynamic subtest: vecs0
[316.885717] Dynamic subtest vecs0: SUCCESS (7.290s)
[316.895017] Starting dynamic subtest: ccs0
[317.988022] Dynamic subtest ccs0: SUCCESS (1.100s)
[317.997400] Starting dynamic subtest: vcs0
[325.845147] Dynamic subtest vcs0: SUCCESS (7.857s)
[325.856625] Starting dynamic subtest: bcs0
[326.980720] Dynamic subtest bcs0: SUCCESS (1.131s)
[326.991619] Starting dynamic subtest: rcs0
[328.092828] Dynamic subtest rcs0: SUCCESS (1.112s)
[328.104516] Starting dynamic subtest: vcs1
[335.885761] Dynamic subtest vcs1: SUCCESS (7.787s)
[335.888342] Subtest precise: SUCCESS (26.287s)
[335.959725] [100/132] (687s left) kms_pm_dc (dc6-dpms)
[337.082720] Starting subtest: dc6-dpms
[339.121862] Subtest dc6-dpms: SUCCESS (2.039s)
[339.223098] [101/132] (684s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[339.319086] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[339.323716] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[339.420192] [102/132] (684s left) syncobj_basic (bad-destroy-pad)
[339.466186] Starting subtest: bad-destroy-pad
[339.468473] Subtest bad-destroy-pad: SUCCESS (0.000s)
[339.574741] [103/132] (683s left) syncobj_eventfd (timeline-wait-signaled)
[339.634992] Starting subtest: timeline-wait-signaled
[339.637532] Subtest timeline-wait-signaled: SUCCESS (0.000s)
[339.730773] [104/132] (683s left) gem_ctx_param (non-root-set)
[339.779947] Starting subtest: non-root-set
[339.798283] Subtest non-root-set: SUCCESS (0.018s)
[339.897157] [105/132] (683s left) prime_mmap (test_dup)
[339.951009] Starting subtest: test_dup
[339.953227] Starting dynamic subtest: test_dup-smem
[339.982486] Dynamic subtest test_dup-smem: SUCCESS (0.033s)
[339.984901] Subtest test_dup: SUCCESS (0.034s)
[340.057093] [106/132] (683s left) kms_psr (psr-sprite-blt)
[340.142291] Starting subtest: psr-sprite-blt
[340.144152] Starting dynamic subtest: eDP-1
[341.893263] Dynamic subtest eDP-1: SUCCESS (1.749s)
[341.895662] Subtest psr-sprite-blt: SUCCESS (1.750s)
[342.735732] [107/132] (680s left) kms_dsc (dsc-basic)
[342.839536] Starting subtest: dsc-basic
[342.853088] Subtest dsc-basic: SKIP (0.000s)
[342.914057] [108/132] (680s left) i915_pm_rpm (gem-mmap-type)
[345.046739] Starting subtest: gem-mmap-type
[345.049557] Starting dynamic subtest: wb-smem0
[348.838676] Dynamic subtest wb-smem0: SUCCESS (3.790s)
[348.847753] Starting dynamic subtest: wc-smem0
[352.008560] Dynamic subtest wc-smem0: SUCCESS (3.168s)
[352.018248] Starting dynamic subtest: uc-smem0
[354.878879] Dynamic subtest uc-smem0: SUCCESS (2.870s)
[354.881237] Subtest gem-mmap-type: SUCCESS (9.832s)
[354.964581] [109/132] (668s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[355.033670] Starting subtest: y-tiled-to-vebox-x-tiled
[355.038149] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[355.220049] [110/132] (668s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[355.318073] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[355.320473] Starting dynamic subtest: pipe-A-eDP-1
[355.380940] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[355.391984] Starting dynamic subtest: pipe-B-eDP-1
[355.449886] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[355.463007] Starting dynamic subtest: pipe-C-eDP-1
[355.516857] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[355.526247] Starting dynamic subtest: pipe-D-eDP-1
[355.580619] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[355.584700] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[357.596573] [111/132] (666s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[357.992127] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[358.800354] [112/132] (665s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[358.899482] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[358.901879] Starting dynamic subtest: pipe-A-eDP-1
[364.821296] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.925s)
[364.830982] Starting dynamic subtest: pipe-B-eDP-1
[371.458052] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.638s)
[371.460587] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.566s)
[372.337030] [113/132] (651s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[372.433302] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[372.437598] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[372.536467] [114/132] (651s left) kms_properties (get_properties-sanity-non-atomic)
[372.631978] Starting subtest: get_properties-sanity-non-atomic
[372.634397] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[372.709817] [115/132] (651s left) kms_addfb_basic (invalid-set-prop)
[372.757704] Starting subtest: invalid-set-prop
[372.759822] Subtest invalid-set-prop: SUCCESS (0.000s)
[372.902130] [116/132] (651s left) i915_hangman (hangcheck-unterminated)
[372.985388] Starting subtest: hangcheck-unterminated
[384.882828] Subtest hangcheck-unterminated: SUCCESS (11.897s)
[385.000998] [117/132] (639s left) kms_concurrent (multi-plane-atomic-lowres)
[385.097896] Starting subtest: multi-plane-atomic-lowres
[385.100052] Starting dynamic subtest: pipe-A-eDP-1
[385.601274] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.501s)
[385.610680] Starting dynamic subtest: pipe-B-eDP-1
[386.883783] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.281s)
[386.893250] Starting dynamic subtest: pipe-C-eDP-1
[388.088644] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.205s)
[388.099206] Starting dynamic subtest: pipe-D-eDP-1
[389.330499] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.240s)
[389.332971] Subtest multi-plane-atomic-lowres: SUCCESS (4.232s)
[390.163948] [118/132] (633s left) gem_mmap_gtt (medium-copy)
[390.221757] Subtest medium-copy: SKIP (0.000s)
[390.288555] [119/132] (633s left) gem_exec_suspend (basic-s3-devices)
[390.355525] Starting subtest: basic-S3-devices
[390.357593] Starting dynamic subtest: smem
[401.877665] Dynamic subtest smem: SUCCESS (11.520s)
[401.880487] Subtest basic-S3-devices: SUCCESS (11.522s)
[402.066745] [120/132] (622s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[402.195913] Starting subtest: flip-vs-cursor-crc-legacy
[402.858901] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.664s)
[403.708403] [121/132] (620s left) gem_exec_reloc (basic-gtt-noreloc)
[403.770978] Subtest basic-gtt-noreloc: SKIP (0.000s)
[403.838129] [122/132] (620s left) sriov_basic (bind-unbind-vf)
[403.898708] Starting subtest: bind-unbind-vf
[403.901052] Starting dynamic subtest: vf-1
[403.973188] Dynamic subtest vf-1: FAIL (0.016s)
[403.984682] Starting dynamic subtest: vf-2
[404.057881] Dynamic subtest vf-2: FAIL (0.004s)
[404.070043] Starting dynamic subtest: vf-3
[404.142489] Dynamic subtest vf-3: FAIL (0.004s)
[404.153275] Starting dynamic subtest: vf-4
[404.225381] Dynamic subtest vf-4: FAIL (0.004s)
[404.235950] Starting dynamic subtest: vf-5
[404.307926] Dynamic subtest vf-5: FAIL (0.004s)
[404.319639] Starting dynamic subtest: vf-6
[404.392453] Dynamic subtest vf-6: FAIL (0.006s)
[404.403523] Starting dynamic subtest: vf-7
[404.477308] Dynamic subtest vf-7: FAIL (0.004s)
[404.490792] Starting dynamic subtest: vf-random
[404.562850] Dynamic subtest vf-random: FAIL (0.004s)
[404.573892] Starting dynamic subtest: vf-last
[404.645852] Dynamic subtest vf-last: FAIL (0.516s)
[404.647812] Subtest bind-unbind-vf: FAIL (0.570s)
[404.685980] [123/132] (619s left) gem_ctx_shared (create-shared-gtt)
[404.758926] Starting subtest: create-shared-gtt
[406.935342] Subtest create-shared-gtt: SUCCESS (2.152s)
[407.246022] [124/132] (617s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier)
[407.349137] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier
[407.353628] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier: SKIP (0.000s)
[407.420570] [125/132] (616s left) gem_ctx_persistence (engines-mixed)
[407.486975] Starting subtest: engines-mixed
[407.489084] Starting dynamic subtest: rcs0
[407.573889] Dynamic subtest rcs0: SUCCESS (0.089s)
[407.586586] Starting dynamic subtest: bcs0
[407.642689] Dynamic subtest bcs0: SUCCESS (0.067s)
[407.656723] Starting dynamic subtest: vcs0
[407.697693] Dynamic subtest vcs0: SUCCESS (0.053s)
[407.710657] Starting dynamic subtest: vcs1
[407.741668] Dynamic subtest vcs1: SUCCESS (0.043s)
[407.752490] Starting dynamic subtest: vecs0
[407.801778] Dynamic subtest vecs0: SUCCESS (0.058s)
[407.814587] Starting dynamic subtest: ccs0
[407.850430] Dynamic subtest ccs0: SUCCESS (0.047s)
[407.852705] Subtest engines-mixed: SUCCESS (0.367s)
[407.928172] [126/132] (616s left) kms_vblank (ts-continuation-modeset-hang)
[408.013756] Starting subtest: ts-continuation-modeset-hang
[408.015642] Starting dynamic subtest: pipe-A-eDP-1
[434.642978] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.625s)
[434.661025] Starting dynamic subtest: pipe-D-eDP-1
[459.671906] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.026s)
[459.674118] Subtest ts-continuation-modeset-hang: SUCCESS (51.656s)
[460.539034] [127/132] (563s left) kms_psr (psr2-primary-render)
[460.626035] Starting subtest: psr2-primary-render
[460.628290] Starting dynamic subtest: eDP-1
[462.356451] Dynamic subtest eDP-1: SUCCESS (1.729s)
[462.358352] Subtest psr2-primary-render: SUCCESS (1.730s)
[463.214891] [128/132] (561s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[463.316497] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[464.429421] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.114s)
[464.566672] [129/132] (560s left) dmabuf_sync_file (export-multiwait)
[464.613855] Starting subtest: export-multiwait
[464.615833] Subtest export-multiwait: SUCCESS (0.000s)
[464.671790] [130/132] (559s left) kms_psr (fbc-psr2-primary-render)
[464.766499] Starting subtest: fbc-psr2-primary-render
[464.768275] Starting dynamic subtest: eDP-1
[464.771554] Dynamic subtest eDP-1: SKIP (0.000s)
[464.774812] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[464.838991] [131/132] (559s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[464.975978] Starting subtest: cursor-rapid-movement-32x32
[465.022346] Subtest cursor-rapid-movement-32x32: SKIP (0.043s)
[465.298912] [132/132] (559s left) gem_busy (busy)
[465.363544] Starting subtest: busy
[465.365193] Starting dynamic subtest: all-engines
[465.422772] Dynamic subtest all-engines: SUCCESS (0.061s)
[465.434142] Starting dynamic subtest: rcs0
[466.928060] Dynamic subtest rcs0: SUCCESS (1.479s)
[466.939988] Starting dynamic subtest: bcs0
[466.964827] Dynamic subtest bcs0: SUCCESS (0.060s)
[466.974558] Starting dynamic subtest: vcs0
[467.004505] Dynamic subtest vcs0: SUCCESS (0.036s)
[467.013934] Starting dynamic subtest: vcs1
[468.890201] Dynamic subtest vcs1: SUCCESS (1.884s)
[468.900113] Starting dynamic subtest: vecs0
[470.939795] Dynamic subtest vecs0: SUCCESS (2.047s)
[470.952967] Starting dynamic subtest: ccs0
[472.926335] Dynamic subtest ccs0: SUCCESS (1.986s)
[472.928820] Subtest busy: SUCCESS (7.561s)
[473.266275] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: syncobj_wait
results: parsing output: 5/ for test: i915_drm_fdinfo
results: parsing output: 6/ for test: gem_mmap
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: gem_pxp
results: parsing output: 9/ for test: kms_hdr
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: perf
results: parsing output: 12/ for test: kms_async_flips
results: parsing output: 13/ for test: gem_exec_balancer
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: kms_invalid_mode
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_cursor_edge_walk
results: parsing output: 19/ for test: api_intel_bb
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_plane_lowres
results: parsing output: 23/ for test: gem_lmem_swapping
results: parsing output: 24/ for test: kms_sequence
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: intel_hwmon
results: parsing output: 27/ for test: gem_eio
results: parsing output: 28/ for test: kms_flip
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_chamelium_edid
results: parsing output: 35/ for test: kms_rotation_crc
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: gem_ctx_shared
results: parsing output: 38/ for test: gem_sync
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_sync
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: gem_softpin
results: parsing output: 43/ for test: gen9_exec_parse
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: gem_mmap_offset
results: parsing output: 47/ for test: kms_cursor_crc
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_plane_scaling
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: i915_pm_rps
results: parsing output: 53/ for test: syncobj_wait
results: parsing output: 54/ for test: kms_chamelium_color
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: prime_mmap_coherency
results: parsing output: 58/ for test: kms_color_pipeline
results: parsing output: 59/ for test: gem_vm_create
results: parsing output: 60/ for test: api_intel_allocator
results: parsing output: 61/ for test: perf
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: kms_rotation_crc
results: parsing output: 64/ for test: gem_exec_fence
results: parsing output: 65/ for test: gem_mmap_wc
results: parsing output: 66/ for test: gem_mmap_wc
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_exec_schedule
results: parsing output: 70/ for test: kms_cursor_legacy
results: parsing output: 71/ for test: kms_cursor_legacy
results: parsing output: 72/ for test: kms_flip_scaled_crc
results: parsing output: 73/ for test: kms_joiner
results: parsing output: 74/ for test: gem_exec_whisper
results: parsing output: 75/ for test: kms_busy
results: parsing output: 76/ for test: kms_pipe_stress
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_exec_fence
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: gem_spin_batch
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_prop_blob
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: syncobj_timeline
results: parsing output: 85/ for test: gem_mmap_gtt
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_exec_params
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: syncobj_basic
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_plane
results: parsing output: 94/ for test: gem_render_linear_blits
results: parsing output: 95/ for test: kms_flip
results: parsing output: 96/ for test: i915_query
results: parsing output: 97/ for test: gem_create
results: parsing output: 98/ for test: sysfs_heartbeat_interval
results: parsing output: 99/ for test: kms_pm_dc
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: syncobj_basic
results: parsing output: 102/ for test: syncobj_eventfd
results: parsing output: 103/ for test: gem_ctx_param
results: parsing output: 104/ for test: prime_mmap
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: kms_dsc
results: parsing output: 107/ for test: i915_pm_rpm
results: parsing output: 108/ for test: gem_render_copy
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: kms_chamelium_hpd
results: parsing output: 111/ for test: kms_psr2_sf
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_properties
results: parsing output: 114/ for test: kms_addfb_basic
results: parsing output: 115/ for test: i915_hangman
results: parsing output: 116/ for test: kms_concurrent
results: parsing output: 117/ for test: gem_mmap_gtt
results: parsing output: 118/ for test: gem_exec_suspend
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: sriov_basic
results: parsing output: 122/ for test: gem_ctx_shared
results: parsing output: 123/ for test: kms_plane
results: parsing output: 124/ for test: gem_ctx_persistence
results: parsing output: 125/ for test: kms_vblank
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: dmabuf_sync_file
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: kms_cursor_crc
results: parsing output: 131/ for test: gem_busy
Done.