[63.913222] Initializing watchdogs
[63.913269]   /dev/watchdog0
[63.928703] [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]
[63.933412] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.935850] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.937763] [001/133] (960s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[64.030746] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[64.031782] Starting dynamic subtest: pipe-A-eDP-1
[64.039709] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[64.046795] Starting dynamic subtest: pipe-B-eDP-1
[64.053853] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[64.064582] Starting dynamic subtest: pipe-C-eDP-1
[64.072830] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[64.081941] Starting dynamic subtest: pipe-D-eDP-1
[64.089221] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[64.089994] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.004s)
[66.268620] [002/133] (958s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[66.367478] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[73.164954] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.802s)
[73.273988] [003/133] (951s left) api_intel_bb (intel-bb-blit-x)
[73.325665] Starting subtest: intel-bb-blit-x
[77.343642] Subtest intel-bb-blit-x: SUCCESS (4.009s)
[77.441731] [004/133] (947s left) fbdev (info)
[77.473891] Starting subtest: info
[77.474824] Subtest info: SUCCESS (0.000s)
[77.531179] [005/133] (947s left) syncobj_wait (invalid-signal-zero-handles)
[77.574889] Starting subtest: invalid-signal-zero-handles
[77.576159] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[77.680598] [006/133] (946s left) gem_exec_fence (basic-busy)
[77.858816] Starting subtest: basic-busy
[77.859984] Starting dynamic subtest: rcs0
[77.935038] Dynamic subtest rcs0: SUCCESS (0.083s)
[77.943548] Starting dynamic subtest: bcs0
[77.999019] Dynamic subtest bcs0: SUCCESS (0.063s)
[78.007414] Starting dynamic subtest: vcs0
[78.068455] Dynamic subtest vcs0: SUCCESS (0.068s)
[78.078117] Starting dynamic subtest: vcs1
[78.124322] Dynamic subtest vcs1: SUCCESS (0.055s)
[78.132754] Starting dynamic subtest: vecs0
[78.183203] Dynamic subtest vecs0: SUCCESS (0.057s)
[78.191517] Starting dynamic subtest: ccs0
[78.241757] Dynamic subtest ccs0: SUCCESS (0.057s)
[78.243358] Subtest basic-busy: SUCCESS (0.390s)
[79.945997] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[79.947681] [007/133] (944s left) kms_big_fb (linear-32bpp-rotate-180)
[80.045471] Starting subtest: linear-32bpp-rotate-180
[81.583294] Subtest linear-32bpp-rotate-180: SUCCESS (1.538s)
[81.700648] [008/133] (942s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[81.790126] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[81.791046] Starting dynamic subtest: pipe-A-eDP-1
[81.799227] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[81.809371] Starting dynamic subtest: pipe-B-eDP-1
[81.818056] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[81.825072] Starting dynamic subtest: pipe-C-eDP-1
[81.832973] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[81.838680] Starting dynamic subtest: pipe-D-eDP-1
[81.847481] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[81.849157] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[84.055147] [009/133] (940s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[84.424498] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[85.254535] [010/133] (939s left) kms_plane (pixel-format-4-tiled-modifier)
[85.340470] Starting subtest: pixel-format-4-tiled-modifier
[85.368189] Starting dynamic subtest: pipe-A-plane-0
[86.001959] Dynamic subtest pipe-A-plane-0: SUCCESS (0.633s)
[86.010256] Starting dynamic subtest: pipe-A-plane-3
[86.423988] Dynamic subtest pipe-A-plane-3: SUCCESS (0.422s)
[86.431360] Starting dynamic subtest: pipe-A-plane-5
[86.441208] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[87.573821] Starting dynamic subtest: pipe-B-plane-0
[88.267064] Dynamic subtest pipe-B-plane-0: SUCCESS (0.700s)
[88.275795] Starting dynamic subtest: pipe-B-plane-3
[88.711045] Dynamic subtest pipe-B-plane-3: SUCCESS (0.444s)
[88.718934] Starting dynamic subtest: pipe-B-plane-5
[88.721212] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[89.026445] Subtest pixel-format-4-tiled-modifier: SUCCESS (3.686s)
[89.846897] [011/133] (934s left) gem_exec_reloc (basic-cpu-wc)
[89.903282] Subtest basic-cpu-wc: SKIP (0.000s)
[89.965156] [012/133] (934s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[90.059485] Starting subtest: fbcpsr-1p-rte
[97.091620] Subtest fbcpsr-1p-rte: SUCCESS (7.034s)
[97.231532] [013/133] (927s left) gem_wait (busy)
[97.290044] Starting subtest: busy
[97.291023] Starting dynamic subtest: all-engines
[98.911142] Dynamic subtest all-engines: SUCCESS (1.611s)
[98.919401] Starting dynamic subtest: rcs0
[99.453106] Dynamic subtest rcs0: SUCCESS (0.550s)
[99.461641] Starting dynamic subtest: bcs0
[99.994384] Dynamic subtest bcs0: SUCCESS (0.540s)
[100.002876] Starting dynamic subtest: vcs0
[101.920728] Dynamic subtest vcs0: SUCCESS (1.916s)
[101.928836] Starting dynamic subtest: vcs1
[103.903418] Dynamic subtest vcs1: SUCCESS (1.981s)
[103.912245] Starting dynamic subtest: vecs0
[105.887467] Dynamic subtest vecs0: SUCCESS (1.983s)
[105.895861] Starting dynamic subtest: ccs0
[106.420644] Dynamic subtest ccs0: SUCCESS (0.541s)
[106.421978] Subtest busy: SUCCESS (9.130s)
[106.616404] [014/133] (918s left) gem_exec_parallel (userptr)
[106.678972] Starting subtest: userptr
[106.679982] Starting dynamic subtest: rcs0
[108.205873] Dynamic subtest rcs0: SUCCESS (1.526s)
[108.213427] Starting dynamic subtest: bcs0
[109.658920] Dynamic subtest bcs0: SUCCESS (1.451s)
[109.667998] Starting dynamic subtest: vcs0
[112.863292] Dynamic subtest vcs0: SUCCESS (3.194s)
[112.873456] Starting dynamic subtest: vcs1
[114.911828] Dynamic subtest vcs1: SUCCESS (2.047s)
[114.921135] Starting dynamic subtest: vecs0
[116.895089] Dynamic subtest vecs0: SUCCESS (1.982s)
[116.904399] Starting dynamic subtest: ccs0
[118.383399] Dynamic subtest ccs0: SUCCESS (1.497s)
[118.384420] Subtest userptr: SUCCESS (11.705s)
[118.576638] [015/133] (906s left) kms_cursor_crc (cursor-random-128x128)
[118.779962] Starting subtest: cursor-random-128x128
[118.785065] Starting dynamic subtest: pipe-A-eDP-1
[122.120578] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.335s)
[122.147744] Starting dynamic subtest: pipe-D-eDP-1
[126.112592] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.969s)
[126.113563] Subtest cursor-random-128x128: SUCCESS (7.332s)
[126.946015] [016/133] (897s left) kms_rotation_crc (bad-tiling)
[127.036496] Starting subtest: bad-tiling
[127.038454] Subtest bad-tiling: SKIP (0.001s)
[127.102806] [017/133] (897s left) gem_close_race (multigpu-basic-threads)
[127.168061] Starting subtest: multigpu-basic-threads
[127.169794] Subtest multigpu-basic-threads: SKIP (0.000s)
[127.391023] [018/133] (897s left) gem_mmap (bad-size)
[127.442463] Subtest bad-size: SKIP (0.000s)
[127.489833] [019/133] (897s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[127.579909] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[127.581615] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[127.662677] [020/133] (897s left) gem_userptr_blits (create-destroy-sync)
[127.718486] Starting subtest: create-destroy-sync
[132.782279] Subtest create-destroy-sync: SUCCESS (5.017s)
[132.874851] [021/133] (892s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[132.989104] Starting subtest: cursor-tearing-framebuffer-change
[132.990029] Starting dynamic subtest: pipe-A-eDP-1
[133.581520] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.591s)
[133.588757] Starting dynamic subtest: pipe-D-eDP-1
[134.773675] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.192s)
[134.774525] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.786s)
[135.609594] [022/133] (889s left) kms_cursor_legacy (forked-bo)
[135.719128] Starting subtest: forked-bo
[135.720081] Starting dynamic subtest: pipe-A
[141.219431] Dynamic subtest pipe-A: SUCCESS (5.455s)
[141.230454] Starting dynamic subtest: pipe-B
[146.691939] Dynamic subtest pipe-B: SUCCESS (5.470s)
[146.701451] Starting dynamic subtest: pipe-C
[152.203394] Dynamic subtest pipe-C: SUCCESS (5.510s)
[152.212089] Starting dynamic subtest: pipe-D
[157.672851] Dynamic subtest pipe-D: SUCCESS (5.468s)
[157.682187] Starting dynamic subtest: all-pipes
[163.196406] Dynamic subtest all-pipes: SUCCESS (5.522s)
[163.197872] Subtest forked-bo: SUCCESS (27.431s)
[163.301034] [023/133] (861s left) kms_async_flips (async-flip-dpms)
[163.389073] Starting subtest: async-flip-dpms
[163.773536] Starting dynamic subtest: pipe-A-eDP-1
[167.546036] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.772s)
[167.927781] Starting dynamic subtest: pipe-B-eDP-1
[170.697059] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.775s)
[171.093834] Starting dynamic subtest: pipe-C-eDP-1
[173.858616] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.772s)
[174.233836] Starting dynamic subtest: pipe-D-eDP-1
[177.019509] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.791s)
[177.020434] Subtest async-flip-dpms: SUCCESS (13.630s)
[178.196111] [024/133] (846s left) gem_ctx_param (invalid-get-no-zeromap)
[178.246630] Starting subtest: invalid-get-no-zeromap
[178.248934] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[178.351257] [025/133] (846s left) kms_atomic (crtc-invalid-params-fence)
[178.439176] Starting subtest: crtc-invalid-params-fence
[178.440088] Starting dynamic subtest: pipe-A-eDP-1
[178.861645] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[178.862526] Subtest crtc-invalid-params-fence: SUCCESS (0.423s)
[179.732524] [026/133] (845s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[179.825331] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[180.936730] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.111s)
[181.044086] [027/133] (844s left) kms_flip (2x-absolute-wf_vblank)
[181.134093] Starting subtest: 2x-absolute-wf_vblank
[181.144616] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[181.232300] [028/133] (844s left) gen9_exec_parse (valid-registers)
[181.292779] Subtest valid-registers: SKIP (0.000s)
[181.356145] [029/133] (844s left) core_setmaster_vs_auth
[181.505167] [030/133] (843s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[181.558864] Starting subtest: multi-wait-available-unsubmitted-signaled
[181.559803] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.001s)
[181.658211] [031/133] (843s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[181.752145] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[181.753035] Starting dynamic subtest: pipe-A-eDP-1
[181.852220] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[181.859895] Starting dynamic subtest: pipe-B-eDP-1
[183.012970] Dynamic subtest pipe-B-eDP-1: SKIP (1.160s)
[183.015807] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.262s)
[184.199555] [032/133] (841s left) perf (non-zero-reason)
[184.321205] Starting subtest: non-zero-reason
[184.322168] Starting dynamic subtest: 0-rcs0
[190.091318] Dynamic subtest 0-rcs0: SUCCESS (5.724s)
[190.100370] Starting dynamic subtest: 1-vcs1
[194.122935] Dynamic subtest 1-vcs1: SUCCESS (4.066s)
[194.124252] Subtest non-zero-reason: SUCCESS (9.792s)
[194.153906] [033/133] (831s left) kms_frontbuffer_tracking (psr-slowdraw)
[194.253964] Starting subtest: psr-slowdraw
[219.574814] Subtest psr-slowdraw: SUCCESS (25.320s)
[219.687290] [034/133] (805s left) gem_tiled_wc
[219.788822] [035/133] (805s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[219.881343] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[220.707389] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.826s)
[220.864112] [036/133] (804s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[220.923188] Starting subtest: y-tiled-to-vebox-x-tiled
[220.924878] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[221.099403] [037/133] (804s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[221.152697] Starting subtest: etime-multi-wait-for-submit-submitted
[221.258725] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.105s)
[221.330604] [038/133] (804s left) kms_pm_dc (dc5-dpms)
[222.435247] Starting subtest: dc5-dpms
[224.525954] Subtest dc5-dpms: SUCCESS (2.080s)
[224.590140] [039/133] (801s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[224.680192] Starting subtest: 4-tiled-8bpp-rotate-0
[225.893710] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.214s)
[226.000494] [040/133] (799s left) kms_psr (psr-primary-blt)
[226.094344] Starting subtest: psr-primary-blt
[226.095249] Starting dynamic subtest: eDP-1
[227.860288] Dynamic subtest eDP-1: SUCCESS (1.766s)
[227.861134] Subtest psr-primary-blt: SUCCESS (1.767s)
[228.723935] [041/133] (797s left) gem_exec_flush (basic-wb-pro-default)
[228.788775] Starting subtest: basic-wb-pro-default
[234.250794] Subtest basic-wb-pro-default: SUCCESS (5.462s)
[234.430368] [042/133] (791s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[234.542448] Starting subtest: yf-tiled-addfb-size-overflow
[234.544260] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[234.629259] [043/133] (791s left) gem_pread (bench)
[234.684247] Subtest bench: SKIP (0.000s)
[234.741139] [044/133] (791s left) i915_pm_rps (reset)
[234.791625] Starting subtest: reset
[243.267653] Subtest reset: SUCCESS (8.468s)
[243.366010] [045/133] (782s left) kms_psr (psr-cursor-mmap-cpu)
[243.448881] Starting subtest: psr-cursor-mmap-cpu
[243.449932] Starting dynamic subtest: eDP-1
[245.126378] Dynamic subtest eDP-1: SUCCESS (1.678s)
[245.127257] Subtest psr-cursor-mmap-cpu: SUCCESS (1.679s)
[246.007441] [046/133] (779s left) kms_rmfb (close-fd)
[246.090634] Starting subtest: close-fd
[246.091522] Starting dynamic subtest: pipe-A-eDP-1
[246.203366] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.111s)
[246.212657] Starting dynamic subtest: pipe-B-eDP-1
[247.344883] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.139s)
[247.352940] Starting dynamic subtest: pipe-C-eDP-1
[248.478227] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.130s)
[248.485359] Starting dynamic subtest: pipe-D-eDP-1
[249.601389] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[249.602284] Subtest close-fd: SUCCESS (3.511s)
[250.719818] [047/133] (775s left) kms_addfb_basic (bad-pitch-1024)
[250.767900] Starting subtest: bad-pitch-1024
[250.768886] Subtest bad-pitch-1024: SUCCESS (0.000s)
[250.887920] [048/133] (775s left) kms_prop_blob (invalid-get-prop)
[250.932057] Starting subtest: invalid-get-prop
[250.932838] Subtest invalid-get-prop: SUCCESS (0.000s)
[251.025789] [049/133] (775s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[251.113412] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[251.114475] Starting dynamic subtest: pipe-A-eDP-1
[251.123024] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[251.130169] Starting dynamic subtest: pipe-B-eDP-1
[251.138657] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[251.145389] Starting dynamic subtest: pipe-C-eDP-1
[251.153648] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[251.160182] Starting dynamic subtest: pipe-D-eDP-1
[251.168749] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[251.170377] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[253.295526] [050/133] (772s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[253.377044] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[253.378960] Starting dynamic subtest: A-eDP1
[257.044287] Dynamic subtest A-eDP1: SUCCESS (3.664s)
[257.051524] Starting dynamic subtest: B-eDP1
[260.340288] Dynamic subtest B-eDP1: SUCCESS (3.295s)
[260.347299] Starting dynamic subtest: C-eDP1
[263.539201] Dynamic subtest C-eDP1: SUCCESS (3.197s)
[263.548595] Starting dynamic subtest: D-eDP1
[266.649890] Dynamic subtest D-eDP1: SUCCESS (3.111s)
[266.651919] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.275s)
[267.447056] [051/133] (758s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[267.535382] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[267.536363] Starting dynamic subtest: pipe-A
[268.661786] Dynamic subtest pipe-A: SUCCESS (1.128s)
[268.670289] Starting dynamic subtest: pipe-B
[270.857416] Dynamic subtest pipe-B: SUCCESS (2.193s)
[270.867534] Starting dynamic subtest: pipe-C
[272.055625] Dynamic subtest pipe-C: SUCCESS (1.196s)
[272.062836] Starting dynamic subtest: pipe-D
[273.290505] Dynamic subtest pipe-D: SUCCESS (1.234s)
[273.291428] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.757s)
[274.474538] [052/133] (751s left) core_getversion (basic)
[274.507959] Starting subtest: basic
[274.509859] Subtest basic: SUCCESS (0.000s)
[274.573563] [053/133] (751s left) kms_joiner (basic-force-ultra-joiner)
[274.664934] Starting subtest: basic-force-ultra-joiner
[274.666433] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[274.758420] [054/133] (751s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[274.859058] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[282.100863] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.243s)
[282.208474] [055/133] (744s left) prime_busy (hang)
[282.261467] Starting subtest: hang
[282.262453] Starting dynamic subtest: rcs0
[308.404580] Dynamic subtest rcs0: SUCCESS (26.139s)
[308.411342] Starting dynamic subtest: bcs0
[315.556601] Dynamic subtest bcs0: SUCCESS (7.150s)
[315.563237] Starting dynamic subtest: vcs0
[322.533492] Dynamic subtest vcs0: SUCCESS (6.975s)
[322.540280] Starting dynamic subtest: vcs1
[329.573043] Dynamic subtest vcs1: SUCCESS (7.039s)
[329.580348] Starting dynamic subtest: vecs0
[336.549469] Dynamic subtest vecs0: SUCCESS (6.972s)
[336.556230] Starting dynamic subtest: ccs0
[350.447396] Dynamic subtest ccs0: SUCCESS (13.898s)
[350.448385] Subtest hang: SUCCESS (68.181s)
[350.559572] [056/133] (675s left) kms_plane_scaling (planes-upscale-factor-0-25)
[350.648457] Starting subtest: planes-upscale-factor-0-25
[350.649326] Starting dynamic subtest: pipe-A
[350.712438] Dynamic subtest pipe-A: SUCCESS (0.064s)
[350.721806] Starting dynamic subtest: pipe-B
[351.832967] Dynamic subtest pipe-B: SUCCESS (1.119s)
[351.845580] Starting dynamic subtest: pipe-C
[352.949531] Dynamic subtest pipe-C: SUCCESS (1.115s)
[352.965620] Starting dynamic subtest: pipe-D
[354.072615] Dynamic subtest pipe-D: SUCCESS (1.121s)
[354.073524] Subtest planes-upscale-factor-0-25: SUCCESS (3.425s)
[355.239112] [057/133] (671s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[355.332216] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[355.334085] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[355.419347] [058/133] (670s left) syncobj_timeline (transfer-timeline-point)
[355.474852] Starting subtest: transfer-timeline-point
[355.475836] Subtest transfer-timeline-point: SUCCESS (0.000s)
[355.566924] [059/133] (670s left) kms_atomic_transition (plane-all-transition-fencing)
[355.650493] Starting subtest: plane-all-transition-fencing
[355.651414] Starting dynamic subtest: pipe-A-eDP-1
[358.152174] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.501s)
[358.466612] Starting dynamic subtest: pipe-B-eDP-1
[361.674799] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.214s)
[361.975063] Subtest plane-all-transition-fencing: SUCCESS (6.325s)
[362.789653] [060/133] (663s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[362.877160] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[362.878903] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[362.982055] [061/133] (663s left) prime_mmap_kms (buffer-sharing)
[363.060962] Starting subtest: buffer-sharing
[363.517876] Subtest buffer-sharing: SUCCESS (0.456s)
[364.322663] [062/133] (662s left) perf_pmu (most-busy-check-all)
[364.365428] Starting subtest: most-busy-check-all
[364.366293] Starting dynamic subtest: rcs0
[365.919534] Dynamic subtest rcs0: SUCCESS (1.545s)
[365.929399] Starting dynamic subtest: bcs0
[367.903790] Dynamic subtest bcs0: SUCCESS (1.982s)
[367.913667] Starting dynamic subtest: vcs0
[369.951549] Dynamic subtest vcs0: SUCCESS (2.045s)
[369.961563] Starting dynamic subtest: vcs1
[371.935285] Dynamic subtest vcs1: SUCCESS (1.981s)
[371.945295] Starting dynamic subtest: vecs0
[373.920367] Dynamic subtest vecs0: SUCCESS (1.982s)
[373.930057] Starting dynamic subtest: ccs0
[375.903601] Dynamic subtest ccs0: SUCCESS (1.980s)
[375.904794] Subtest most-busy-check-all: SUCCESS (11.531s)
[375.999131] [063/133] (650s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[376.591536] Starting subtest: syncobj-backward-timeline-chain-engines
[376.794451] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.202s)
[377.954253] [064/133] (648s left) kms_content_protection (lic-type-1)
[378.100459] Starting subtest: lic-type-1
[378.102179] Subtest lic-type-1: SKIP (0.000s)
[378.215843] [065/133] (648s left) i915_pm_rps (waitboost)
[378.265640] Starting subtest: waitboost
[384.355548] Subtest waitboost: SUCCESS (6.083s)
[384.477905] [066/133] (642s left) gem_pread (display)
[384.523156] Subtest display: SKIP (0.000s)
[384.572601] [067/133] (642s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[384.672883] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[384.674764] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[384.773505] [068/133] (641s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[384.861030] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[389.428307] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.567s)
[390.631928] [069/133] (636s left) gem_ctx_shared (q-out-order)
[390.689503] Starting subtest: Q-out-order
[390.690459] Starting dynamic subtest: rcs0
[390.802081] Dynamic subtest rcs0: SUCCESS (0.111s)
[390.815577] Starting dynamic subtest: vcs0
[390.898513] Dynamic subtest vcs0: SUCCESS (0.095s)
[390.915458] Starting dynamic subtest: vcs1
[390.985898] Dynamic subtest vcs1: SUCCESS (0.085s)
[391.001311] Starting dynamic subtest: vecs0
[391.075494] Dynamic subtest vecs0: SUCCESS (0.086s)
[391.093697] Starting dynamic subtest: ccs0
[391.172486] Dynamic subtest ccs0: SUCCESS (0.094s)
[391.174046] Subtest Q-out-order: SUCCESS (0.483s)
[391.990307] [070/133] (634s left) gem_exec_reloc (basic-gtt-wc-active)
[392.046366] Subtest basic-gtt-wc-active: SKIP (0.000s)
[392.130998] [071/133] (634s left) prime_mmap (test_forked)
[392.190884] Starting subtest: test_forked
[392.191811] Starting dynamic subtest: test_forked-smem
[392.255591] Dynamic subtest test_forked-smem: SUCCESS (0.065s)
[392.256445] Subtest test_forked: SUCCESS (0.067s)
[392.345718] [072/133] (634s left) gem_mmap_gtt (basic-copy)
[392.404740] Subtest basic-copy: SKIP (0.000s)
[392.461421] [073/133] (634s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[392.560629] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[392.562336] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[392.657759] [074/133] (634s left) kms_psr (fbc-pr-sprite-plane-move)
[392.744543] Starting subtest: fbc-pr-sprite-plane-move
[392.746372] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[392.821931] [075/133] (634s left) gem_exec_balancer (full)
[392.881416] Starting subtest: full
[397.862919] Subtest full: SUCCESS (4.982s)
[398.187778] [076/133] (628s left) gem_exec_schedule (pi-common)
[398.379399] Starting subtest: pi-common
[398.380289] Starting dynamic subtest: rcs0
[398.401824] Dynamic subtest rcs0: SUCCESS (0.031s)
[398.409416] Starting dynamic subtest: bcs0
[398.432932] Dynamic subtest bcs0: SUCCESS (0.030s)
[398.440759] Starting dynamic subtest: vcs0
[398.464277] Dynamic subtest vcs0: SUCCESS (0.030s)
[398.471866] Starting dynamic subtest: vcs1
[398.494909] Dynamic subtest vcs1: SUCCESS (0.030s)
[398.502732] Starting dynamic subtest: vecs0
[398.524705] Dynamic subtest vecs0: SUCCESS (0.029s)
[398.532226] Starting dynamic subtest: ccs0
[398.556482] Dynamic subtest ccs0: SUCCESS (0.031s)
[398.557363] Subtest pi-common: SUCCESS (0.187s)
[398.799742] [077/133] (628s left) gem_ctx_param (invalid-ctx-set)
[398.840784] Starting subtest: invalid-ctx-set
[398.841768] Subtest invalid-ctx-set: SUCCESS (0.001s)
[398.908980] [078/133] (628s left) prime_self_import (basic-llseek-bad)
[398.939518] Starting subtest: basic-llseek-bad
[398.954059] Subtest basic-llseek-bad: SUCCESS (0.016s)
[399.036347] [079/133] (628s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[399.125948] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[399.126980] Starting dynamic subtest: pipe-A-eDP-1
[399.189851] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.064s)
[399.501872] Starting dynamic subtest: pipe-B-eDP-1
[400.324165] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.830s)
[400.621624] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.495s)
[401.474674] [080/133] (625s left) kms_dsc (dsc-with-bpc)
[401.565442] Starting subtest: dsc-with-bpc
[401.577118] Subtest dsc-with-bpc: SKIP (0.001s)
[401.641441] [081/133] (625s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[401.689843] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[401.794825] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.104s)
[401.868230] [082/133] (625s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[401.963422] Starting subtest: fbc-1p-pri-indfb-multidraw
[415.192462] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.229s)
[416.573549] [083/133] (610s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[416.620342] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[416.622170] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[416.800829] [084/133] (610s left) gem_exec_schedule (semaphore-noskip)
[416.856211] Starting subtest: semaphore-noskip
[417.298062] Subtest semaphore-noskip: SUCCESS (0.432s)
[417.638953] [085/133] (609s left) kms_sequence (get-forked)
[417.729474] Starting subtest: get-forked
[417.730468] Starting dynamic subtest: pipe-A-eDP-1
[419.606377] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[419.613922] Starting dynamic subtest: pipe-B-eDP-1
[422.149356] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.542s)
[422.158205] Starting dynamic subtest: pipe-C-eDP-1
[424.651284] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.498s)
[424.659619] Starting dynamic subtest: pipe-D-eDP-1
[427.177617] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.526s)
[427.178671] Subtest get-forked: SUCCESS (9.449s)
[428.042825] [086/133] (599s left) kms_flip (2x-flip-vs-wf_vblank)
[428.128995] Starting subtest: 2x-flip-vs-wf_vblank
[428.140222] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[428.237729] [087/133] (599s left) gem_exec_params (secure-non-root)
[428.295323] Starting subtest: secure-non-root
[428.297159] Subtest secure-non-root: SKIP (0.000s)
[428.396734] [088/133] (599s left) gem_ctx_persistence (smoketest)
[428.467443] Starting subtest: smoketest
[468.861777] Subtest smoketest: SUCCESS (40.384s)
[468.927975] [089/133] (558s left) syncobj_timeline (wait-for-submit-snapshot)
[468.985551] Starting subtest: wait-for-submit-snapshot
[469.067818] Subtest wait-for-submit-snapshot: SUCCESS (0.081s)
[469.140515] [090/133] (558s left) kms_pm_rpm (package-g7)
[471.072358] Starting subtest: package-g7
[471.074932] Subtest package-g7: SKIP (0.000s)
[471.471557] [091/133] (556s left) kms_psr (pr-primary-render)
[471.553127] Starting subtest: pr-primary-render
[471.554916] Subtest pr-primary-render: SKIP (0.000s)
[471.631567] [092/133] (555s left) kms_chamelium_frames (dp-frame-dump)
[472.015598] Subtest dp-frame-dump: SKIP (0.000s)
[472.843063] [093/133] (554s left) gem_mmap_wc (read-write-distinct)
[472.905701] Subtest read-write-distinct: SKIP (0.000s)
[472.970782] [094/133] (554s left) gem_huc_copy (huc-copy)
[473.026556] Starting subtest: huc-copy
[473.029879] Subtest huc-copy: SUCCESS (0.003s)
[473.146103] [095/133] (554s left) gem_exec_store (pages)
[473.197733] Starting subtest: pages
[473.198880] Starting dynamic subtest: rcs0
[473.287635] Dynamic subtest rcs0: SUCCESS (0.089s)
[473.298231] Starting dynamic subtest: bcs0
[473.356556] Dynamic subtest bcs0: SUCCESS (0.067s)
[473.364808] Starting dynamic subtest: vcs0
[473.420061] Dynamic subtest vcs0: SUCCESS (0.063s)
[473.429860] Starting dynamic subtest: vcs1
[473.485906] Dynamic subtest vcs1: SUCCESS (0.065s)
[473.495726] Starting dynamic subtest: vecs0
[473.550836] Dynamic subtest vecs0: SUCCESS (0.064s)
[473.560042] Starting dynamic subtest: ccs0
[473.623650] Dynamic subtest ccs0: SUCCESS (0.071s)
[473.624615] Subtest pages: SUCCESS (0.427s)
[473.804355] [096/133] (553s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[473.900688] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[473.902521] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[473.993315] [097/133] (553s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[474.087349] Starting subtest: y-tiled-addfb-size-offset-overflow
[474.089088] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[474.188724] [098/133] (553s left) kms_cursor_crc (cursor-onscreen-256x256)
[474.432512] Starting subtest: cursor-onscreen-256x256
[474.436913] Starting dynamic subtest: pipe-A-eDP-1
[476.531596] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.095s)
[476.562252] Starting dynamic subtest: pipe-D-eDP-1
[479.353947] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.798s)
[479.354768] Subtest cursor-onscreen-256x256: SUCCESS (4.922s)
[480.236889] [099/133] (547s left) gem_softpin (allocator-fork)
[480.297426] Starting subtest: allocator-fork
[482.554432] Subtest allocator-fork: SUCCESS (2.211s)
[482.627323] [100/133] (545s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[482.739714] Starting subtest: flip-vs-cursor-atomic-transitions
[490.533442] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (7.793s)
[491.367307] [101/133] (536s left) gem_exec_params (no-blt)
[491.419898] Starting subtest: no-blt
[491.422502] Subtest no-blt: SUCCESS (0.002s)
[491.541993] [102/133] (536s left) kms_color (ctm-blue-to-red)
[491.629344] Starting subtest: ctm-blue-to-red
[491.630192] Starting dynamic subtest: pipe-A-eDP-1
[492.352412] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.722s)
[492.360062] Starting dynamic subtest: pipe-B-eDP-1
[493.789662] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.436s)
[493.799639] Starting dynamic subtest: pipe-C-eDP-1
[495.132603] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.343s)
[495.141281] Starting dynamic subtest: pipe-D-eDP-1
[496.441348] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.308s)
[496.442174] Subtest ctm-blue-to-red: SUCCESS (4.813s)
[497.267407] [103/133] (530s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[497.354457] Starting subtest: random-ccs-data-y-tiled-ccs
[497.355413] Starting dynamic subtest: pipe-A-eDP-1
[497.365367] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[497.373517] Starting dynamic subtest: pipe-B-eDP-1
[497.383732] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[497.393280] Starting dynamic subtest: pipe-C-eDP-1
[497.410862] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[497.418178] Starting dynamic subtest: pipe-D-eDP-1
[497.427464] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[497.429057] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s)
[499.623766] [104/133] (528s left) kms_psr (fbc-pr-primary-mmap-cpu)
[499.708116] Starting subtest: fbc-pr-primary-mmap-cpu
[499.709854] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[499.775502] [105/133] (528s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[499.865234] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[503.845618] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.980s)
[505.037146] [106/133] (523s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[505.133534] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[505.134403] Starting dynamic subtest: pipe-A-eDP-1
[505.155226] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[505.163088] Starting dynamic subtest: pipe-B-eDP-1
[505.183843] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[505.191076] Starting dynamic subtest: pipe-C-eDP-1
[505.212574] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[505.220189] Starting dynamic subtest: pipe-D-eDP-1
[505.241111] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[505.242848] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[507.372872] [107/133] (520s left) kms_psr (fbc-psr-cursor-plane-move)
[507.468446] Starting subtest: fbc-psr-cursor-plane-move
[507.469372] Starting dynamic subtest: eDP-1
[507.471045] Dynamic subtest eDP-1: SKIP (0.000s)
[507.472806] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[507.547785] [108/133] (520s left) kms_vblank (ts-continuation-modeset-hang)
[507.634211] Starting subtest: ts-continuation-modeset-hang
[507.636411] Starting dynamic subtest: pipe-A-eDP-1
[533.718995] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.083s)
[533.729303] Starting dynamic subtest: pipe-D-eDP-1
[548.701644] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.979s)
[548.702654] Subtest ts-continuation-modeset-hang: SUCCESS (41.066s)
[549.509938] [109/133] (478s left) kms_lease (atomic-implicit-crtc)
[549.601659] Starting subtest: atomic-implicit-crtc
[549.603064] Starting dynamic subtest: pipe-A-eDP-1
[549.608684] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[549.619094] Starting dynamic subtest: pipe-B-eDP-1
[549.623930] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[549.631663] Starting dynamic subtest: pipe-C-eDP-1
[549.636015] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[549.643469] Starting dynamic subtest: pipe-D-eDP-1
[549.647746] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[549.648566] Subtest atomic-implicit-crtc: SUCCESS (0.038s)
[549.740898] [110/133] (478s left) gen7_exec_parse (bitmasks)
[549.785774] Subtest bitmasks: SKIP (0.000s)
[549.854804] [111/133] (478s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[549.947243] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[549.948996] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[550.035204] [112/133] (478s left) gem_madvise (dontneed-before-pwrite)
[550.072241] Starting subtest: dontneed-before-pwrite
[550.086655] Subtest dontneed-before-pwrite: SKIP (0.015s)
[550.144860] [113/133] (478s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[550.235358] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[550.236940] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[550.332193] [114/133] (478s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[550.429437] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[550.431343] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[550.522902] [115/133] (477s left) perf (non-sampling-read-error)
[550.605065] Starting subtest: non-sampling-read-error
[550.612661] Subtest non-sampling-read-error: SUCCESS (0.007s)
[550.654027] [116/133] (477s left) kms_plane_alpha_blend (constant-alpha-mid)
[550.746525] Starting subtest: constant-alpha-mid
[551.037656] Starting dynamic subtest: pipe-A-eDP-1
[552.029418] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.991s)
[552.037141] Starting dynamic subtest: pipe-D-eDP-1
[553.573502] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.543s)
[553.574460] Subtest constant-alpha-mid: SUCCESS (2.827s)
[554.366156] [117/133] (474s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[554.468726] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[554.470617] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[554.569131] [118/133] (473s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[554.686452] Starting subtest: flip-vs-cursor-legacy
[562.002616] Subtest flip-vs-cursor-legacy: SUCCESS (7.314s)
[562.812860] [119/133] (465s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[562.858308] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[562.911257] [120/133] (465s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[563.000532] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[563.002391] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[563.088852] [121/133] (465s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[563.166105] Starting subtest: plane-primary-toggle-with-vblank-wait
[563.166941] Starting dynamic subtest: pipe-A-eDP-1
[563.341554] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.175s)
[563.646042] Starting dynamic subtest: pipe-B-eDP-1
[564.505035] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.866s)
[564.815094] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.648s)
[565.669446] [122/133] (462s left) i915_hangman (engine-engine-error)
[565.740412] Starting subtest: engine-engine-error
[565.741339] Starting dynamic subtest: rcs0
[566.910512] Dynamic subtest rcs0: SUCCESS (1.160s)
[566.921340] Starting dynamic subtest: bcs0
[568.893556] Dynamic subtest bcs0: SUCCESS (1.980s)
[568.903621] Starting dynamic subtest: vcs0
[570.878065] Dynamic subtest vcs0: SUCCESS (1.981s)
[570.886902] Starting dynamic subtest: vcs1
[572.925898] Dynamic subtest vcs1: SUCCESS (2.045s)
[572.936548] Starting dynamic subtest: vecs0
[574.909081] Dynamic subtest vecs0: SUCCESS (1.981s)
[574.918796] Starting dynamic subtest: ccs0
[576.894360] Dynamic subtest ccs0: SUCCESS (1.982s)
[576.895818] Subtest engine-engine-error: SUCCESS (11.145s)
[576.961605] [123/133] (451s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[577.069185] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[577.070158] Starting dynamic subtest: pipe-A-default-mode
[578.232339] Dynamic subtest pipe-A-default-mode: SKIP (1.161s)
[578.234058] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.162s)
[578.391339] [124/133] (450s left) syncobj_timeline (invalid-query-one-illegal-handle)
[578.447815] Starting subtest: invalid-query-one-illegal-handle
[578.448755] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[578.565146] [125/133] (450s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[578.654672] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[578.656361] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[578.751398] [126/133] (450s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[578.843816] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[585.396841] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.553s)
[585.517515] [127/133] (443s left) api_intel_allocator (fork-simple-stress-signal)
[585.566994] Starting subtest: fork-simple-stress-signal
[591.017978] Subtest fork-simple-stress-signal: SUCCESS (5.404s)
[591.108568] [128/133] (437s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier)
[591.207889] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier
[591.209757] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s)
[591.278993] [129/133] (437s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[591.370920] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[591.372743] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[591.478812] [130/133] (437s left) gem_userptr_blits (usage-restrictions)
[591.547209] Starting subtest: usage-restrictions
[591.548053] Subtest usage-restrictions: SUCCESS (0.000s)
[591.674667] [131/133] (437s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[591.772898] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[598.803670] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.032s)
[598.924514] [132/133] (430s left) gem_mmap_offset (clear)
[598.980504] Starting subtest: clear
[598.981483] Starting dynamic subtest: smem0
[624.208738] Dynamic subtest smem0: SUCCESS (25.225s)
[624.209776] Subtest clear: SUCCESS (25.227s)
[624.332326] [133/133] (404s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[624.471231] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[624.483828] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.012s)
[624.568666] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: api_intel_bb
results: parsing output: 3/ for test: fbdev
results: parsing output: 4/ for test: syncobj_wait
results: parsing output: 5/ for test: gem_exec_fence
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: kms_chamelium_hpd
results: parsing output: 9/ for test: kms_plane
results: parsing output: 10/ for test: gem_exec_reloc
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_wait
results: parsing output: 13/ for test: gem_exec_parallel
results: parsing output: 14/ for test: kms_cursor_crc
results: parsing output: 15/ for test: kms_rotation_crc
results: parsing output: 16/ for test: gem_close_race
results: parsing output: 17/ for test: gem_mmap
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_userptr_blits
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: kms_async_flips
results: parsing output: 23/ for test: gem_ctx_param
results: parsing output: 24/ for test: kms_atomic
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: gen9_exec_parse
results: parsing output: 28/ for test: core_setmaster_vs_auth
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: kms_psr2_sf
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: gem_tiled_wc
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: gem_render_copy
results: parsing output: 36/ for test: syncobj_timeline
results: parsing output: 37/ for test: kms_pm_dc
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: gem_exec_flush
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: gem_pread
results: parsing output: 43/ for test: i915_pm_rps
results: parsing output: 44/ for test: kms_psr
results: parsing output: 45/ for test: kms_rmfb
results: parsing output: 46/ for test: kms_addfb_basic
results: parsing output: 47/ for test: kms_prop_blob
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_plane_scaling
results: parsing output: 51/ for test: core_getversion
results: parsing output: 52/ for test: kms_joiner
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: prime_busy
results: parsing output: 55/ for test: kms_plane_scaling
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_atomic_transition
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: prime_mmap_kms
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: gem_exec_fence
results: parsing output: 63/ for test: kms_content_protection
results: parsing output: 64/ for test: i915_pm_rps
results: parsing output: 65/ for test: gem_pread
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_ctx_shared
results: parsing output: 69/ for test: gem_exec_reloc
results: parsing output: 70/ for test: prime_mmap
results: parsing output: 71/ for test: gem_mmap_gtt
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: gem_exec_balancer
results: parsing output: 75/ for test: gem_exec_schedule
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: prime_self_import
results: parsing output: 78/ for test: kms_atomic_transition
results: parsing output: 79/ for test: kms_dsc
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_render_copy
results: parsing output: 83/ for test: gem_exec_schedule
results: parsing output: 84/ for test: kms_sequence
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: gem_exec_params
results: parsing output: 87/ for test: gem_ctx_persistence
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: kms_pm_rpm
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: kms_chamelium_frames
results: parsing output: 92/ for test: gem_mmap_wc
results: parsing output: 93/ for test: gem_huc_copy
results: parsing output: 94/ for test: gem_exec_store
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: kms_cursor_crc
results: parsing output: 98/ for test: gem_softpin
results: parsing output: 99/ for test: kms_cursor_legacy
results: parsing output: 100/ for test: gem_exec_params
results: parsing output: 101/ for test: kms_color
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_ccs
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: kms_vblank
results: parsing output: 108/ for test: kms_lease
results: parsing output: 109/ for test: gen7_exec_parse
results: parsing output: 110/ for test: kms_flip_scaled_crc
results: parsing output: 111/ for test: gem_madvise
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: perf
results: parsing output: 115/ for test: kms_plane_alpha_blend
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: gem_mmap_gtt
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_atomic_transition
results: parsing output: 121/ for test: i915_hangman
results: parsing output: 122/ for test: kms_flip_scaled_crc
results: parsing output: 123/ for test: syncobj_timeline
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: api_intel_allocator
results: parsing output: 127/ for test: kms_plane
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_userptr_blits
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_mmap_offset
results: parsing output: 132/ for test: kms_flip
Done.