[57.344720] Initializing watchdogs
[57.344820]   /dev/watchdog0
[57.359679] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.364711] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.367059] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.370749] [001/131] (960s left) gen9_exec_parse (bb-chained)
[57.450191] Subtest bb-chained: SKIP (0.000s)
[57.512604] [002/131] (960s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[57.615769] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[57.618130] Starting dynamic subtest: pipe-A-eDP-1
[57.641022] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[57.653139] Starting dynamic subtest: pipe-B-eDP-1
[57.675190] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[57.683438] Starting dynamic subtest: pipe-C-eDP-1
[57.705025] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.712700] Starting dynamic subtest: pipe-D-eDP-1
[57.734194] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.738491] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[59.870210] [003/131] (958s left) kms_properties (crtc-properties-atomic)
[59.971082] Starting subtest: crtc-properties-atomic
[59.973434] Starting dynamic subtest: pipe-A-eDP-1
[60.441315] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.475s)
[60.450936] Starting dynamic subtest: pipe-B-eDP-1
[61.592551] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.161s)
[61.600838] Starting dynamic subtest: pipe-C-eDP-1
[62.698702] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.115s)
[62.706959] Starting dynamic subtest: pipe-D-eDP-1
[63.830556] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.142s)
[63.832744] Subtest crtc-properties-atomic: SUCCESS (3.899s)
[64.642497] [004/131] (953s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[64.746060] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[64.750770] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[64.887239] [005/131] (953s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[64.975214] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[64.977533] Starting dynamic subtest: pipe-A-default-mode
[65.271348] Dynamic subtest pipe-A-default-mode: SKIP (0.293s)
[65.275976] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.294s)
[66.079024] [006/131] (952s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[66.182341] Starting subtest: psr-1p-primscrn-cur-indfb-move
[74.170877] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (8.019s)
[74.339715] [007/131] (943s left) kms_psr (psr2-primary-mmap-cpu)
[74.441532] Starting subtest: psr2-primary-mmap-cpu
[74.443925] Starting dynamic subtest: eDP-1
[76.136372] Dynamic subtest eDP-1: SUCCESS (1.698s)
[76.138755] Subtest psr2-primary-mmap-cpu: SUCCESS (1.699s)
[77.034129] [008/131] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[77.128697] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[77.132942] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[77.232995] [009/131] (940s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[77.340091] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[77.344516] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[77.477785] [010/131] (940s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[77.586866] Starting subtest: 4-tiled-addfb-size-offset-overflow
[77.589192] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[77.701813] [011/131] (940s left) gem_eio (wait-immediate)
[77.868834] Starting subtest: wait-immediate
[78.098078] Subtest wait-immediate: SUCCESS (0.231s)
[78.272226] [012/131] (940s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[83.280418] Starting subtest: pr-overlay-primary-update-sf-dmg-area
[83.284970] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[84.148228] [013/131] (934s left) gem_exec_alignment (many)
[84.210847] Starting subtest: many
[106.149073] Subtest many: SUCCESS (21.882s)
[108.557747] [014/131] (909s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[108.715820] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[113.366947] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (4.654s)
[114.566868] [015/131] (903s left) kms_psr (fbc-psr-cursor-blt)
[114.659309] Starting subtest: fbc-psr-cursor-blt
[114.661197] Starting dynamic subtest: eDP-1
[114.665329] Dynamic subtest eDP-1: SKIP (0.000s)
[114.669382] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[114.738926] [016/131] (903s left) gem_fence_thrash (bo-write-verify-threaded-y)
[114.774418] Starting subtest: bo-write-verify-threaded-y
[114.795135] Subtest bo-write-verify-threaded-y: SKIP (0.018s)
[114.854070] [017/131] (903s left) fbdev (unaligned-read)
[114.896031] Starting subtest: unaligned-read
[114.911483] Subtest unaligned-read: SUCCESS (0.015s)
[114.978367] [018/131] (903s left) kms_lease (lease-unleased-crtc)
[115.064167] Starting subtest: lease-unleased-crtc
[115.066608] Starting dynamic subtest: pipe-A-eDP-1
[115.359282] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[115.368178] Starting dynamic subtest: pipe-B-eDP-1
[116.091305] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.731s)
[116.100946] Starting dynamic subtest: pipe-C-eDP-1
[116.103285] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[116.111400] Starting dynamic subtest: pipe-D-eDP-1
[116.113302] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[116.115129] Subtest lease-unleased-crtc: SUCCESS (1.039s)
[116.484480] [019/131] (902s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[116.588712] Starting subtest: x-tiled-16bpp-rotate-0
[117.878219] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.291s)
[118.009558] [020/131] (900s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[123.059895] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[123.064594] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.003s)
[123.915761] [021/131] (894s left) gem_exec_balancer (bonded-false-hang)
[124.178463] Starting subtest: bonded-false-hang
[124.183420] Subtest bonded-false-hang: SKIP (0.000s)
[124.287261] [022/131] (894s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[124.381450] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[131.064763] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.685s)
[131.239327] [023/131] (887s left) gem_exec_params (mmapped)
[131.301966] Starting subtest: mmapped
[131.306968] Subtest mmapped: SUCCESS (0.003s)
[131.458742] [024/131] (887s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[131.562381] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[138.587760] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.029s)
[139.779295] [025/131] (879s left) gem_mmap_gtt (cpuset-big-copy)
[139.836261] Subtest cpuset-big-copy: SKIP (0.000s)
[139.895316] [026/131] (878s left) kms_psr (fbc-psr2-sprite-blt)
[139.997321] Starting subtest: fbc-psr2-sprite-blt
[139.999482] Starting dynamic subtest: eDP-1
[140.003692] Dynamic subtest eDP-1: SKIP (0.000s)
[140.008032] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[140.093082] [027/131] (878s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[140.187178] Starting subtest: flip-primary-invalidate-overlay
[151.828570] Subtest flip-primary-invalidate-overlay: SUCCESS (11.643s)
[152.016900] [028/131] (866s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[152.108494] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[152.111745] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[152.238427] [029/131] (866s left) kms_getfb (getfb-handle-protection)
[152.331118] Starting subtest: getfb-handle-protection
[152.355685] Subtest getfb-handle-protection: SUCCESS (0.025s)
[152.470348] [030/131] (866s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[152.531981] Starting subtest: multi-wait-all-for-submit-submitted
[152.640834] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.106s)
[152.736701] [031/131] (866s left) kms_flip (2x-plain-flip-ts-check)
[152.833369] Starting subtest: 2x-plain-flip-ts-check
[152.844488] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[152.945748] [032/131] (866s left) gem_pxp (reject-modify-context-protection-on)
[153.075805] Starting subtest: reject-modify-context-protection-on
[153.077901] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[153.208752] [033/131] (865s left) kms_chamelium_audio (dp-audio-edid)
[153.595479] Subtest dp-audio-edid: SKIP (0.000s)
[154.368761] [034/131] (864s left) sysfs_timeslice_duration (timeout)
[154.429579] Starting subtest: timeout
[154.431996] Starting dynamic subtest: vecs0
[163.930291] Dynamic subtest vecs0: SUCCESS (9.495s)
[163.939813] Starting dynamic subtest: ccs0
[165.219999] Dynamic subtest ccs0: SUCCESS (1.290s)
[165.234043] Starting dynamic subtest: vcs0
[174.950536] Dynamic subtest vcs0: SUCCESS (9.728s)
[174.961747] Starting dynamic subtest: bcs0
[175.652933] Dynamic subtest bcs0: SUCCESS (0.702s)
[175.663937] Starting dynamic subtest: rcs0
[176.981548] Dynamic subtest rcs0: SUCCESS (1.328s)
[176.989706] Starting dynamic subtest: vcs1
[186.920141] Dynamic subtest vcs1: SUCCESS (9.935s)
[186.922739] Subtest timeout: SUCCESS (32.485s)
[187.005573] [035/131] (832s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[187.071570] Starting subtest: invalid-bsd2-flag-on-vebox
[187.073995] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[187.217952] [036/131] (831s left) gem_exercise_blt (fast-copy-emit)
[187.280000] Starting subtest: fast-copy-emit
[187.284574] Starting dynamic subtest: linear-smem-smem-emit
[187.305474] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.025s)
[187.316164] Starting dynamic subtest: xmajor-smem-smem-emit
[187.324671] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.019s)
[187.333295] Starting dynamic subtest: tile4-smem-smem-emit
[187.343025] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.018s)
[187.351735] Starting dynamic subtest: tile64-smem-smem-emit
[187.360947] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.018s)
[187.363212] Subtest fast-copy-emit: SUCCESS (0.081s)
[187.513483] [037/131] (831s left) kms_plane_multiple (2x-tiling-4)
[187.614897] Starting subtest: 2x-tiling-4
[187.619130] Subtest 2x-tiling-4: SKIP (0.000s)
[187.697323] [038/131] (831s left) dmabuf_sync_file (export-before-signal)
[187.806484] Starting subtest: export-before-signal
[187.808702] Subtest export-before-signal: SUCCESS (0.001s)
[187.870424] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[187.873545] [039/131] (831s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[187.967188] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[187.969046] Starting dynamic subtest: pipe-A-eDP-1
[188.017224] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[188.025309] Starting dynamic subtest: pipe-B-eDP-1
[188.072335] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[188.081076] Starting dynamic subtest: pipe-C-eDP-1
[188.127100] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[188.134862] Starting dynamic subtest: pipe-D-eDP-1
[188.178600] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[188.182056] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.008s)
[190.281512] [040/131] (829s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[190.382217] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[190.386668] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[190.510314] [041/131] (828s left) i915_pm_rpm (gem-pread)
[195.323025] Starting subtest: gem-pread
[197.745108] Subtest gem-pread: SUCCESS (2.422s)
[197.847438] [042/131] (821s left) prime_vgem (fence-wait)
[197.942118] Starting subtest: fence-wait
[197.944522] Starting dynamic subtest: rcs0
[198.994386] Dynamic subtest rcs0: SUCCESS (1.051s)
[199.005615] Starting dynamic subtest: bcs0
[200.074239] Dynamic subtest bcs0: SUCCESS (1.079s)
[200.084261] Starting dynamic subtest: vcs0
[201.116802] Dynamic subtest vcs0: SUCCESS (1.041s)
[201.126815] Starting dynamic subtest: vcs1
[202.159080] Dynamic subtest vcs1: SUCCESS (1.040s)
[202.169625] Starting dynamic subtest: vecs0
[203.203312] Dynamic subtest vecs0: SUCCESS (1.042s)
[203.214231] Starting dynamic subtest: ccs0
[204.247093] Dynamic subtest ccs0: SUCCESS (1.042s)
[204.249810] Subtest fence-wait: SUCCESS (6.305s)
[204.409615] [043/131] (815s left) syncobj_timeline (invalid-wait-bad-flags)
[204.459785] Starting subtest: invalid-wait-bad-flags
[204.461897] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[204.585051] [044/131] (814s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[204.684469] Starting subtest: fbcpsr-rgb565-draw-render
[211.809455] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.127s)
[211.972242] [045/131] (807s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[212.033758] Starting subtest: wait-all-for-submit-delayed-submit
[212.136380] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[212.226708] [046/131] (807s left) gem_exec_flush (basic-wb-rw-default)
[212.290299] Starting subtest: basic-wb-rw-default
[217.774219] Subtest basic-wb-rw-default: SUCCESS (5.451s)
[217.939564] [047/131] (801s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[218.047267] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[218.051217] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[218.197291] [048/131] (801s left) kms_pipe_crc_basic (suspend-read-crc)
[218.295697] Starting subtest: suspend-read-crc
[218.297847] Starting dynamic subtest: pipe-A-eDP-1
[237.117936] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.046s)
[237.127181] Starting dynamic subtest: pipe-B-eDP-1
[255.811680] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.249s)
[255.823224] Starting dynamic subtest: pipe-C-eDP-1
[273.795312] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.131s)
[273.805284] Starting dynamic subtest: pipe-D-eDP-1
[291.831912] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.177s)
[291.834285] Subtest suspend-read-crc: SUCCESS (12.609s)
[298.744311] [049/131] (727s left) gem_exec_reloc (basic-gtt-wc)
[298.816704] Subtest basic-gtt-wc: SKIP (0.000s)
[298.909560] [050/131] (726s left) gem_watchdog (default-physical)
[298.974421] Starting subtest: default-physical
[300.742370] Subtest default-physical: SUCCESS (1.831s)
[300.846186] [051/131] (725s left) gem_fence_thrash (bo-write-verify-threaded-x)
[300.875522] Starting subtest: bo-write-verify-threaded-x
[300.900318] Subtest bo-write-verify-threaded-x: SKIP (0.021s)
[300.968254] [052/131] (724s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[301.070032] Starting subtest: fbc-rgb565-draw-blt
[305.214174] Subtest fbc-rgb565-draw-blt: SUCCESS (4.231s)
[306.415485] [053/131] (719s left) kms_universal_plane (disable-primary-vs-flip)
[306.505660] Starting subtest: disable-primary-vs-flip
[306.507480] Starting dynamic subtest: pipe-A-eDP-1
[308.025398] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.535s)
[308.035673] Starting dynamic subtest: pipe-B-eDP-1
[310.186007] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.180s)
[310.196294] Starting dynamic subtest: pipe-C-eDP-1
[312.427084] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.256s)
[312.436998] Starting dynamic subtest: pipe-D-eDP-1
[314.587959] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.170s)
[314.590242] Subtest disable-primary-vs-flip: SUCCESS (8.147s)
[315.399278] [054/131] (710s left) kms_cursor_edge_walk (128x128-top-edge)
[315.491336] Starting subtest: 128x128-top-edge
[315.493631] Starting dynamic subtest: pipe-A-eDP-1
[318.929357] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.447s)
[318.939448] Starting dynamic subtest: pipe-D-eDP-1
[322.054162] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.132s)
[322.056425] Subtest 128x128-top-edge: SUCCESS (6.582s)
[322.872859] [055/131] (703s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[323.006033] Starting subtest: flip-vs-cursor-busy-crc-legacy
[323.820369] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.815s)
[324.689005] [056/131] (701s left) kms_flip (modeset-vs-vblank-race-interruptible)
[324.782339] Starting subtest: modeset-vs-vblank-race-interruptible
[324.787149] Starting dynamic subtest: A-eDP1
[329.486870] Dynamic subtest A-eDP1: SUCCESS (4.708s)
[329.499174] Starting dynamic subtest: B-eDP1
[334.124349] Dynamic subtest B-eDP1: SUCCESS (4.634s)
[334.136889] Starting dynamic subtest: C-eDP1
[338.736460] Dynamic subtest C-eDP1: SUCCESS (4.608s)
[338.748431] Starting dynamic subtest: D-eDP1
[343.277634] Dynamic subtest D-eDP1: SUCCESS (4.538s)
[343.282629] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.501s)
[344.442575] [057/131] (681s left) perf_pmu (busy-start)
[344.484699] Starting subtest: busy-start
[344.486902] Starting dynamic subtest: rcs0
[347.035777] Dynamic subtest rcs0: SUCCESS (2.551s)
[347.048424] Starting dynamic subtest: bcs0
[349.605785] Dynamic subtest bcs0: SUCCESS (2.547s)
[349.618019] Starting dynamic subtest: vcs0
[352.849120] Dynamic subtest vcs0: SUCCESS (3.261s)
[352.861483] Starting dynamic subtest: vcs1
[356.817316] Dynamic subtest vcs1: SUCCESS (3.966s)
[356.829447] Starting dynamic subtest: vecs0
[360.849495] Dynamic subtest vecs0: SUCCESS (4.030s)
[360.861748] Starting dynamic subtest: ccs0
[363.420483] Dynamic subtest ccs0: SUCCESS (2.550s)
[363.422568] Subtest busy-start: SUCCESS (18.918s)
[363.536888] [058/131] (662s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[363.647339] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[370.196617] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.554s)
[370.368338] [059/131] (655s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[370.471901] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[370.473754] Starting dynamic subtest: pipe-A-eDP-1
[387.445590] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.849s)
[387.456860] Starting dynamic subtest: pipe-B-eDP-1
[405.411050] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.838s)
[405.420902] Starting dynamic subtest: pipe-C-eDP-1
[423.320855] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.655s)
[423.331373] Starting dynamic subtest: pipe-D-eDP-1
[441.329979] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.682s)
[441.332006] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (10.030s)
[452.595183] [060/131] (583s left) kms_flip (2x-flip-vs-expired-vblank)
[452.700675] Starting subtest: 2x-flip-vs-expired-vblank
[452.709018] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s)
[452.821303] [061/131] (583s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[452.934351] Starting subtest: basic-flip-before-cursor-atomic
[453.845878] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.938s)
[454.696937] [062/131] (581s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[454.800690] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[454.804607] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[454.919131] [063/131] (581s left) gem_ctx_param (basic)
[454.965502] Starting subtest: basic
[454.967952] Subtest basic: SUCCESS (0.001s)
[455.095796] [064/131] (581s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[455.187520] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[455.189829] Starting dynamic subtest: pipe-A-default-mode
[455.496346] Dynamic subtest pipe-A-default-mode: SKIP (0.316s)
[455.500959] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.317s)
[456.298652] [065/131] (580s left) gem_create (create-ext-placement-each)
[456.467484] Starting subtest: create-ext-placement-each
[456.469559] Subtest create-ext-placement-each: SUCCESS (0.000s)
[456.575754] [066/131] (580s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[456.944083] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[457.746989] [067/131] (578s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[457.852257] Starting subtest: extended-pageflip-modeset-hang-oldfb
[457.854487] Starting dynamic subtest: pipe-A
[465.659825] Dynamic subtest pipe-A: SUCCESS (7.893s)
[465.670722] Starting dynamic subtest: pipe-D
[474.257185] Dynamic subtest pipe-D: SUCCESS (8.631s)
[474.259448] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.528s)
[475.106580] [068/131] (561s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[475.160187] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[475.162394] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[475.287085] [069/131] (561s left) api_intel_bb (add-remove-objects)
[475.341331] Starting subtest: add-remove-objects
[475.347790] Subtest add-remove-objects: SUCCESS (0.008s)
[475.464266] [070/131] (561s left) gem_exec_fence (keep-in-fence)
[475.638494] Starting subtest: keep-in-fence
[475.641457] Starting dynamic subtest: rcs0
[475.720417] Dynamic subtest rcs0: SUCCESS (0.080s)
[475.737420] Starting dynamic subtest: bcs0
[475.775701] Dynamic subtest bcs0: SUCCESS (0.053s)
[475.788362] Starting dynamic subtest: vcs0
[477.680635] Dynamic subtest vcs0: SUCCESS (1.907s)
[477.692516] Starting dynamic subtest: vcs1
[479.719115] Dynamic subtest vcs1: SUCCESS (1.985s)
[479.731327] Starting dynamic subtest: vecs0
[481.649858] Dynamic subtest vecs0: SUCCESS (1.983s)
[481.661117] Starting dynamic subtest: ccs0
[481.888706] Dynamic subtest ccs0: SUCCESS (0.238s)
[481.891475] Subtest keep-in-fence: SUCCESS (6.261s)
[483.756244] [071/131] (553s left) api_intel_bb (destroy-bb)
[483.805714] Starting subtest: destroy-bb
[483.819607] Subtest destroy-bb: SUCCESS (0.014s)
[483.995131] [072/131] (552s left) kms_psr (fbc-pr-cursor-render)
[484.086537] Starting subtest: fbc-pr-cursor-render
[484.090954] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[484.172545] [073/131] (552s left) gem_softpin (safe-alignment)
[484.233630] Starting subtest: safe-alignment
[484.256381] Subtest safe-alignment: SUCCESS (0.023s)
[485.732468] [074/131] (551s left) i915_hangman (gt-engine-error)
[485.795153] Starting subtest: gt-engine-error
[485.797435] Starting dynamic subtest: rcs0
[487.632083] Dynamic subtest rcs0: SUCCESS (1.833s)
[487.645208] Starting dynamic subtest: bcs0
[489.615042] Dynamic subtest bcs0: SUCCESS (1.978s)
[489.632096] Starting dynamic subtest: vcs0
[491.663253] Dynamic subtest vcs0: SUCCESS (2.044s)
[491.674870] Starting dynamic subtest: vcs1
[493.647157] Dynamic subtest vcs1: SUCCESS (1.979s)
[493.660554] Starting dynamic subtest: vecs0
[495.631652] Dynamic subtest vecs0: SUCCESS (1.980s)
[495.644696] Starting dynamic subtest: ccs0
[497.615335] Dynamic subtest ccs0: SUCCESS (1.980s)
[497.618199] Subtest gt-engine-error: SUCCESS (11.811s)
[497.710470] [075/131] (539s left) kms_cursor_crc (cursor-onscreen-512x512)
[497.967601] Starting subtest: cursor-onscreen-512x512
[497.971993] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[498.158675] [076/131] (538s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[498.243456] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[498.245605] Starting dynamic subtest: pipe-A
[498.306509] Dynamic subtest pipe-A: SUCCESS (0.064s)
[498.318740] Starting dynamic subtest: pipe-B
[499.423512] Dynamic subtest pipe-B: SUCCESS (1.115s)
[499.433891] Starting dynamic subtest: pipe-C
[500.532991] Dynamic subtest pipe-C: SUCCESS (1.108s)
[500.543061] Starting dynamic subtest: pipe-D
[501.679707] Dynamic subtest pipe-D: SUCCESS (1.145s)
[501.681951] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.437s)
[502.856039] [077/131] (534s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[502.983024] Starting subtest: flip-vs-cursor-busy-crc-atomic
[503.797261] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.815s)
[504.686928] [078/131] (532s left) kms_dp_link_training (uhbr-sst)
[504.784867] Starting subtest: uhbr-sst
[504.792895] Subtest uhbr-sst: SKIP (0.000s)
[504.882703] [079/131] (532s left) perf (blocking-parameterized)
[505.040017] Starting subtest: blocking-parameterized
[525.111392] Subtest blocking-parameterized: SUCCESS (20.019s)
[525.152170] [080/131] (512s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[525.261125] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[525.265290] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[525.404037] [081/131] (511s left) vgem_basic (sysfs)
[525.447695] Starting subtest: sysfs
[525.449542] Subtest sysfs: SUCCESS (0.000s)
[525.529170] [082/131] (511s left) gem_ctx_persistence (engines-hostile)
[525.598707] Starting subtest: engines-hostile
[525.600557] Starting dynamic subtest: rcs0
[525.763981] Dynamic subtest rcs0: SUCCESS (0.167s)
[525.777119] Starting dynamic subtest: bcs0
[525.892507] Dynamic subtest bcs0: SUCCESS (0.127s)
[525.905586] Starting dynamic subtest: vcs0
[526.009964] Dynamic subtest vcs0: SUCCESS (0.115s)
[526.023337] Starting dynamic subtest: vcs1
[526.136834] Dynamic subtest vcs1: SUCCESS (0.126s)
[526.149273] Starting dynamic subtest: vecs0
[526.257950] Dynamic subtest vecs0: SUCCESS (0.119s)
[526.270512] Starting dynamic subtest: ccs0
[526.338710] Dynamic subtest ccs0: SUCCESS (0.079s)
[526.340802] Subtest engines-hostile: SUCCESS (0.744s)
[526.480572] [083/131] (510s left) i915_suspend (fence-restore-untiled)
[526.542607] Starting subtest: fence-restore-untiled
[526.546537] Subtest fence-restore-untiled: SKIP (0.000s)
[526.663938] [084/131] (510s left) syncobj_wait (invalid-reset-illegal-handle)
[526.710321] Starting subtest: invalid-reset-illegal-handle
[526.712332] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[526.829572] [085/131] (510s left) gem_readwrite (write-bad-handle)
[526.884071] Subtest write-bad-handle: SKIP (0.000s)
[526.945220] [086/131] (510s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[527.005955] Subtest no-spare-fences-busy: SKIP (0.000s)
[527.084270] [087/131] (510s left) gem_ctx_persistence (heartbeat-close)
[527.139067] Starting subtest: heartbeat-close
[527.143078] Subtest heartbeat-close: SKIP (0.000s)
[527.269575] [088/131] (510s left) kms_cursor_crc (cursor-onscreen-256x256)
[527.506861] Starting subtest: cursor-onscreen-256x256
[527.511256] Starting dynamic subtest: pipe-A-eDP-1
[529.609334] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.096s)
[529.638780] Starting dynamic subtest: pipe-D-eDP-1
[532.416274] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.784s)
[532.418506] Subtest cursor-onscreen-256x256: SUCCESS (4.907s)
[533.254092] [089/131] (504s left) gem_ringfill (engines-basic)
[538.676373] Starting subtest: engines-basic
[538.678883] Starting dynamic subtest: rcs0
[538.733522] Dynamic subtest rcs0: SUCCESS (0.057s)
[538.746205] Starting dynamic subtest: bcs0
[538.777676] Dynamic subtest bcs0: SUCCESS (0.043s)
[538.788513] Starting dynamic subtest: vcs0
[538.821826] Dynamic subtest vcs0: SUCCESS (0.042s)
[538.834601] Starting dynamic subtest: vcs1
[538.865162] Dynamic subtest vcs1: SUCCESS (0.041s)
[538.877241] Starting dynamic subtest: vecs0
[538.909739] Dynamic subtest vecs0: SUCCESS (0.043s)
[538.923882] Starting dynamic subtest: ccs0
[538.957515] Dynamic subtest ccs0: SUCCESS (0.045s)
[538.960046] Subtest engines-basic: SUCCESS (0.283s)
[539.060336] [090/131] (498s left) kms_pm_rpm (cursor)
[540.790304] Starting subtest: cursor
[540.795548] Subtest cursor: SKIP (0.000s)
[541.381208] [091/131] (496s left) gem_pread (bench)
[541.447387] Subtest bench: SKIP (0.000s)
[541.513066] [092/131] (496s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[541.606522] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[545.203135] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.601s)
[546.392808] [093/131] (491s left) gem_exec_schedule (submit-golden-slice)
[546.467994] Starting subtest: submit-golden-slice
[546.470059] Starting dynamic subtest: rcs0
[546.496155] Dynamic subtest rcs0: SUCCESS (0.036s)
[546.509254] Starting dynamic subtest: bcs0
[546.525196] Dynamic subtest bcs0: SUCCESS (0.028s)
[546.541603] Starting dynamic subtest: vcs0
[546.566019] Dynamic subtest vcs0: SUCCESS (0.039s)
[546.576240] Starting dynamic subtest: vcs1
[546.599060] Dynamic subtest vcs1: SUCCESS (0.032s)
[546.609219] Starting dynamic subtest: vecs0
[546.627597] Dynamic subtest vecs0: SUCCESS (0.028s)
[546.637518] Starting dynamic subtest: ccs0
[546.658662] Dynamic subtest ccs0: SUCCESS (0.030s)
[546.660824] Subtest submit-golden-slice: SUCCESS (0.199s)
[547.762609] [094/131] (489s left) gem_exec_schedule (preempt-contexts)
[547.833145] Starting subtest: preempt-contexts
[547.835357] Starting dynamic subtest: rcs0
[547.880348] Dynamic subtest rcs0: SUCCESS (0.055s)
[547.890914] Starting dynamic subtest: bcs0
[547.930554] Dynamic subtest bcs0: SUCCESS (0.050s)
[547.941679] Starting dynamic subtest: vcs0
[547.984363] Dynamic subtest vcs0: SUCCESS (0.053s)
[547.994638] Starting dynamic subtest: vcs1
[548.027803] Dynamic subtest vcs1: SUCCESS (0.041s)
[548.037792] Starting dynamic subtest: vecs0
[548.065749] Dynamic subtest vecs0: SUCCESS (0.038s)
[548.075953] Starting dynamic subtest: ccs0
[548.107695] Dynamic subtest ccs0: SUCCESS (0.042s)
[548.109886] Subtest preempt-contexts: SUCCESS (0.284s)
[549.807460] [095/131] (487s left) kms_frontbuffer_tracking (psr-suspend)
[549.904255] Starting subtest: psr-suspend
[588.447495] Subtest psr-suspend: SUCCESS (8.390s)
[598.669995] [096/131] (449s left) gem_exec_capture (capture-invisible)
[598.736888] Starting subtest: capture-invisible
[598.739360] Starting dynamic subtest: smem0
[598.744338] Dynamic subtest smem0: SKIP (0.000s)
[598.749324] Subtest capture-invisible: SKIP (0.001s)
[598.863985] [097/131] (449s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[598.949983] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[598.951915] Starting dynamic subtest: pipe-A-eDP-1
[598.974397] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.022s)
[598.985579] Starting dynamic subtest: pipe-B-eDP-1
[599.007611] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[599.017572] Starting dynamic subtest: pipe-C-eDP-1
[599.038949] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[599.048546] Starting dynamic subtest: pipe-D-eDP-1
[599.069001] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[599.071289] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.093s)
[601.249342] [098/131] (446s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[601.351463] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[602.330338] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.001s)
[602.495057] [099/131] (445s left) kms_big_fb (linear-64bpp-rotate-90)
[602.597378] Starting subtest: linear-64bpp-rotate-90
[602.616364] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[602.740424] [100/131] (445s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[602.839887] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[609.303322] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.552s)
[609.462452] [101/131] (438s left) kms_content_protection (dp-mst-suspend-resume)
[609.602389] Starting subtest: dp-mst-suspend-resume
[609.607923] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[609.762060] [102/131] (438s left) kms_atomic (plane-primary-legacy)
[609.851410] Starting subtest: plane-primary-legacy
[609.854164] Starting dynamic subtest: pipe-A-eDP-1
[610.300363] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.454s)
[610.303224] Subtest plane-primary-legacy: SUCCESS (0.455s)
[611.193993] [103/131] (437s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[611.251359] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[611.254107] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[611.382317] [104/131] (436s left) gem_userptr_blits (unsync-unmap-after-close)
[611.451007] Subtest unsync-unmap-after-close: SKIP (0.000s)
[611.585719] [105/131] (436s left) drm_read (empty-nonblock)
[611.720328] Starting subtest: empty-nonblock
[611.723347] Subtest empty-nonblock: SUCCESS (0.000s)
[611.882009] [106/131] (436s left) gem_eio (suspend)
[612.102997] Starting subtest: suspend
[624.005433] Subtest suspend: SUCCESS (11.939s)
[630.184974] [107/131] (424s left) kms_psr (pr-primary-render)
[630.305054] Starting subtest: pr-primary-render
[630.309601] Subtest pr-primary-render: SKIP (0.000s)
[630.398654] [108/131] (424s left) kms_vblank (ts-continuation-suspend)
[630.499378] Starting subtest: ts-continuation-suspend
[630.502040] Starting dynamic subtest: pipe-A-eDP-1
[647.503121] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.922s)
[647.520795] Starting dynamic subtest: pipe-D-eDP-1
[665.475453] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.535s)
[665.477302] Subtest ts-continuation-suspend: SUCCESS (4.460s)
[677.404082] [109/131] (388s left) kms_flip (2x-nonexisting-fb-interruptible)
[677.501401] Starting subtest: 2x-nonexisting-fb-interruptible
[677.515000] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.011s)
[677.603230] [110/131] (387s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[677.699979] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[684.619718] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.925s)
[684.780857] [111/131] (380s left) kms_invalid_mode (uint-max-clock)
[684.884374] Starting subtest: uint-max-clock
[684.886691] Starting dynamic subtest: pipe-A-eDP-1
[685.172116] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[685.182588] Starting dynamic subtest: pipe-B-eDP-1
[685.184700] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[685.194289] Starting dynamic subtest: pipe-C-eDP-1
[685.196141] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[685.206133] Starting dynamic subtest: pipe-D-eDP-1
[685.208287] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[685.210526] Subtest uint-max-clock: SUCCESS (0.294s)
[686.019396] [112/131] (379s left) kms_properties (get_properties-sanity-non-atomic)
[686.126587] Starting subtest: get_properties-sanity-non-atomic
[686.128939] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[686.249848] [113/131] (379s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[688.480512] Starting subtest: modeset-non-lpsp-stress-no-wait
[688.518781] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.042s)
[688.864682] [114/131] (376s left) kms_flip (flip-vs-absolute-wf_vblank)
[688.957951] Starting subtest: flip-vs-absolute-wf_vblank
[688.959889] Starting dynamic subtest: A-eDP1
[709.681262] Dynamic subtest A-eDP1: SUCCESS (20.964s)
[709.692660] Starting dynamic subtest: B-eDP1
[718.592835] Dynamic subtest B-eDP1: SUCCESS (8.925s)
[718.602692] Starting dynamic subtest: C-eDP1
[727.564108] Dynamic subtest C-eDP1: SUCCESS (8.920s)
[727.575473] Starting dynamic subtest: D-eDP1
[736.502096] Dynamic subtest D-eDP1: SUCCESS (8.935s)
[736.504480] Subtest flip-vs-absolute-wf_vblank: SUCCESS (47.752s)
[737.693944] [115/131] (328s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[737.757036] Starting subtest: basic-batch-kernel-default-cmd
[737.761102] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[737.951832] [116/131] (327s left) gem_exec_whisper (basic-fds-all)
[738.020081] Starting subtest: basic-fds-all
[749.349797] Subtest basic-fds-all: SUCCESS (11.328s)
[749.547282] [117/131] (316s left) kms_properties (invalid-properties-atomic)
[749.643260] Starting subtest: invalid-properties-atomic
[749.947513] Subtest invalid-properties-atomic: SUCCESS (0.304s)
[750.035976] [118/131] (315s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[750.138216] Starting subtest: plane-scaler-unity-scaling-with-rotation
[750.140158] Starting dynamic subtest: pipe-A
[750.364852] Dynamic subtest pipe-A: SUCCESS (0.229s)
[750.377310] Starting dynamic subtest: pipe-B
[751.666478] Dynamic subtest pipe-B: SUCCESS (1.301s)
[751.677808] Starting dynamic subtest: pipe-C
[752.990639] Dynamic subtest pipe-C: SUCCESS (1.323s)
[753.002651] Starting dynamic subtest: pipe-D
[754.325892] Dynamic subtest pipe-D: SUCCESS (1.335s)
[754.328382] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.192s)
[755.513000] [119/131] (310s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[755.578080] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[755.683669] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[755.781409] [120/131] (310s left) syncobj_basic (bad-pad-handle-to-fd)
[755.850122] Starting subtest: bad-pad-handle-to-fd
[755.852047] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[755.979239] [121/131] (310s left) kms_chamelium_hpd (dp-hpd)
[756.367105] Subtest dp-hpd: SKIP (0.000s)
[757.216804] [122/131] (308s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[757.308969] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[757.681876] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[759.030376] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: FAIL (0.763s)
[759.042415] Starting dynamic subtest: pipe-A-eDP-1-4
[759.438919] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.654s)
[759.451091] Starting dynamic subtest: pipe-A-eDP-1-4
[759.610512] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.016s)
[759.622275] Starting dynamic subtest: pipe-A-eDP-1-x
[759.964334] Dynamic subtest pipe-A-eDP-1-x: FAIL (0.594s)
[760.096137] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[761.458961] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: FAIL (1.163s)
[761.469079] Starting dynamic subtest: pipe-B-eDP-1-4
[761.879708] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.176s)
[761.891467] Starting dynamic subtest: pipe-B-eDP-1-4
[762.043596] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.479s)
[762.053553] Starting dynamic subtest: pipe-B-eDP-1-x
[762.425926] Dynamic subtest pipe-B-eDP-1-x: FAIL (0.187s)
[762.468110] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[765.337336] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.824s)
[765.347866] Starting dynamic subtest: pipe-C-eDP-1-4
[767.511709] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.174s)
[767.524700] Starting dynamic subtest: pipe-C-eDP-1-4
[769.469519] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[769.480081] Starting dynamic subtest: pipe-C-eDP-1-x
[771.688139] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.167s)
[772.012044] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[774.871223] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.815s)
[774.883885] Starting dynamic subtest: pipe-D-eDP-1-4
[777.033870] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.163s)
[777.046964] Starting dynamic subtest: pipe-D-eDP-1-4
[778.992191] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[779.003604] Starting dynamic subtest: pipe-D-eDP-1-x
[781.221457] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[781.223836] Subtest async-flip-with-page-flip-events-tiled-atomic: FAIL (23.870s)
[782.420145] [123/131] (283s left) i915_drm_fdinfo (most-busy-check-all)
[782.461837] Starting subtest: most-busy-check-all
[782.464083] Starting dynamic subtest: rcs0
[782.468581] Dynamic subtest rcs0: SKIP (0.004s)
[782.479833] Starting dynamic subtest: bcs0
[782.484139] Dynamic subtest bcs0: SKIP (0.000s)
[782.494116] Starting dynamic subtest: vcs0
[782.497878] Dynamic subtest vcs0: SKIP (0.000s)
[782.507372] Starting dynamic subtest: vcs1
[782.511504] Dynamic subtest vcs1: SKIP (0.000s)
[782.521047] Starting dynamic subtest: vecs0
[782.525118] Dynamic subtest vecs0: SKIP (0.000s)
[782.534245] Starting dynamic subtest: ccs0
[782.538072] Dynamic subtest ccs0: SKIP (0.000s)
[782.542006] Subtest most-busy-check-all: SKIP (0.009s)
[782.580003] [124/131] (283s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[782.714706] Starting subtest: fb-8bpc-vs-panel-6bpc
[782.716958] Starting dynamic subtest: pipe-A-eDP-1
[784.102272] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.388s)
[784.104671] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.389s)
[784.972235] [125/131] (281s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[785.099033] Starting subtest: cursorB-vs-flipA-atomic-transitions
[785.103449] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[785.260964] [126/131] (281s left) gem_set_tiling_vs_pwrite
[785.378716] [127/131] (280s left) gem_mmap (bad-object)
[785.435174] Subtest bad-object: SKIP (0.000s)
[785.505626] [128/131] (280s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[785.605453] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[785.609840] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[785.724828] [129/131] (280s left) syncobj_wait (multi-wait-for-submit-submitted)
[785.783961] Starting subtest: multi-wait-for-submit-submitted
[785.892958] Subtest multi-wait-for-submit-submitted: SUCCESS (0.107s)
[786.001380] [130/131] (280s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[786.106302] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[786.110794] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[786.227767] [131/131] (280s left) gem_mmap_offset (isolation)
[786.288930] Starting subtest: isolation
[786.306867] Subtest isolation: SUCCESS (0.006s)
[786.420252] Closing watchdogs
results: parsing output: 0/ for test: gen9_exec_parse
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_properties
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: gem_eio
results: parsing output: 11/ for test: kms_psr2_sf
results: parsing output: 12/ for test: gem_exec_alignment
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: gem_fence_thrash
results: parsing output: 16/ for test: fbdev
results: parsing output: 17/ for test: kms_lease
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_mmap_gtt
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_psr_stress_test
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_getfb
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: gem_pxp
results: parsing output: 32/ for test: kms_chamelium_audio
results: parsing output: 33/ for test: sysfs_timeslice_duration
results: parsing output: 34/ for test: gem_exec_params
results: parsing output: 35/ for test: gem_exercise_blt
results: parsing output: 36/ for test: kms_plane_multiple
results: parsing output: 37/ for test: dmabuf_sync_file
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: i915_pm_rpm
results: parsing output: 41/ for test: prime_vgem
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_flush
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_pipe_crc_basic
results: parsing output: 48/ for test: gem_exec_reloc
results: parsing output: 49/ for test: gem_watchdog
results: parsing output: 50/ for test: gem_fence_thrash
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_universal_plane
results: parsing output: 53/ for test: kms_cursor_edge_walk
results: parsing output: 54/ for test: kms_cursor_legacy
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: perf_pmu
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_ccs
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_ctx_param
results: parsing output: 63/ for test: kms_flip_scaled_crc
results: parsing output: 64/ for test: gem_create
results: parsing output: 65/ for test: kms_chamelium_hpd
results: parsing output: 66/ for test: kms_busy
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: api_intel_bb
results: parsing output: 69/ for test: gem_exec_fence
results: parsing output: 70/ for test: api_intel_bb
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: gem_softpin
results: parsing output: 73/ for test: i915_hangman
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: kms_plane_scaling
results: parsing output: 76/ for test: kms_cursor_legacy
results: parsing output: 77/ for test: kms_dp_link_training
results: parsing output: 78/ for test: perf
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: vgem_basic
results: parsing output: 81/ for test: gem_ctx_persistence
results: parsing output: 82/ for test: i915_suspend
results: parsing output: 83/ for test: syncobj_wait
results: parsing output: 84/ for test: gem_readwrite
results: parsing output: 85/ for test: gem_fenced_exec_thrash
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: gem_ringfill
results: parsing output: 89/ for test: kms_pm_rpm
results: parsing output: 90/ for test: gem_pread
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_exec_schedule
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_capture
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_content_protection
results: parsing output: 101/ for test: kms_atomic
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: gem_userptr_blits
results: parsing output: 104/ for test: drm_read
results: parsing output: 105/ for test: gem_eio
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: kms_vblank
results: parsing output: 108/ for test: kms_flip
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_invalid_mode
results: parsing output: 111/ for test: kms_properties
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: kms_flip
results: parsing output: 114/ for test: gem_exec_flush
results: parsing output: 115/ for test: gem_exec_whisper
results: parsing output: 116/ for test: kms_properties
results: parsing output: 117/ for test: kms_plane_scaling
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: syncobj_basic
results: parsing output: 120/ for test: kms_chamelium_hpd
results: parsing output: 121/ for test: kms_async_flips
results: parsing output: 122/ for test: i915_drm_fdinfo
results: parsing output: 123/ for test: kms_dither
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 126/ for test: gem_mmap
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_mmap_offset
Done.