[56.557258] Initializing watchdogs
[56.557307]   /dev/watchdog0
[56.571527] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[56.575782] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.577888] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.581434] [001/130] (960s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[56.690102] Starting subtest: plane-lut1d-ctm3x4-lut1d
[56.875991] Starting dynamic subtest: pipe-A-plane-0
[57.060349] Dynamic subtest pipe-A-plane-0: SUCCESS (0.189s)
[57.067924] Starting dynamic subtest: pipe-A-plane-1
[57.211755] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[57.218614] Starting dynamic subtest: pipe-A-plane-2
[57.374773] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[58.528844] Starting dynamic subtest: pipe-B-plane-0
[58.685857] Dynamic subtest pipe-B-plane-0: SUCCESS (0.167s)
[58.693887] Starting dynamic subtest: pipe-B-plane-1
[58.827226] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s)
[58.835038] Starting dynamic subtest: pipe-B-plane-2
[58.990463] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[60.168469] Starting dynamic subtest: pipe-C-plane-0
[60.316453] Dynamic subtest pipe-C-plane-0: SUCCESS (0.156s)
[60.325280] Starting dynamic subtest: pipe-C-plane-1
[60.458261] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[60.465290] Starting dynamic subtest: pipe-C-plane-2
[60.600702] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[61.744221] Starting dynamic subtest: pipe-D-plane-0
[61.902258] Dynamic subtest pipe-D-plane-0: SUCCESS (0.167s)
[61.910839] Starting dynamic subtest: pipe-D-plane-1
[62.044661] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[62.052210] Starting dynamic subtest: pipe-D-plane-2
[62.198234] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[62.493087] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (5.913s)
[63.305133] [002/130] (953s left) syncobj_wait (invalid-signal-one-illegal-handle)
[63.376108] Starting subtest: invalid-signal-one-illegal-handle
[63.378356] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[63.469341] [003/130] (953s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[63.566940] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[63.571370] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[63.673418] [004/130] (953s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[63.767333] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[63.771702] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[63.854948] [005/130] (953s left) gen9_exec_parse (cmd-crossing-page)
[63.922636] Subtest cmd-crossing-page: SKIP (0.000s)
[63.980974] [006/130] (953s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[64.079254] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[64.083374] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[64.176646] [007/130] (953s left) gem_exec_reloc (basic-cpu-wc-active)
[64.230703] Subtest basic-cpu-wc-active: SKIP (0.000s)
[64.300153] [008/130] (953s left) gem_exec_whisper (basic-queues-priority-all)
[64.369225] Starting subtest: basic-queues-priority-all
[84.699908] Subtest basic-queues-priority-all: SUCCESS (20.395s)
[84.886481] [009/130] (932s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[84.990604] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[84.994775] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[85.083516] [010/130] (932s left) kms_content_protection (legacy)
[85.220916] Starting subtest: legacy
[85.225237] Subtest legacy: SKIP (0.000s)
[85.323923] [011/130] (932s left) kms_flip (blocking-wf_vblank)
[85.419624] Starting subtest: blocking-wf_vblank
[85.421886] Starting dynamic subtest: A-eDP1
[94.370057] Dynamic subtest A-eDP1: SUCCESS (8.949s)
[94.379802] Starting dynamic subtest: B-eDP1
[103.314981] Dynamic subtest B-eDP1: SUCCESS (8.942s)
[103.324824] Starting dynamic subtest: C-eDP1
[112.238320] Dynamic subtest C-eDP1: SUCCESS (8.921s)
[112.247703] Starting dynamic subtest: D-eDP1
[121.172553] Dynamic subtest D-eDP1: SUCCESS (8.934s)
[121.177813] Subtest blocking-wf_vblank: SUCCESS (35.754s)
[122.392347] [012/130] (895s left) gem_mmap_gtt (big-copy-xy)
[122.444762] Subtest big-copy-XY: SKIP (0.000s)
[122.498212] [013/130] (895s left) syncobj_timeline (etime-single-wait-submitted)
[122.554820] Starting subtest: etime-single-wait-submitted
[122.664311] Subtest etime-single-wait-submitted: SUCCESS (0.109s)
[122.738474] [014/130] (894s left) kms_cursor_crc (cursor-onscreen-512x512)
[122.998699] Starting subtest: cursor-onscreen-512x512
[123.003151] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[123.170840] [015/130] (894s left) kms_chamelium_edid (vga-edid-read)
[123.547430] Subtest vga-edid-read: SKIP (0.000s)
[124.401592] [016/130] (893s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[124.510923] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[131.301932] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.795s)
[131.410654] [017/130] (886s left) gem_exec_schedule (fifo)
[131.488695] Starting subtest: fifo
[131.490749] Starting dynamic subtest: rcs0
[131.577880] Dynamic subtest rcs0: SUCCESS (0.099s)
[131.586476] Starting dynamic subtest: vcs0
[131.695430] Dynamic subtest vcs0: SUCCESS (0.116s)
[131.705920] Starting dynamic subtest: vcs1
[131.778024] Dynamic subtest vcs1: SUCCESS (0.082s)
[131.786502] Starting dynamic subtest: vecs0
[131.854163] Dynamic subtest vecs0: SUCCESS (0.075s)
[131.865989] Starting dynamic subtest: ccs0
[131.933474] Dynamic subtest ccs0: SUCCESS (0.078s)
[131.936215] Subtest fifo: SUCCESS (0.453s)
[133.440540] [018/130] (884s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[133.537310] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[138.392074] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.857s)
[139.579766] [019/130] (878s left) kms_psr (psr2-sprite-plane-move)
[139.672858] Starting subtest: psr2-sprite-plane-move
[139.675148] Starting dynamic subtest: eDP-1
[141.397012] Dynamic subtest eDP-1: SUCCESS (1.725s)
[141.399308] Subtest psr2-sprite-plane-move: SUCCESS (1.726s)
[142.241842] [020/130] (875s left) prime_self_import (basic-llseek-size)
[142.281085] Starting subtest: basic-llseek-size
[142.297128] Subtest basic-llseek-size: SUCCESS (0.016s)
[142.421340] [021/130] (875s left) kms_cursor_crc (cursor-sliding-32x10)
[142.584725] Starting subtest: cursor-sliding-32x10
[142.632917] Subtest cursor-sliding-32x10: SKIP (0.045s)
[142.893053] [022/130] (875s left) dumb_buffer (map-valid)
[142.947681] Starting subtest: map-valid
[142.949809] Subtest map-valid: SUCCESS (0.000s)
[143.026309] [023/130] (874s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[143.119112] Starting subtest: multiplane-rotation-cropping-bottom
[160.511239] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.393s)
[160.626975] [024/130] (857s left) perf_pmu (busy-idle)
[160.677146] Starting subtest: busy-idle
[160.679094] Starting dynamic subtest: rcs0
[161.358322] Dynamic subtest rcs0: SUCCESS (0.682s)
[161.369448] Starting dynamic subtest: bcs0
[162.028359] Dynamic subtest bcs0: SUCCESS (0.668s)
[162.039190] Starting dynamic subtest: vcs0
[163.363054] Dynamic subtest vcs0: SUCCESS (1.331s)
[163.375066] Starting dynamic subtest: vcs1
[165.346821] Dynamic subtest vcs1: SUCCESS (1.981s)
[165.358139] Starting dynamic subtest: vecs0
[167.330862] Dynamic subtest vecs0: SUCCESS (1.981s)
[167.342399] Starting dynamic subtest: ccs0
[168.004995] Dynamic subtest ccs0: SUCCESS (0.673s)
[168.007998] Subtest busy-idle: SUCCESS (7.329s)
[168.103608] [025/130] (849s left) kms_joiner (basic-force-big-joiner)
[168.202598] Starting subtest: basic-force-big-joiner
[168.206690] Subtest basic-force-big-joiner: SKIP (0.000s)
[168.282225] [026/130] (849s left) kms_cursor_crc (cursor-random-max-size)
[168.589931] Starting subtest: cursor-random-max-size
[168.596532] Subtest cursor-random-max-size: SKIP (0.000s)
[168.704887] [027/130] (849s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[168.792059] Starting subtest: nonblocking-crc-frame-sequence
[168.794030] Starting dynamic subtest: pipe-A-eDP-1
[169.561352] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.768s)
[169.570808] Starting dynamic subtest: pipe-B-eDP-1
[170.947032] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.386s)
[170.957049] Starting dynamic subtest: pipe-C-eDP-1
[172.344832] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.394s)
[172.353811] Starting dynamic subtest: pipe-D-eDP-1
[173.662173] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.317s)
[173.664438] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.871s)
[174.483262] [028/130] (843s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[174.576891] Starting subtest: bad-rotation-90-yf-tiled-ccs
[174.579143] Starting dynamic subtest: pipe-A-eDP-1
[174.601148] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[174.611478] Starting dynamic subtest: pipe-B-eDP-1
[174.631775] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[174.639723] Starting dynamic subtest: pipe-C-eDP-1
[174.661337] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[174.669299] Starting dynamic subtest: pipe-D-eDP-1
[174.690871] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[174.695156] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[176.817594] [029/130] (841s left) kms_plane_multiple (2x-tiling-x)
[176.912137] Starting subtest: 2x-tiling-x
[176.916559] Subtest 2x-tiling-x: SKIP (0.000s)
[176.982117] [030/130] (841s left) kms_pm_lpsp (kms-lpsp)
[178.087069] Starting subtest: kms-lpsp
[178.089464] Starting dynamic subtest: pipe-A-eDP-1
[178.233973] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.148s)
[178.530751] Subtest kms-lpsp: SUCCESS (0.445s)
[179.353755] [031/130] (838s left) gem_exec_flush (basic-wb-rw-before-default)
[179.407456] Starting subtest: basic-wb-rw-before-default
[184.870497] Subtest basic-wb-rw-before-default: SUCCESS (5.463s)
[185.058792] [032/130] (833s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[185.180122] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[185.182304] Starting dynamic subtest: pipe-A
[186.350964] Dynamic subtest pipe-A: SUCCESS (1.173s)
[186.360447] Starting dynamic subtest: pipe-B
[188.623521] Dynamic subtest pipe-B: SUCCESS (2.271s)
[188.631775] Starting dynamic subtest: pipe-C
[189.772208] Dynamic subtest pipe-C: SUCCESS (1.147s)
[189.781897] Starting dynamic subtest: pipe-D
[191.007086] Dynamic subtest pipe-D: SUCCESS (1.234s)
[191.009288] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.830s)
[192.207833] [033/130] (826s left) gem_mmap_wc (write-read-distinct)
[192.261200] Subtest write-read-distinct: SKIP (0.000s)
[192.318298] [034/130] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[192.404118] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[198.974848] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.574s)
[199.113204] [035/130] (819s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[199.211451] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[199.215814] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[199.309148] [036/130] (819s left) kms_addfb_basic (bad-pitch-128)
[199.384464] Starting subtest: bad-pitch-128
[199.386831] Subtest bad-pitch-128: SUCCESS (0.000s)
[199.519205] [037/130] (818s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[199.638774] Starting subtest: cursorB-vs-flipB-legacy
[199.643291] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[199.782394] [038/130] (818s left) gem_exec_fence (syncobj-import)
[200.253178] Starting subtest: syncobj-import
[200.259046] Subtest syncobj-import: SUCCESS (0.005s)
[201.398107] [FACT gem_exec_fence (syncobj-import)] new: kernel.kmod_is_loaded.vgem: true
[201.401628] [039/130] (817s left) kms_chamelium_audio (dp-audio)
[201.790386] Subtest dp-audio: SKIP (0.000s)
[202.630111] [040/130] (816s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[202.727468] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[202.729877] Starting dynamic subtest: pipe-A-eDP-1
[202.739039] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[202.753912] Starting dynamic subtest: pipe-B-eDP-1
[202.762240] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[202.770517] Starting dynamic subtest: pipe-C-eDP-1
[202.778952] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[202.787271] Starting dynamic subtest: pipe-D-eDP-1
[202.795416] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[202.799443] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[204.923642] [041/130] (813s left) gen3_render_tiledy_blits
[205.059042] [042/130] (813s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[205.151278] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[205.155589] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.000s)
[205.224303] [043/130] (813s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[205.320124] Starting subtest: pr-overlay-plane-update-continuous-sf
[205.323987] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[205.387616] [044/130] (813s left) sw_sync (sync_merge_same)
[205.422529] Starting subtest: sync_merge_same
[205.424450] Subtest sync_merge_same: SUCCESS (0.000s)
[205.478499] [045/130] (813s left) api_intel_bb (reset-flags)
[205.538252] Starting subtest: reset-flags
[205.545845] Subtest reset-flags: SUCCESS (0.010s)
[205.668275] [046/130] (813s left) kms_cursor_legacy (forked-bo)
[205.787322] Starting subtest: forked-bo
[205.789469] Starting dynamic subtest: pipe-A
[211.245019] Dynamic subtest pipe-A: SUCCESS (5.454s)
[211.256472] Starting dynamic subtest: pipe-B
[216.720271] Dynamic subtest pipe-B: SUCCESS (5.472s)
[216.734970] Starting dynamic subtest: pipe-C
[222.204386] Dynamic subtest pipe-C: SUCCESS (5.482s)
[222.217083] Starting dynamic subtest: pipe-D
[227.674453] Dynamic subtest pipe-D: SUCCESS (5.467s)
[227.685952] Starting dynamic subtest: all-pipes
[233.171063] Dynamic subtest all-pipes: SUCCESS (5.494s)
[233.173889] Subtest forked-bo: SUCCESS (27.380s)
[233.333164] [047/130] (785s left) kms_psr (pr-sprite-plane-move)
[233.462122] Starting subtest: pr-sprite-plane-move
[233.466557] Subtest pr-sprite-plane-move: SKIP (0.000s)
[233.541802] [048/130] (785s left) kms_psr (fbc-pr-cursor-plane-onoff)
[233.630165] Starting subtest: fbc-pr-cursor-plane-onoff
[233.634609] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[233.701736] [049/130] (785s left) gem_exec_await (wide-all)
[233.872925] Starting subtest: wide-all
[257.403159] Subtest wide-all: SUCCESS (23.528s)
[257.600466] [050/130] (761s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[257.704225] Subtest writes-after-reads-snoop: SKIP (0.000s)
[257.769174] [051/130] (761s left) gem_wait (invalid-buf)
[257.831733] Starting subtest: invalid-buf
[257.833974] Subtest invalid-buf: SUCCESS (0.000s)
[258.032542] [052/130] (761s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[258.083728] Starting subtest: x-tiled-to-vebox-yf-tiled
[258.088224] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[258.285797] [053/130] (760s left) kms_async_flips (async-flip-hang)
[258.392841] Starting subtest: async-flip-hang
[258.772169] Starting dynamic subtest: pipe-A-eDP-1
[273.860357] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.091s)
[274.244867] Starting dynamic subtest: pipe-B-eDP-1
[289.857588] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.618s)
[290.290157] Starting dynamic subtest: pipe-C-eDP-1
[305.788207] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.506s)
[306.188723] Starting dynamic subtest: pipe-D-eDP-1
[321.792941] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.608s)
[321.795685] Subtest async-flip-hang: SUCCESS (63.396s)
[323.245123] [054/130] (695s left) prime_vgem (coherency-blt)
[323.335325] Starting subtest: coherency-blt
[324.427833] Subtest coherency-blt: SUCCESS (1.091s)
[324.538489] [055/130] (694s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[324.627048] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[324.629359] Starting dynamic subtest: pipe-A-default-mode
[327.344228] Dynamic subtest pipe-A-default-mode: SUCCESS (2.718s)
[327.346590] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.720s)
[328.226853] [056/130] (691s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[328.331089] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[328.335051] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[328.439817] [057/130] (690s left) gem_spin_batch (resubmit-new-all)
[328.506854] Starting subtest: resubmit-new-all
[328.509041] Starting dynamic subtest: rcs0
[328.516745] Dynamic subtest rcs0: SUCCESS (0.012s)
[328.527263] Starting dynamic subtest: bcs0
[328.529454] Dynamic subtest bcs0: SUCCESS (0.011s)
[328.538525] Starting dynamic subtest: vcs0
[328.543473] Dynamic subtest vcs0: SUCCESS (0.013s)
[328.552952] Starting dynamic subtest: vcs1
[328.555199] Dynamic subtest vcs1: SUCCESS (0.011s)
[328.563749] Starting dynamic subtest: vecs0
[328.565965] Dynamic subtest vecs0: SUCCESS (0.008s)
[328.574824] Starting dynamic subtest: ccs0
[328.576655] Dynamic subtest ccs0: SUCCESS (0.008s)
[328.578517] Subtest resubmit-new-all: SUCCESS (0.069s)
[330.380196] [058/130] (688s left) gem_mmap_offset (partial-unmap)
[330.441141] Starting subtest: partial-unmap
[330.443032] Starting dynamic subtest: smem0
[330.847028] Dynamic subtest smem0: SUCCESS (0.407s)
[330.849358] Subtest partial-unmap: SUCCESS (0.408s)
[330.951612] [059/130] (688s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[331.003419] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[331.005741] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[331.082581] [060/130] (688s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[331.178710] Starting subtest: fbc-modesetfrombusy
[334.981667] Subtest fbc-modesetfrombusy: SUCCESS (3.807s)
[336.159353] [061/130] (683s left) gem_eio (in-flight-internal-1us)
[336.402293] Starting subtest: in-flight-internal-1us
[337.390555] Subtest in-flight-internal-1us: SUCCESS (0.986s)
[337.536239] [062/130] (682s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[337.635575] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[337.640026] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[337.719564] [063/130] (681s left) gem_pread (bench)
[337.788976] Subtest bench: SKIP (0.000s)
[337.845437] [064/130] (681s left) kms_psr (fbc-pr-sprite-plane-move)
[337.932755] Starting subtest: fbc-pr-sprite-plane-move
[337.937092] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[338.007468] [065/130] (681s left) perf_pmu (busy-accuracy-50)
[338.048487] Starting subtest: busy-accuracy-50
[338.050362] Starting dynamic subtest: rcs0
[339.583792] Dynamic subtest rcs0: SUCCESS (1.534s)
[339.595419] Starting dynamic subtest: bcs0
[341.130886] Dynamic subtest bcs0: SUCCESS (1.544s)
[341.142295] Starting dynamic subtest: vcs0
[342.676049] Dynamic subtest vcs0: SUCCESS (1.543s)
[342.687834] Starting dynamic subtest: vcs1
[344.217918] Dynamic subtest vcs1: SUCCESS (1.539s)
[344.232166] Starting dynamic subtest: vecs0
[345.760398] Dynamic subtest vecs0: SUCCESS (1.540s)
[345.772183] Starting dynamic subtest: ccs0
[347.310327] Dynamic subtest ccs0: SUCCESS (1.547s)
[347.312666] Subtest busy-accuracy-50: SUCCESS (9.261s)
[347.426232] [066/130] (672s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[347.527664] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[347.531807] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[347.628071] [067/130] (672s left) gem_userptr_blits (forbidden-operations)
[347.692356] Starting subtest: forbidden-operations
[347.696703] Subtest forbidden-operations: SKIP (0.000s)
[347.827417] [068/130] (671s left) i915_drm_fdinfo (virtual-idle)
[347.865051] Starting subtest: virtual-idle
[350.929028] Subtest virtual-idle: SUCCESS (3.062s)
[351.010913] [069/130] (668s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180-hflip)
[351.127730] Starting subtest: linear-max-hw-stride-32bpp-rotate-180-hflip
[351.132274] Subtest linear-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[351.235916] [070/130] (668s left) gem_exec_flush (basic-wb-prw-default)
[351.299395] Starting subtest: basic-wb-prw-default
[356.770540] Subtest basic-wb-prw-default: SUCCESS (5.470s)
[356.992663] [071/130] (662s left) kms_psr (fbc-psr-cursor-plane-onoff)
[357.098879] Starting subtest: fbc-psr-cursor-plane-onoff
[357.101120] Starting dynamic subtest: eDP-1
[357.105177] Dynamic subtest eDP-1: SKIP (0.000s)
[357.109190] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[357.177163] [072/130] (662s left) gem_mmap (bad-size)
[357.232763] Subtest bad-size: SKIP (0.000s)
[357.293918] [073/130] (662s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[357.386724] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[357.390816] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[357.456257] [074/130] (662s left) perf_pmu (frequency)
[357.495382] Starting subtest: frequency
[357.499644] Starting dynamic subtest: gt0
[359.635767] Dynamic subtest gt0: SUCCESS (2.131s)
[359.647829] Starting dynamic subtest: idle-gt0
[360.143114] Dynamic subtest idle-gt0: SUCCESS (0.506s)
[360.155281] Starting dynamic subtest: gt1
[364.333582] Dynamic subtest gt1: SUCCESS (4.185s)
[364.345571] Starting dynamic subtest: idle-gt1
[364.839100] Dynamic subtest idle-gt1: SUCCESS (0.505s)
[364.841929] Subtest frequency: SUCCESS (7.346s)
[364.943989] [075/130] (655s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[365.049237] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[365.053364] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[365.149653] [076/130] (655s left) kms_big_fb (y-tiled-addfb)
[365.242494] Starting subtest: y-tiled-addfb
[365.246953] Subtest y-tiled-addfb: SKIP (0.000s)
[365.346450] [077/130] (654s left) syncobj_wait (wait-zero-handles)
[365.398689] Starting subtest: wait-zero-handles
[365.401499] Subtest wait-zero-handles: SUCCESS (0.000s)
[365.508670] [078/130] (654s left) gem_unfence_active_buffers
[365.669427] [079/130] (654s left) gem_exec_schedule (semaphore-user)
[365.750078] Starting subtest: semaphore-user
[365.800669] Subtest semaphore-user: SUCCESS (0.057s)
[367.371107] [080/130] (652s left) kms_addfb_basic (no-handle)
[367.421151] Starting subtest: no-handle
[367.422970] Subtest no-handle: SUCCESS (0.000s)
[367.562070] [081/130] (652s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[367.655618] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[367.659586] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[367.756701] [082/130] (652s left) kms_legacy_colorkey (basic)
[367.849175] Starting subtest: basic
[367.851185] Starting dynamic subtest: pipe-A
[367.891954] Dynamic subtest pipe-A: SUCCESS (0.045s)
[367.903457] Starting dynamic subtest: pipe-B
[367.905688] Dynamic subtest pipe-B: SUCCESS (0.001s)
[367.914987] Starting dynamic subtest: pipe-C
[367.916974] Dynamic subtest pipe-C: SUCCESS (0.000s)
[367.925837] Starting dynamic subtest: pipe-D
[367.927761] Dynamic subtest pipe-D: SUCCESS (0.000s)
[367.929677] Subtest basic: SUCCESS (0.050s)
[367.967763] [083/130] (652s left) prime_mmap_kms (buffer-sharing)
[368.064125] Starting subtest: buffer-sharing
[368.524515] Subtest buffer-sharing: SUCCESS (0.461s)
[369.386265] [084/130] (651s left) kms_pm_rpm (modeset-lpsp)
[371.447836] Starting subtest: modeset-lpsp
[372.899451] Subtest modeset-lpsp: SUCCESS (1.456s)
[372.988266] [085/130] (647s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[373.076148] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[373.080087] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[373.164569] [086/130] (647s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[373.262481] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[373.266525] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[373.358176] [087/130] (647s left) kms_feature_discovery (display-1x)
[373.451741] Starting subtest: display-1x
[373.454070] Subtest display-1x: SUCCESS (0.000s)
[373.528145] [088/130] (647s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[373.620232] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[373.622105] Starting dynamic subtest: A-eDP1
[377.191697] Dynamic subtest A-eDP1: SUCCESS (3.572s)
[377.201582] Starting dynamic subtest: B-eDP1
[380.399981] Dynamic subtest B-eDP1: SUCCESS (3.208s)
[380.411714] Starting dynamic subtest: C-eDP1
[383.545348] Dynamic subtest C-eDP1: SUCCESS (3.143s)
[383.555714] Starting dynamic subtest: D-eDP1
[386.638428] Dynamic subtest D-eDP1: SUCCESS (3.092s)
[386.644639] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.028s)
[387.436403] [089/130] (633s left) i915_pm_rps (thresholds-park)
[387.509117] Starting subtest: thresholds-park
[387.513372] Subtest thresholds-park: SKIP (0.000s)
[387.609562] [090/130] (633s left) gem_ctx_persistence (engines-queued)
[387.670284] Starting subtest: engines-queued
[387.672614] Starting dynamic subtest: rcs0
[387.740597] Dynamic subtest rcs0: SUCCESS (0.070s)
[387.753331] Starting dynamic subtest: bcs0
[387.785607] Dynamic subtest bcs0: SUCCESS (0.043s)
[387.796921] Starting dynamic subtest: vcs0
[389.314449] Dynamic subtest vcs0: SUCCESS (1.525s)
[389.327019] Starting dynamic subtest: vcs1
[391.295935] Dynamic subtest vcs1: SUCCESS (1.979s)
[391.309229] Starting dynamic subtest: vecs0
[393.280639] Dynamic subtest vecs0: SUCCESS (1.982s)
[393.292274] Starting dynamic subtest: ccs0
[393.459221] Dynamic subtest ccs0: SUCCESS (0.177s)
[393.461644] Subtest engines-queued: SUCCESS (5.789s)
[393.574848] [091/130] (627s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[393.666061] Starting subtest: plane-upscale-20x20-with-modifiers
[393.668330] Starting dynamic subtest: pipe-A
[394.004703] Dynamic subtest pipe-A: SUCCESS (0.339s)
[394.021298] Starting dynamic subtest: pipe-B
[395.432900] Dynamic subtest pipe-B: SUCCESS (1.426s)
[395.442639] Starting dynamic subtest: pipe-C
[396.867608] Dynamic subtest pipe-C: SUCCESS (1.434s)
[396.878064] Starting dynamic subtest: pipe-D
[398.313267] Dynamic subtest pipe-D: SUCCESS (1.444s)
[398.315518] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.649s)
[399.506152] [092/130] (621s left) gem_userptr_blits (stress-purge)
[399.575487] Starting subtest: stress-purge
[404.999154] Subtest stress-purge: SUCCESS (5.425s)
[405.140819] [093/130] (615s left) kms_frontbuffer_tracking (fbc-2p-rte)
[405.286726] Starting subtest: fbc-2p-rte
[405.290166] Subtest fbc-2p-rte: SKIP (0.000s)
[405.379528] [094/130] (615s left) gem_ctx_persistence (heartbeat-stop)
[405.442057] Starting subtest: heartbeat-stop
[405.445771] Subtest heartbeat-stop: SKIP (0.000s)
[405.554628] [095/130] (615s left) gem_exec_store (basic)
[405.609264] Starting subtest: basic
[405.686672] Subtest basic: SUCCESS (0.077s)
[405.869897] [096/130] (615s left) gem_exec_reloc (basic-write-cpu-active)
[405.948755] Subtest basic-write-cpu-active: SKIP (0.000s)
[406.029342] [097/130] (615s left) gem_ctx_engines (none)
[406.087480] Starting subtest: none
[406.089461] Subtest none: SUCCESS (0.001s)
[406.274917] [098/130] (614s left) kms_vblank (query-forked-busy)
[406.367728] Starting subtest: query-forked-busy
[406.369685] Starting dynamic subtest: pipe-A-eDP-1
[408.244077] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[408.259187] Starting dynamic subtest: pipe-D-eDP-1
[410.844235] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.599s)
[410.846658] Subtest query-forked-busy: SUCCESS (4.478s)
[411.666900] [099/130] (609s left) api_intel_allocator (gem-pool)
[411.716077] Starting subtest: gem-pool
[411.732723] Subtest gem-pool: SUCCESS (0.017s)
[411.861598] [100/130] (609s left) prime_mmap (test_correct_cpu_write)
[411.920877] Starting subtest: test_correct_cpu_write
[411.923264] Starting dynamic subtest: test_correct_cpu_write-smem
[411.925501] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[411.927734] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[412.027316] [101/130] (609s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[412.123916] Starting subtest: crc-primary-basic-y-tiled-ccs
[412.126212] Starting dynamic subtest: pipe-A-eDP-1
[412.149075] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[412.161047] Starting dynamic subtest: pipe-B-eDP-1
[412.181229] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[412.190703] Starting dynamic subtest: pipe-C-eDP-1
[412.211680] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[412.222058] Starting dynamic subtest: pipe-D-eDP-1
[412.242719] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[412.246880] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.006s)
[414.433964] [102/130] (606s left) kms_frontbuffer_tracking (psr-suspend)
[414.532305] Starting subtest: psr-suspend
[453.636107] Subtest psr-suspend: SUCCESS (9.634s)
[463.793029] [103/130] (567s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[463.953438] Starting subtest: cursorB-vs-flipA-atomic
[463.958097] Subtest cursorB-vs-flipA-atomic: SKIP (0.003s)
[464.104460] [104/130] (567s left) i915_drm_fdinfo (busy-idle-check-all)
[464.153612] Starting subtest: busy-idle-check-all
[464.155508] Starting dynamic subtest: rcs0
[464.160077] Dynamic subtest rcs0: SKIP (0.004s)
[464.171023] Starting dynamic subtest: bcs0
[464.175354] Dynamic subtest bcs0: SKIP (0.000s)
[464.185576] Starting dynamic subtest: vcs0
[464.189576] Dynamic subtest vcs0: SKIP (0.000s)
[464.199893] Starting dynamic subtest: vcs1
[464.203673] Dynamic subtest vcs1: SKIP (0.000s)
[464.213121] Starting dynamic subtest: vecs0
[464.216959] Dynamic subtest vecs0: SKIP (0.000s)
[464.226381] Starting dynamic subtest: ccs0
[464.230320] Dynamic subtest ccs0: SKIP (0.000s)
[464.234236] Subtest busy-idle-check-all: SKIP (0.009s)
[464.272020] [105/130] (567s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[464.383049] Starting subtest: 4-tiled-16bpp-rotate-270
[464.405410] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.020s)
[464.502753] [106/130] (567s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[464.596156] Starting subtest: fbcpsr-tiling-4
[471.525509] Subtest fbcpsr-tiling-4: SUCCESS (7.133s)
[471.648183] [107/130] (559s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[471.776857] Starting subtest: flip-vs-cursor-busy-crc-atomic
[472.617221] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.852s)
[473.446587] [108/130] (558s left) kms_sequence (get-forked)
[473.539057] Starting subtest: get-forked
[473.541484] Starting dynamic subtest: pipe-A-eDP-1
[475.414659] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.899s)
[475.430262] Starting dynamic subtest: pipe-B-eDP-1
[477.964025] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.571s)
[477.975132] Starting dynamic subtest: pipe-C-eDP-1
[480.443087] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.497s)
[480.460736] Starting dynamic subtest: pipe-D-eDP-1
[482.993137] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.560s)
[482.995523] Subtest get-forked: SUCCESS (9.533s)
[483.822055] [109/130] (547s left) gem_exec_nop (basic-sequential)
[483.898154] Starting subtest: basic-sequential
[498.299710] Subtest basic-sequential: SUCCESS (14.432s)
[498.541141] [110/130] (533s left) gem_exec_params (invalid-bsd2-flag-on-render)
[498.607276] Starting subtest: invalid-bsd2-flag-on-render
[498.609648] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[498.706828] [111/130] (533s left) gem_exec_balancer (invalid-bonds)
[498.780120] Starting subtest: invalid-bonds
[498.784385] Subtest invalid-bonds: SKIP (0.000s)
[499.100423] [112/130] (532s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[499.161142] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[499.271418] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.108s)
[499.366257] [113/130] (532s left) kms_atomic_transition (modeset-transition-fencing)
[499.469680] Starting subtest: modeset-transition-fencing
[499.471640] Starting dynamic subtest: 1x-outputs
[510.453486] Dynamic subtest 1x-outputs: SUCCESS (10.981s)
[510.455998] Subtest modeset-transition-fencing: SUCCESS (10.983s)
[511.317896] [114/130] (520s left) kms_big_fb (linear-16bpp-rotate-90)
[511.431954] Starting subtest: linear-16bpp-rotate-90
[511.454217] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[511.579750] [115/130] (520s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[511.671584] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[511.698087] Starting dynamic subtest: pipe-A-plane-0
[512.341173] Dynamic subtest pipe-A-plane-0: SUCCESS (0.643s)
[512.354522] Starting dynamic subtest: pipe-A-plane-3
[512.785659] Dynamic subtest pipe-A-plane-3: SUCCESS (0.442s)
[513.895967] Starting dynamic subtest: pipe-B-plane-0
[514.553285] Dynamic subtest pipe-B-plane-0: SUCCESS (0.666s)
[514.564791] Starting dynamic subtest: pipe-B-plane-3
[514.941652] Dynamic subtest pipe-B-plane-3: SUCCESS (0.387s)
[515.274173] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.603s)
[516.094364] [116/130] (515s left) gem_set_tiling_vs_gtt
[516.214376] [117/130] (515s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[516.300152] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[516.304518] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[516.395349] [118/130] (515s left) kms_flip (dpms-off-confusion-interruptible)
[516.483558] Starting subtest: dpms-off-confusion-interruptible
[516.485394] Starting dynamic subtest: A-eDP1
[525.193074] Dynamic subtest A-eDP1: SUCCESS (8.709s)
[525.206971] Starting dynamic subtest: B-eDP1
[533.840500] Dynamic subtest B-eDP1: SUCCESS (8.647s)
[533.851501] Starting dynamic subtest: C-eDP1
[542.461293] Dynamic subtest C-eDP1: SUCCESS (8.621s)
[542.472001] Starting dynamic subtest: D-eDP1
[551.092406] Dynamic subtest D-eDP1: SUCCESS (8.632s)
[551.097433] Subtest dpms-off-confusion-interruptible: SUCCESS (34.621s)
[552.265209] [119/130] (479s left) kms_hdr (bpc-switch-suspend)
[552.365102] Starting subtest: bpc-switch-suspend
[553.423963] Starting dynamic subtest: pipe-A-eDP-1
[573.248353] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.897s)
[573.250672] Subtest bpc-switch-suspend: SUCCESS (5.955s)
[579.379721] [120/130] (458s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[579.485401] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[579.487666] Starting dynamic subtest: pipe-A-eDP-1
[579.509369] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[579.520687] Starting dynamic subtest: pipe-B-eDP-1
[579.541985] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[579.552088] Starting dynamic subtest: pipe-C-eDP-1
[579.572840] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[579.583919] Starting dynamic subtest: pipe-D-eDP-1
[579.604733] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[579.608879] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.008s)
[581.812688] [121/130] (456s left) syncobj_timeline (wait-any-interrupted)
[581.883526] Starting subtest: wait-any-interrupted
[581.988150] Subtest wait-any-interrupted: SUCCESS (0.104s)
[582.066171] [122/130] (456s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[582.167673] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[582.171897] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[582.269663] [123/130] (456s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[582.328859] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[582.333263] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[582.515707] [124/130] (455s left) gem_exec_schedule (pi-distinct-iova)
[582.694799] Starting subtest: pi-distinct-iova
[582.697011] Starting dynamic subtest: rcs0
[582.774683] Dynamic subtest rcs0: SUCCESS (0.083s)
[582.791056] Starting dynamic subtest: vcs0
[582.840025] Dynamic subtest vcs0: SUCCESS (0.063s)
[582.853309] Starting dynamic subtest: vcs1
[582.893306] Dynamic subtest vcs1: SUCCESS (0.053s)
[582.904378] Starting dynamic subtest: vecs0
[582.947154] Dynamic subtest vecs0: SUCCESS (0.053s)
[582.961216] Starting dynamic subtest: ccs0
[583.010848] Dynamic subtest ccs0: SUCCESS (0.062s)
[583.013311] Subtest pi-distinct-iova: SUCCESS (0.319s)
[584.834018] [125/130] (453s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[584.937935] Starting subtest: yf-tiled-16bpp-rotate-180
[584.942019] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[585.042259] [126/130] (453s left) kms_chamelium_hpd (common-hpd-after-suspend)
[585.425265] Subtest common-hpd-after-suspend: SKIP (0.000s)
[586.194288] [127/130] (452s left) dmabuf_sync_file (import-multiple-read-only)
[586.238963] Starting subtest: import-multiple-read-only
[586.241087] Subtest import-multiple-read-only: SUCCESS (0.003s)
[586.298961] [128/130] (452s left) gem_exec_fence (basic-await)
[586.484335] Starting subtest: basic-await
[586.486768] Starting dynamic subtest: rcs0
[586.559943] Dynamic subtest rcs0: SUCCESS (0.078s)
[586.573052] Starting dynamic subtest: bcs0
[586.635036] Dynamic subtest bcs0: SUCCESS (0.075s)
[586.648070] Starting dynamic subtest: vcs0
[586.709085] Dynamic subtest vcs0: SUCCESS (0.075s)
[586.721949] Starting dynamic subtest: vcs1
[586.784028] Dynamic subtest vcs1: SUCCESS (0.075s)
[586.796823] Starting dynamic subtest: vecs0
[586.856410] Dynamic subtest vecs0: SUCCESS (0.073s)
[586.868111] Starting dynamic subtest: ccs0
[586.928996] Dynamic subtest ccs0: SUCCESS (0.073s)
[586.931257] Subtest basic-await: SUCCESS (0.461s)
[588.852962] [129/130] (449s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[588.915284] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[588.917561] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[589.025104] [130/130] (449s left) i915_pm_rc6_residency (rc6-fence)
[589.076167] Starting subtest: rc6-fence
[589.094096] Starting dynamic subtest: gt0
[601.411232] Dynamic subtest gt0: SUCCESS (12.466s)
[601.424644] Starting dynamic subtest: gt1
[615.767340] Dynamic subtest gt1: SUCCESS (14.389s)
[615.769780] Subtest rc6-fence: SUCCESS (26.880s)
[615.847045] Closing watchdogs
results: parsing output: 0/ for test: kms_color_pipeline
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gen9_exec_parse
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: gem_exec_reloc
results: parsing output: 7/ for test: gem_exec_whisper
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_content_protection
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: gem_mmap_gtt
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_cursor_crc
results: parsing output: 14/ for test: kms_chamelium_edid
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_exec_schedule
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: prime_self_import
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: dumb_buffer
results: parsing output: 22/ for test: kms_rotation_crc
results: parsing output: 23/ for test: perf_pmu
results: parsing output: 24/ for test: kms_joiner
results: parsing output: 25/ for test: kms_cursor_crc
results: parsing output: 26/ for test: kms_pipe_crc_basic
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_plane_multiple
results: parsing output: 29/ for test: kms_pm_lpsp
results: parsing output: 30/ for test: gem_exec_flush
results: parsing output: 31/ for test: kms_plane_scaling
results: parsing output: 32/ for test: gem_mmap_wc
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_addfb_basic
results: parsing output: 36/ for test: kms_cursor_legacy
results: parsing output: 37/ for test: gem_exec_fence
results: parsing output: 38/ for test: kms_chamelium_audio
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: gen3_render_tiledy_blits
results: parsing output: 41/ for test: kms_flip_scaled_crc
results: parsing output: 42/ for test: kms_psr2_sf
results: parsing output: 43/ for test: sw_sync
results: parsing output: 44/ for test: api_intel_bb
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: gem_exec_await
results: parsing output: 49/ for test: gem_partial_pwrite_pread
results: parsing output: 50/ for test: gem_wait
results: parsing output: 51/ for test: gem_render_copy
results: parsing output: 52/ for test: kms_async_flips
results: parsing output: 53/ for test: prime_vgem
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: gem_spin_batch
results: parsing output: 57/ for test: gem_mmap_offset
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_eio
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_pread
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: perf_pmu
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_userptr_blits
results: parsing output: 67/ for test: i915_drm_fdinfo
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: gem_exec_flush
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: gem_mmap
results: parsing output: 72/ for test: kms_psr2_sf
results: parsing output: 73/ for test: perf_pmu
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: gem_unfence_active_buffers
results: parsing output: 78/ for test: gem_exec_schedule
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_legacy_colorkey
results: parsing output: 82/ for test: prime_mmap_kms
results: parsing output: 83/ for test: kms_pm_rpm
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_feature_discovery
results: parsing output: 87/ for test: kms_flip
results: parsing output: 88/ for test: i915_pm_rps
results: parsing output: 89/ for test: gem_ctx_persistence
results: parsing output: 90/ for test: kms_plane_scaling
results: parsing output: 91/ for test: gem_userptr_blits
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_ctx_persistence
results: parsing output: 94/ for test: gem_exec_store
results: parsing output: 95/ for test: gem_exec_reloc
results: parsing output: 96/ for test: gem_ctx_engines
results: parsing output: 97/ for test: kms_vblank
results: parsing output: 98/ for test: api_intel_allocator
results: parsing output: 99/ for test: prime_mmap
results: parsing output: 100/ for test: kms_ccs
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_cursor_legacy
results: parsing output: 103/ for test: i915_drm_fdinfo
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_cursor_legacy
results: parsing output: 107/ for test: kms_sequence
results: parsing output: 108/ for test: gem_exec_nop
results: parsing output: 109/ for test: gem_exec_params
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: kms_atomic_transition
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: kms_plane
results: parsing output: 115/ for test: gem_set_tiling_vs_gtt
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: kms_hdr
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_render_copy
results: parsing output: 123/ for test: gem_exec_schedule
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: kms_chamelium_hpd
results: parsing output: 126/ for test: dmabuf_sync_file
results: parsing output: 127/ for test: gem_exec_fence
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: i915_pm_rc6_residency
Done.