[62.193780] Initializing watchdogs
[62.193824]   /dev/watchdog0
[62.207079] [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]
[62.211375] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.213567] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.216943] [001/133] (960s left) kms_plane_lowres (tiling-4)
[62.349805] Starting subtest: tiling-4
[62.352059] Starting dynamic subtest: pipe-A-eDP-1
[62.356517] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[62.647982] Starting dynamic subtest: pipe-B-eDP-1
[62.652646] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[62.659401] Starting dynamic subtest: pipe-C-eDP-1
[62.664170] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[62.671465] Starting dynamic subtest: pipe-D-eDP-1
[62.676236] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[62.680756] Subtest tiling-4: SKIP (0.299s)
[63.447704] [002/133] (959s left) kms_pm_rpm (system-suspend-idle)
[65.097137] Starting subtest: system-suspend-idle
[85.203609] Subtest system-suspend-idle: SUCCESS (5.790s)
[85.592069] [003/133] (937s left) kms_flip (2x-flip-vs-modeset)
[85.697823] Starting subtest: 2x-flip-vs-modeset
[85.710836] Subtest 2x-flip-vs-modeset: SKIP (0.014s)
[85.790973] [004/133] (937s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[86.324949] Starting subtest: syncobj-timeline-invalid-flags
[86.327704] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[87.361164] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[87.364872] [005/133] (935s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[87.465001] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[87.469140] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[87.581823] [006/133] (935s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[87.680100] Starting subtest: fbc-pr-sprite-mmap-cpu
[87.684150] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[87.750056] [007/133] (935s left) kms_cursor_legacy (forked-move)
[87.872610] Starting subtest: forked-move
[87.875049] Starting dynamic subtest: pipe-A
[93.290767] Dynamic subtest pipe-A: SUCCESS (5.472s)
[93.301803] Starting dynamic subtest: pipe-B
[98.748730] Dynamic subtest pipe-B: SUCCESS (5.485s)
[98.759601] Starting dynamic subtest: pipe-C
[104.209023] Dynamic subtest pipe-C: SUCCESS (5.472s)
[104.219608] Starting dynamic subtest: pipe-D
[109.672928] Dynamic subtest pipe-D: SUCCESS (5.474s)
[109.682891] Starting dynamic subtest: all-pipes
[115.162914] Dynamic subtest all-pipes: SUCCESS (5.491s)
[115.165513] Subtest forked-move: SUCCESS (27.399s)
[115.303227] [008/133] (907s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[115.414602] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[121.971962] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.557s)
[122.086252] [009/133] (900s left) gem_bad_reloc (negative-reloc-bltcopy)
[122.142104] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[122.227813] [010/133] (900s left) kms_flip (2x-flip-vs-suspend-interruptible)
[122.317586] Starting subtest: 2x-flip-vs-suspend-interruptible
[122.330126] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.010s)
[122.406536] [011/133] (900s left) gem_mmap_wc (close)
[122.467714] Subtest close: SKIP (0.000s)
[122.534409] [012/133] (900s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[122.637489] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[122.641636] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[122.733399] [013/133] (900s left) dmabuf_sync_file (import-multiple-read-write)
[122.775880] Starting subtest: import-multiple-read-write
[122.778034] Subtest import-multiple-read-write: SUCCESS (0.003s)
[122.829325] [014/133] (900s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[124.455655] Starting subtest: gem-execbuf-stress-pc8
[124.497238] Subtest gem-execbuf-stress-pc8: SKIP (0.041s)
[125.092899] [015/133] (898s left) gem_exec_fence (syncobj-wait)
[125.493930] Starting subtest: syncobj-wait
[127.303777] Subtest syncobj-wait: SUCCESS (1.808s)
[127.651310] [016/133] (895s left) drm_read (empty-nonblock)
[127.813308] Starting subtest: empty-nonblock
[127.815698] Subtest empty-nonblock: SUCCESS (0.000s)
[127.952218] [017/133] (895s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[128.060423] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[129.061567] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.001s)
[129.208887] [018/133] (894s left) gem_mmap_gtt (close-race)
[129.270109] Subtest close-race: SKIP (0.000s)
[129.335110] [019/133] (894s left) gem_exec_schedule (implicit-write-read)
[129.405112] Starting subtest: implicit-write-read
[129.407057] Starting dynamic subtest: rcs0
[129.511557] Dynamic subtest rcs0: SUCCESS (0.114s)
[129.524036] Starting dynamic subtest: vcs0
[129.589536] Dynamic subtest vcs0: SUCCESS (0.078s)
[129.600014] Starting dynamic subtest: vcs1
[129.667130] Dynamic subtest vcs1: SUCCESS (0.077s)
[129.679156] Starting dynamic subtest: vecs0
[129.751173] Dynamic subtest vecs0: SUCCESS (0.084s)
[129.761224] Starting dynamic subtest: ccs0
[129.837455] Dynamic subtest ccs0: SUCCESS (0.086s)
[129.839548] Subtest implicit-write-read: SUCCESS (0.440s)
[131.363001] [020/133] (892s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[131.425528] Starting subtest: multi-wait-all-for-submit-signaled
[131.428202] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[131.534549] [021/133] (892s left) gem_exec_balancer (smoke)
[131.599689] Starting subtest: smoke
[154.196602] Subtest smoke: SUCCESS (22.600s)
[155.392424] [022/133] (868s left) kms_chamelium_frames (hdmi-crc-multiple)
[155.777387] Subtest hdmi-crc-multiple: SKIP (0.000s)
[156.598182] [023/133] (867s left) prime_busy (after-wait)
[156.652479] Starting subtest: after-wait
[156.654542] Starting dynamic subtest: rcs0
[156.682744] Dynamic subtest rcs0: SUCCESS (0.028s)
[156.693060] Starting dynamic subtest: bcs0
[156.723756] Dynamic subtest bcs0: SUCCESS (0.041s)
[156.732774] Starting dynamic subtest: vcs0
[156.763952] Dynamic subtest vcs0: SUCCESS (0.040s)
[156.773034] Starting dynamic subtest: vcs1
[156.804798] Dynamic subtest vcs1: SUCCESS (0.041s)
[156.813884] Starting dynamic subtest: vecs0
[156.845679] Dynamic subtest vecs0: SUCCESS (0.041s)
[156.854972] Starting dynamic subtest: ccs0
[156.886699] Dynamic subtest ccs0: SUCCESS (0.041s)
[156.994852] Subtest after-wait: SUCCESS (0.341s)
[157.070612] [024/133] (866s left) i915_hangman (error-state-basic)
[157.129423] Starting subtest: error-state-basic
[157.280245] Subtest error-state-basic: SUCCESS (0.153s)
[157.352945] [025/133] (866s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[157.453228] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[157.455556] Starting dynamic subtest: pipe-A-eDP-1
[157.478891] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[157.489543] Starting dynamic subtest: pipe-B-eDP-1
[157.511207] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[157.519449] Starting dynamic subtest: pipe-C-eDP-1
[157.541766] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[157.549876] Starting dynamic subtest: pipe-D-eDP-1
[157.572020] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[157.576124] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[159.682163] [026/133] (864s left) kms_chamelium_hpd (vga-hpd)
[160.081903] Subtest vga-hpd: SKIP (0.000s)
[160.896485] [027/133] (862s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[160.956432] Starting subtest: multi-wait-all-for-submit-signaled
[160.958896] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.002s)
[161.061915] [028/133] (862s left) i915_drm_fdinfo (busy-hang)
[161.104186] Starting subtest: busy-hang
[161.106515] Starting dynamic subtest: rcs0
[161.133424] Dynamic subtest rcs0: SKIP (0.029s)
[161.141742] Starting dynamic subtest: bcs0
[161.145806] Dynamic subtest bcs0: SKIP (0.000s)
[161.154259] Starting dynamic subtest: vcs0
[161.158582] Dynamic subtest vcs0: SKIP (0.000s)
[161.166441] Starting dynamic subtest: vcs1
[161.170538] Dynamic subtest vcs1: SKIP (0.000s)
[161.179214] Starting dynamic subtest: vecs0
[161.183150] Dynamic subtest vecs0: SKIP (0.000s)
[161.191003] Starting dynamic subtest: ccs0
[161.195060] Dynamic subtest ccs0: SKIP (0.000s)
[161.199068] Subtest busy-hang: SKIP (0.032s)
[161.241429] [029/133] (862s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[161.366278] Starting subtest: cursorA-vs-flipA-toggle
[172.487728] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.125s)
[173.358345] [030/133] (850s left) gem_exec_schedule (thriceslice)
[173.428358] Starting subtest: thriceslice
[173.430340] Starting dynamic subtest: rcs0
[173.449015] Dynamic subtest rcs0: SUCCESS (0.030s)
[173.459845] Starting dynamic subtest: bcs0
[173.477987] Dynamic subtest bcs0: SUCCESS (0.028s)
[173.486104] Starting dynamic subtest: vcs0
[173.529472] Dynamic subtest vcs0: SUCCESS (0.050s)
[173.542238] Starting dynamic subtest: vcs1
[173.558526] Dynamic subtest vcs1: SUCCESS (0.028s)
[173.568706] Starting dynamic subtest: vecs0
[173.612318] Dynamic subtest vecs0: SUCCESS (0.053s)
[173.621327] Starting dynamic subtest: ccs0
[173.644666] Dynamic subtest ccs0: SUCCESS (0.031s)
[173.647213] Subtest thriceslice: SUCCESS (0.227s)
[173.950716] [031/133] (850s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[174.012313] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[174.091889] [032/133] (849s left) kms_psr (pr-basic)
[174.185901] Starting subtest: pr-basic
[174.189964] Subtest pr-basic: SKIP (0.000s)
[174.260954] [033/133] (849s left) gem_tiled_blits (basic)
[174.326264] Subtest basic: SKIP (0.000s)
[174.395840] [034/133] (849s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[174.483121] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[174.487582] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[174.566862] [035/133] (849s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[174.663521] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[174.667766] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[174.745402] [036/133] (849s left) gem_mmap_offset (partial-remap)
[174.792644] Starting subtest: partial-remap
[174.794969] Starting dynamic subtest: smem0
[174.803629] Dynamic subtest smem0: SUCCESS (0.004s)
[174.805587] Subtest partial-remap: SUCCESS (0.004s)
[174.878064] [037/133] (849s left) perf (low-oa-exponent-permissions)
[174.998746] Starting subtest: low-oa-exponent-permissions
[175.038362] Subtest low-oa-exponent-permissions: SUCCESS (0.040s)
[175.088147] [038/133] (849s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[175.187525] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[182.201876] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.015s)
[182.335990] [039/133] (841s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[182.451156] Starting subtest: cursorB-vs-flipB-atomic
[182.455716] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[182.594324] [040/133] (841s left) syncobj_timeline (device-submit-unordered)
[182.648004] Starting subtest: device-submit-unordered
[182.650372] Subtest device-submit-unordered: SUCCESS (0.001s)
[182.732279] [041/133] (841s left) gem_ctx_bad_destroy (invalid-default-ctx)
[182.777534] Starting subtest: invalid-default-ctx
[182.779873] Subtest invalid-default-ctx: SUCCESS (0.000s)
[182.842366] [042/133] (841s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[182.948092] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[182.952393] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[183.050271] [043/133] (841s left) gem_gpgpu_fill (basic)
[183.109955] Starting subtest: basic
[183.112380] Starting dynamic subtest: smem
[183.117298] Dynamic subtest smem: SUCCESS (0.007s)
[183.119230] Subtest basic: SUCCESS (0.007s)
[183.249520] [044/133] (841s left) gem_exec_parallel (basic)
[183.308488] Starting subtest: basic
[183.310643] Starting dynamic subtest: rcs0
[184.796289] Dynamic subtest rcs0: SUCCESS (1.486s)
[184.807537] Starting dynamic subtest: bcs0
[186.259296] Dynamic subtest bcs0: SUCCESS (1.460s)
[186.269592] Starting dynamic subtest: vcs0
[188.277380] Dynamic subtest vcs0: SUCCESS (2.014s)
[188.287821] Starting dynamic subtest: vcs1
[190.260654] Dynamic subtest vcs1: SUCCESS (1.980s)
[190.269928] Starting dynamic subtest: vecs0
[192.307941] Dynamic subtest vecs0: SUCCESS (2.045s)
[192.316699] Starting dynamic subtest: ccs0
[193.775735] Dynamic subtest ccs0: SUCCESS (1.467s)
[193.778189] Subtest basic: SUCCESS (10.464s)
[193.977770] [045/133] (830s left) gem_exec_params (invalid-bsd2-flag-on-render)
[194.042218] Starting subtest: invalid-bsd2-flag-on-render
[194.044764] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[194.151358] [046/133] (830s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[194.250662] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[201.612376] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.364s)
[201.725470] [047/133] (822s left) kms_vblank (ts-continuation-dpms-suspend)
[201.825209] Starting subtest: ts-continuation-dpms-suspend
[201.827096] Starting dynamic subtest: pipe-A-eDP-1
[219.526689] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.846s)
[219.540784] Starting dynamic subtest: pipe-D-eDP-1
[237.515485] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.517s)
[237.517800] Subtest ts-continuation-dpms-suspend: SUCCESS (4.366s)
[238.317134] [048/133] (786s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[238.415753] Starting subtest: bad-pixel-format-y-tiled-ccs
[238.417935] Starting dynamic subtest: pipe-A-eDP-1
[238.439583] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[238.448749] Starting dynamic subtest: pipe-B-eDP-1
[238.468216] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[238.475858] Starting dynamic subtest: pipe-C-eDP-1
[238.496153] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[238.503779] Starting dynamic subtest: pipe-D-eDP-1
[238.524945] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[238.529217] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[240.625346] [049/133] (784s left) gem_userptr_blits (huge-split)
[240.696038] Starting subtest: huge-split
[240.700819] Subtest huge-split: SUCCESS (0.007s)
[240.807329] [050/133] (783s left) gem_pwrite (basic-random)
[240.854017] Subtest basic-random: SKIP (0.000s)
[240.908844] [051/133] (783s left) kms_psr (psr2-primary-mmap-gtt)
[240.997022] Starting subtest: psr2-primary-mmap-gtt
[240.999163] Starting dynamic subtest: eDP-1
[242.397974] Dynamic subtest eDP-1: SKIP (1.415s)
[242.402782] Subtest psr2-primary-mmap-gtt: SKIP (1.417s)
[242.539087] [052/133] (782s left) gem_ctx_shared (q-independent)
[242.594857] Starting subtest: Q-independent
[242.597308] Starting dynamic subtest: rcs0
[242.662933] Dynamic subtest rcs0: SUCCESS (0.068s)
[242.674754] Starting dynamic subtest: vcs0
[242.708593] Dynamic subtest vcs0: SUCCESS (0.046s)
[242.720275] Starting dynamic subtest: vcs1
[242.744901] Dynamic subtest vcs1: SUCCESS (0.036s)
[242.753402] Starting dynamic subtest: vecs0
[242.779923] Dynamic subtest vecs0: SUCCESS (0.035s)
[242.788866] Starting dynamic subtest: ccs0
[242.821467] Dynamic subtest ccs0: SUCCESS (0.041s)
[242.823941] Subtest Q-independent: SUCCESS (0.229s)
[244.782257] [053/133] (780s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[244.867321] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[244.869458] Starting dynamic subtest: pipe-A-eDP-1
[245.724899] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.858s)
[245.734129] Starting dynamic subtest: pipe-B-eDP-1
[247.266488] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.550s)
[247.278841] Starting dynamic subtest: pipe-C-eDP-1
[248.721997] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.462s)
[248.731781] Starting dynamic subtest: pipe-D-eDP-1
[250.242680] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.527s)
[250.244954] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.401s)
[251.420917] [054/133] (773s left) kms_flip (flip-vs-rmfb)
[251.523991] Starting subtest: flip-vs-rmfb
[251.526492] Starting dynamic subtest: A-eDP1
[260.225983] Dynamic subtest A-eDP1: SUCCESS (8.726s)
[260.234331] Starting dynamic subtest: B-eDP1
[268.925593] Dynamic subtest B-eDP1: SUCCESS (8.705s)
[268.936914] Starting dynamic subtest: C-eDP1
[277.556000] Dynamic subtest C-eDP1: SUCCESS (8.622s)
[277.566896] Starting dynamic subtest: D-eDP1
[286.186668] Dynamic subtest D-eDP1: SUCCESS (8.626s)
[286.192501] Subtest flip-vs-rmfb: SUCCESS (34.694s)
[287.388480] [055/133] (737s left) gem_shrink (reclaim)
[287.460687] Starting subtest: reclaim
[289.868202] Subtest reclaim: SUCCESS (2.405s)
[290.002923] [056/133] (734s left) gem_tiled_swapping (non-threaded)
[290.062575] Subtest non-threaded: SKIP (0.000s)
[290.129186] [057/133] (734s left) kms_cursor_crc (cursor-onscreen-256x85)
[290.394561] Starting subtest: cursor-onscreen-256x85
[290.445389] Subtest cursor-onscreen-256x85: SKIP (0.044s)
[290.606342] [058/133] (734s left) gen7_exec_parse (batch-without-end)
[290.670109] Subtest batch-without-end: SKIP (0.000s)
[290.759348] [059/133] (734s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[290.853040] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[291.923289] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.069s)
[292.041717] [060/133] (733s left) kms_color (degamma)
[292.137472] Starting subtest: degamma
[292.139290] Starting dynamic subtest: pipe-A-eDP-1
[292.860077] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[292.870219] Starting dynamic subtest: pipe-B-eDP-1
[294.298141] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.438s)
[294.307589] Starting dynamic subtest: pipe-C-eDP-1
[295.664222] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.368s)
[295.673314] Starting dynamic subtest: pipe-D-eDP-1
[297.011996] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.346s)
[297.014375] Subtest degamma: SUCCESS (4.875s)
[297.822968] [061/133] (727s left) gem_userptr_blits (unsync-unmap-cycles)
[297.909374] Subtest unsync-unmap-cycles: SKIP (0.000s)
[298.042663] [062/133] (727s left) syncobj_timeline (signal-array)
[298.097735] Starting subtest: signal-array
[298.100201] Subtest signal-array: SUCCESS (0.000s)
[298.207328] [063/133] (727s left) kms_lease (lease-unleased-connector)
[298.301383] Starting subtest: lease-unleased-connector
[298.303744] Starting dynamic subtest: pipe-A-eDP-1
[298.306034] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[298.318359] Starting dynamic subtest: pipe-B-eDP-1
[298.320687] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[298.329936] Starting dynamic subtest: pipe-C-eDP-1
[298.332208] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[298.340790] Starting dynamic subtest: pipe-D-eDP-1
[298.343015] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.009s)
[298.345154] Subtest lease-unleased-connector: SUCCESS (0.033s)
[298.427604] [064/133] (726s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[298.489881] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[298.492157] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[298.579305] [065/133] (726s left) gem_exec_reloc (basic-write-gtt-noreloc)
[298.636423] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[298.709927] [066/133] (726s left) gem_fenced_exec_thrash (no-spare-fences)
[298.759149] Subtest no-spare-fences: SKIP (0.000s)
[298.828914] [067/133] (726s left) gem_render_copy_redux (flink)
[298.880097] Starting subtest: flink
[299.608089] Subtest flink: SUCCESS (0.728s)
[299.729124] [068/133] (725s left) api_intel_bb (destroy-bb)
[299.783477] Starting subtest: destroy-bb
[299.793781] Subtest destroy-bb: SUCCESS (0.011s)
[299.917713] [069/133] (725s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[300.016768] Starting subtest: y-tiled-64bpp-rotate-90
[300.020958] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[300.114469] [070/133] (725s left) gem_pxp (verify-pxp-stale-ctx-execution)
[300.230826] Starting subtest: verify-pxp-stale-ctx-execution
[301.291068] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.060s)
[301.407396] [071/133] (724s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[301.677279] Starting subtest: cursor-rapid-movement-512x512
[301.681860] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[301.843851] [072/133] (723s left) gem_eio (in-flight-suspend)
[302.060318] Starting subtest: in-flight-suspend
[345.263352] Subtest in-flight-suspend: SUCCESS (13.607s)
[345.379370] [073/133] (680s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[345.480458] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[345.484722] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[345.562745] [074/133] (680s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[345.654148] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[345.658641] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[346.756189] [075/133] (679s left) kms_pm_rpm (basic-pci-d3-state)
[348.373391] Starting subtest: basic-pci-d3-state
[349.853930] Subtest basic-pci-d3-state: SUCCESS (1.501s)
[349.942749] [076/133] (675s left) gem_ctx_param (vm)
[349.987847] Starting subtest: vm
[350.036892] Subtest vm: SUCCESS (0.049s)
[350.146930] [077/133] (675s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[350.241601] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[351.253568] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.024s)
[351.362999] [078/133] (674s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[351.461534] Starting subtest: fbc-indfb-scaledprimary
[354.434236] Subtest fbc-indfb-scaledprimary: SUCCESS (3.001s)
[355.560235] [079/133] (670s left) kms_content_protection (uevent)
[355.697622] Starting subtest: uevent
[355.702313] Subtest uevent: SKIP (0.000s)
[355.820382] [080/133] (670s left) gem_spin_batch (spin-all-new)
[355.881089] Starting subtest: spin-all-new
[355.895486] Subtest spin-all-new: SUCCESS (0.014s)
[357.264259] [081/133] (668s left) kms_psr (fbc-pr-basic)
[357.354611] Starting subtest: fbc-pr-basic
[357.359171] Subtest fbc-pr-basic: SKIP (0.000s)
[357.443866] [082/133] (668s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[357.505310] Starting subtest: yf-tiled-to-vebox-x-tiled
[357.509693] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[357.701589] [083/133] (668s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[357.796366] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[357.800890] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[357.870544] [084/133] (668s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[357.959951] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[357.962288] Starting dynamic subtest: pipe-A
[357.981883] Dynamic subtest pipe-A: SKIP (0.017s)
[357.991802] Starting dynamic subtest: pipe-B
[358.012063] Dynamic subtest pipe-B: SKIP (0.031s)
[358.021245] Starting dynamic subtest: pipe-C
[358.046535] Dynamic subtest pipe-C: SKIP (0.034s)
[358.055264] Starting dynamic subtest: pipe-D
[358.075653] Dynamic subtest pipe-D: SKIP (0.030s)
[358.079905] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.114s)
[358.169351] [085/133] (668s left) gem_close (many-handles-one-vma)
[358.226128] Starting subtest: many-handles-one-vma
[358.249839] Subtest many-handles-one-vma: SUCCESS (0.024s)
[358.365237] [086/133] (667s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[358.451383] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[358.455221] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[358.559791] [087/133] (667s left) kms_plane (planar-pixel-format-settings)
[358.651303] Starting subtest: planar-pixel-format-settings
[358.720900] Subtest planar-pixel-format-settings: SUCCESS (0.071s)
[358.821868] [088/133] (667s left) i915_pm_rps (thresholds-idle-park)
[358.880524] Starting subtest: thresholds-idle-park
[358.884158] Subtest thresholds-idle-park: SKIP (0.001s)
[358.976436] [089/133] (667s left) gem_exec_params (no-blt)
[359.042517] Starting subtest: no-blt
[359.044098] Subtest no-blt: SUCCESS (0.002s)
[359.169235] [090/133] (667s left) gem_exec_reloc (basic-cpu-noreloc)
[359.231650] Subtest basic-cpu-noreloc: SKIP (0.000s)
[359.307166] [091/133] (667s left) sw_sync (sync_busy_fork_unixsocket)
[359.344536] Starting subtest: sync_busy_fork_unixsocket
[359.348709] Subtest sync_busy_fork_unixsocket: SUCCESS (0.007s)
[359.406633] [092/133] (667s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[359.502017] Starting subtest: random-ccs-data-yf-tiled-ccs
[359.504004] Starting dynamic subtest: pipe-A-eDP-1
[359.524851] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[359.533982] Starting dynamic subtest: pipe-B-eDP-1
[359.553204] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[359.560884] Starting dynamic subtest: pipe-C-eDP-1
[359.579583] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[359.588096] Starting dynamic subtest: pipe-D-eDP-1
[359.607872] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[359.611289] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[361.840455] [093/133] (664s left) gem_flink_basic (basic)
[361.913347] Starting subtest: basic
[361.915280] Subtest basic: SUCCESS (0.000s)
[362.008356] [094/133] (664s left) tools_test (tools_test)
[362.056861] Starting subtest: tools_test
[362.102507] Subtest tools_test: SUCCESS (0.059s)
[362.163706] [095/133] (664s left) gem_cs_tlb (engines)
[362.212338] Starting subtest: engines
[362.214649] Starting dynamic subtest: rcs0
[364.363142] Dynamic subtest rcs0: SUCCESS (2.153s)
[364.374761] Starting dynamic subtest: bcs0
[366.517108] Dynamic subtest bcs0: SUCCESS (2.154s)
[366.528659] Starting dynamic subtest: vcs0
[368.665829] Dynamic subtest vcs0: SUCCESS (2.153s)
[368.677150] Starting dynamic subtest: vcs1
[370.815951] Dynamic subtest vcs1: SUCCESS (2.153s)
[370.825459] Starting dynamic subtest: vecs0
[372.967294] Dynamic subtest vecs0: SUCCESS (2.153s)
[372.979249] Starting dynamic subtest: ccs0
[375.119645] Dynamic subtest ccs0: SUCCESS (2.154s)
[375.122085] Subtest engines: SUCCESS (12.926s)
[375.233009] [096/133] (651s left) gem_ctx_persistence (legacy-engines-mixed-process)
[375.287590] Starting subtest: legacy-engines-mixed-process
[375.289822] Starting dynamic subtest: render
[377.273624] Dynamic subtest render: SUCCESS (1.984s)
[377.284989] Starting dynamic subtest: bsd1
[379.242913] Dynamic subtest bsd1: SUCCESS (1.967s)
[379.254125] Starting dynamic subtest: bsd2
[381.235308] Dynamic subtest bsd2: SUCCESS (1.990s)
[381.249277] Starting dynamic subtest: blt
[383.235508] Dynamic subtest blt: SUCCESS (1.994s)
[383.246756] Starting dynamic subtest: vebox
[385.265758] Dynamic subtest vebox: SUCCESS (2.031s)
[385.268240] Subtest legacy-engines-mixed-process: SUCCESS (9.976s)
[385.354936] [097/133] (641s left) perf_pmu (most-busy-idle-check-all)
[385.403098] Starting subtest: most-busy-idle-check-all
[385.405123] Starting dynamic subtest: rcs0
[387.242649] Dynamic subtest rcs0: SUCCESS (1.839s)
[387.255775] Starting dynamic subtest: bcs0
[389.226425] Dynamic subtest bcs0: SUCCESS (1.980s)
[389.237677] Starting dynamic subtest: vcs0
[391.274328] Dynamic subtest vcs0: SUCCESS (2.045s)
[391.286273] Starting dynamic subtest: vcs1
[393.258889] Dynamic subtest vcs1: SUCCESS (1.981s)
[393.270806] Starting dynamic subtest: vecs0
[395.242865] Dynamic subtest vecs0: SUCCESS (1.982s)
[395.255026] Starting dynamic subtest: ccs0
[397.227020] Dynamic subtest ccs0: SUCCESS (1.981s)
[397.229738] Subtest most-busy-idle-check-all: SUCCESS (11.827s)
[397.337658] [098/133] (629s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[397.439808] Starting subtest: fbc-psr-sprite-mmap-gtt
[397.442141] Starting dynamic subtest: eDP-1
[397.446312] Dynamic subtest eDP-1: SKIP (0.000s)
[397.450576] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[397.525801] [099/133] (629s left) sysfs_heartbeat_interval (idempotent)
[397.590401] Starting subtest: idempotent
[397.592649] Starting dynamic subtest: vecs0
[397.594845] Dynamic subtest vecs0: SUCCESS (0.001s)
[397.605590] Starting dynamic subtest: ccs0
[397.607718] Dynamic subtest ccs0: SUCCESS (0.001s)
[397.616508] Starting dynamic subtest: vcs0
[397.618641] Dynamic subtest vcs0: SUCCESS (0.000s)
[397.627235] Starting dynamic subtest: bcs0
[397.629383] Dynamic subtest bcs0: SUCCESS (0.001s)
[397.637650] Starting dynamic subtest: rcs0
[397.639571] Dynamic subtest rcs0: SUCCESS (0.001s)
[397.648226] Starting dynamic subtest: vcs1
[397.650294] Dynamic subtest vcs1: SUCCESS (0.000s)
[397.652325] Subtest idempotent: SUCCESS (0.008s)
[397.688488] [100/133] (629s left) perf (gen8-unprivileged-single-ctx-counters)
[397.778739] Starting subtest: gen8-unprivileged-single-ctx-counters
[397.783081] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[397.822020] [101/133] (629s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[397.921782] Starting subtest: stress-xrgb8888-ytiled
[397.926122] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[397.994468] [102/133] (628s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[398.059252] Starting subtest: basic-batch-kernel-default-cmd
[398.063903] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[398.241893] [103/133] (628s left) gem_exec_reloc (basic-gtt-read)
[398.301130] Subtest basic-gtt-read: SKIP (0.000s)
[398.382509] [104/133] (628s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[398.471910] Starting subtest: 4-tiled-16bpp-rotate-0
[399.862717] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.391s)
[399.973437] [105/133] (627s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[400.069030] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[401.042074] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (0.973s)
[401.162838] [106/133] (625s left) kms_invalid_mode (clock-too-high)
[401.256547] Starting subtest: clock-too-high
[401.258848] Starting dynamic subtest: pipe-A-eDP-1
[401.263259] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[401.272630] Starting dynamic subtest: pipe-B-eDP-1
[401.276966] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[401.286081] Starting dynamic subtest: pipe-C-eDP-1
[401.290174] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[401.299329] Starting dynamic subtest: pipe-D-eDP-1
[401.303329] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[401.307326] Subtest clock-too-high: SKIP (0.004s)
[401.344587] [107/133] (625s left) kms_psr (psr-primary-render)
[401.435215] Starting subtest: psr-primary-render
[401.437115] Starting dynamic subtest: eDP-1
[403.170665] Dynamic subtest eDP-1: SUCCESS (1.735s)
[403.172796] Subtest psr-primary-render: SUCCESS (1.736s)
[404.044822] [108/133] (623s left) syncobj_wait (invalid-signal-bad-pad)
[404.107471] Starting subtest: invalid-signal-bad-pad
[404.109663] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[404.199798] [109/133] (622s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[404.291591] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[404.295826] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[404.373453] [110/133] (622s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[404.463553] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[411.316665] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.856s)
[411.466751] [111/133] (615s left) syncobj_timeline (multi-wait-all-available-submitted)
[411.523081] Starting subtest: multi-wait-all-available-submitted
[411.525288] Subtest multi-wait-all-available-submitted: SUCCESS (0.000s)
[411.616781] [112/133] (615s left) gem_busy (extended)
[411.793792] Starting subtest: extended
[411.796265] Starting dynamic subtest: rcs0
[411.869795] Dynamic subtest rcs0: SUCCESS (0.076s)
[411.882036] Starting dynamic subtest: bcs0
[411.911552] Dynamic subtest bcs0: SUCCESS (0.040s)
[411.922171] Starting dynamic subtest: vcs0
[413.289799] Dynamic subtest vcs0: SUCCESS (1.375s)
[413.301067] Starting dynamic subtest: vcs1
[415.273864] Dynamic subtest vcs1: SUCCESS (1.981s)
[415.286107] Starting dynamic subtest: vecs0
[417.258581] Dynamic subtest vecs0: SUCCESS (1.982s)
[417.271147] Starting dynamic subtest: ccs0
[417.304407] Dynamic subtest ccs0: SUCCESS (0.045s)
[417.306960] Subtest extended: SUCCESS (5.515s)
[417.532555] [113/133] (609s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[417.634646] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[417.638863] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[417.738215] [114/133] (609s left) prime_vgem (fence-write-hang)
[417.821071] Subtest fence-write-hang: SKIP (0.000s)
[417.919505] [115/133] (609s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[418.015799] Starting subtest: primary-x-tiled-reflect-x-180
[425.014595] Subtest primary-x-tiled-reflect-x-180: SUCCESS (7.002s)
[426.255639] [116/133] (601s left) gem_exec_whisper (basic-contexts-priority-all)
[426.318487] Starting subtest: basic-contexts-priority-all
[449.483554] Subtest basic-contexts-priority-all: SUCCESS (23.165s)
[449.685009] [117/133] (577s left) gem_exec_flush (basic-wb-rw-default)
[449.749831] Starting subtest: basic-wb-rw-default
[455.223971] Subtest basic-wb-rw-default: SUCCESS (5.473s)
[455.413383] [118/133] (572s left) kms_addfb_basic (basic-x-tiled-legacy)
[455.466133] Starting subtest: basic-x-tiled-legacy
[455.470210] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[455.621645] [119/133] (571s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[455.740206] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[455.784386] [120/133] (571s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[455.871565] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[455.875997] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[455.997311] [121/133] (571s left) perf (polling)
[456.096834] Starting subtest: polling
[456.099262] Starting dynamic subtest: 0-rcs0
[466.129374] Dynamic subtest 0-rcs0: SUCCESS (10.029s)
[466.139670] Starting dynamic subtest: 1-vecs0
[476.149761] Dynamic subtest 1-vecs0: SUCCESS (10.019s)
[476.152201] Subtest polling: SUCCESS (20.051s)
[476.209251] [122/133] (551s left) gem_exec_balancer (full-late-pulse)
[476.297965] Starting subtest: full-late-pulse
[481.121860] Subtest full-late-pulse: SUCCESS (4.824s)
[481.442193] [123/133] (546s left) kms_cursor_crc (cursor-sliding-64x21)
[481.651546] Starting subtest: cursor-sliding-64x21
[481.700697] Subtest cursor-sliding-64x21: SKIP (0.047s)
[481.960127] [124/133] (545s left) kms_hdr (invalid-metadata-sizes)
[482.054342] Starting subtest: invalid-metadata-sizes
[482.060797] Subtest invalid-metadata-sizes: SKIP (0.000s)
[482.123504] [125/133] (545s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[482.215421] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[482.219433] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[482.322139] [126/133] (545s left) perf_pmu (busy-accuracy-98)
[482.354467] Starting subtest: busy-accuracy-98
[482.356538] Starting dynamic subtest: rcs0
[485.398071] Dynamic subtest rcs0: SUCCESS (3.040s)
[485.410245] Starting dynamic subtest: bcs0
[488.452552] Dynamic subtest bcs0: SUCCESS (3.054s)
[488.461992] Starting dynamic subtest: vcs0
[491.499144] Dynamic subtest vcs0: SUCCESS (3.044s)
[491.508817] Starting dynamic subtest: vcs1
[494.542677] Dynamic subtest vcs1: SUCCESS (3.041s)
[494.552361] Starting dynamic subtest: vecs0
[497.588512] Dynamic subtest vecs0: SUCCESS (3.044s)
[497.600412] Starting dynamic subtest: ccs0
[500.642340] Dynamic subtest ccs0: SUCCESS (3.052s)
[500.644731] Subtest busy-accuracy-98: SUCCESS (18.290s)
[500.758167] [127/133] (527s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[500.862323] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[500.866688] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[500.945941] [128/133] (527s left) template (b)
[501.008515] Starting subtest: B
[501.010489] Subtest B: SUCCESS (0.000s)
[501.101556] [129/133] (526s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[501.191144] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[501.193243] Starting dynamic subtest: pipe-A-default-mode
[502.306248] Dynamic subtest pipe-A-default-mode: SKIP (1.111s)
[502.310257] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.113s)
[502.464137] [130/133] (525s left) kms_atomic_transition (plane-all-transition)
[502.563098] Starting subtest: plane-all-transition
[502.565225] Starting dynamic subtest: pipe-A-eDP-1
[505.058311] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[505.382093] Starting dynamic subtest: pipe-B-eDP-1
[508.612293] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.240s)
[508.927336] Subtest plane-all-transition: SUCCESS (6.364s)
[509.738002] [131/133] (518s left) kms_draw_crc (draw-method-pwrite)
[509.845441] Starting subtest: draw-method-pwrite
[509.847675] Starting dynamic subtest: xrgb8888-untiled
[510.419820] Dynamic subtest xrgb8888-untiled: SUCCESS (0.577s)
[510.429932] Starting dynamic subtest: rgb565-untiled
[510.842165] Dynamic subtest rgb565-untiled: SUCCESS (0.422s)
[510.852096] Starting dynamic subtest: xrgb2101010-untiled
[511.386675] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.544s)
[511.396369] Starting dynamic subtest: xbgr16161616f-untiled
[512.130548] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.743s)
[512.143502] Starting dynamic subtest: xrgb8888-xtiled
[513.885506] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.755s)
[513.897655] Starting dynamic subtest: rgb565-xtiled
[514.929970] Dynamic subtest rgb565-xtiled: SUCCESS (1.042s)
[514.941763] Starting dynamic subtest: xrgb2101010-xtiled
[516.674340] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.744s)
[516.684702] Starting dynamic subtest: xbgr16161616f-xtiled
[519.895848] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.220s)
[519.906103] Starting dynamic subtest: xrgb8888-4tiled
[520.973586] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.076s)
[520.983933] Starting dynamic subtest: rgb565-4tiled
[521.517618] Dynamic subtest rgb565-4tiled: SUCCESS (0.544s)
[521.530438] Starting dynamic subtest: xrgb2101010-4tiled
[522.573194] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.053s)
[522.582681] Starting dynamic subtest: xbgr16161616f-4tiled
[525.585415] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.010s)
[525.587722] Subtest draw-method-pwrite: SUCCESS (15.745s)
[525.697120] [132/133] (502s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[525.810857] Starting subtest: fbc-rgb565-draw-mmap-gtt
[525.815199] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[525.901009] [133/133] (502s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[525.994841] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[525.999257] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[526.086666] Closing watchdogs
results: parsing output: 0/ for test: kms_plane_lowres
results: parsing output: 1/ for test: kms_pm_rpm
results: parsing output: 2/ for test: kms_flip
results: parsing output: 3/ for test: gem_exec_fence
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_psr
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_bad_reloc
results: parsing output: 9/ for test: kms_flip
results: parsing output: 10/ for test: gem_mmap_wc
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: dmabuf_sync_file
results: parsing output: 13/ for test: i915_pm_rpm
results: parsing output: 14/ for test: gem_exec_fence
results: parsing output: 15/ for test: drm_read
results: parsing output: 16/ for test: kms_big_fb
results: parsing output: 17/ for test: gem_mmap_gtt
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: syncobj_wait
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_chamelium_frames
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: i915_hangman
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: kms_chamelium_hpd
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: gem_exec_schedule
results: parsing output: 30/ for test: gem_exec_reloc
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_tiled_blits
results: parsing output: 33/ for test: kms_plane
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: gem_mmap_offset
results: parsing output: 36/ for test: perf
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: gem_ctx_bad_destroy
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_gpgpu_fill
results: parsing output: 43/ for test: gem_exec_parallel
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_vblank
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: gem_userptr_blits
results: parsing output: 49/ for test: gem_pwrite
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: gem_ctx_shared
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: gem_shrink
results: parsing output: 55/ for test: gem_tiled_swapping
results: parsing output: 56/ for test: kms_cursor_crc
results: parsing output: 57/ for test: gen7_exec_parse
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_color
results: parsing output: 60/ for test: gem_userptr_blits
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_lease
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: gem_exec_reloc
results: parsing output: 65/ for test: gem_fenced_exec_thrash
results: parsing output: 66/ for test: gem_render_copy_redux
results: parsing output: 67/ for test: api_intel_bb
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: gem_pxp
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: gem_eio
results: parsing output: 72/ for test: kms_psr2_sf
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_pm_rpm
results: parsing output: 75/ for test: gem_ctx_param
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: gem_spin_batch
results: parsing output: 80/ for test: kms_psr
results: parsing output: 81/ for test: gem_render_copy
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: kms_plane_scaling
results: parsing output: 84/ for test: gem_close
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_plane
results: parsing output: 87/ for test: i915_pm_rps
results: parsing output: 88/ for test: gem_exec_params
results: parsing output: 89/ for test: gem_exec_reloc
results: parsing output: 90/ for test: sw_sync
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: gem_flink_basic
results: parsing output: 93/ for test: tools_test
results: parsing output: 94/ for test: gem_cs_tlb
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: perf_pmu
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: sysfs_heartbeat_interval
results: parsing output: 99/ for test: perf
results: parsing output: 100/ for test: kms_pipe_stress
results: parsing output: 101/ for test: gem_exec_flush
results: parsing output: 102/ for test: gem_exec_reloc
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_invalid_mode
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: syncobj_wait
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: gem_busy
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: prime_vgem
results: parsing output: 114/ for test: kms_rotation_crc
results: parsing output: 115/ for test: gem_exec_whisper
results: parsing output: 116/ for test: gem_exec_flush
results: parsing output: 117/ for test: kms_addfb_basic
results: parsing output: 118/ for test: kms_tiled_display
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: perf
results: parsing output: 121/ for test: gem_exec_balancer
results: parsing output: 122/ for test: kms_cursor_crc
results: parsing output: 123/ for test: kms_hdr
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: perf_pmu
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: template
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: kms_atomic_transition
results: parsing output: 130/ for test: kms_draw_crc
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.