[57.443870] Initializing watchdogs
[57.443915]   /dev/watchdog0
[57.457949] [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.462386] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.464402] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.466215] [001/133] (960s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[57.559235] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[57.560645] Starting dynamic subtest: pipe-A-eDP-1
[57.570507] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[57.576910] Starting dynamic subtest: pipe-B-eDP-1
[57.585996] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[57.592628] Starting dynamic subtest: pipe-C-eDP-1
[57.601388] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.606739] Starting dynamic subtest: pipe-D-eDP-1
[57.615837] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.617734] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s)
[59.774595] [002/133] (958s left) perf_pmu (busy-double-start)
[59.818054] Starting subtest: busy-double-start
[59.818976] Starting dynamic subtest: rcs0
[63.422592] Dynamic subtest rcs0: FAIL (3.590s)
[63.428282] Starting dynamic subtest: bcs0
[67.059912] Dynamic subtest bcs0: SUCCESS (3.659s)
[67.068986] Starting dynamic subtest: vcs0
[71.451662] Dynamic subtest vcs0: SUCCESS (4.393s)
[71.459762] Starting dynamic subtest: vcs1
[75.452327] Dynamic subtest vcs1: SUCCESS (4.001s)
[75.460734] Starting dynamic subtest: vecs0
[79.451413] Dynamic subtest vecs0: SUCCESS (3.999s)
[79.459864] Starting dynamic subtest: ccs0
[83.044681] Dynamic subtest ccs0: FAIL (3.567s)
[83.045543] Subtest busy-double-start: FAIL (23.213s)
[83.156913] [003/133] (934s left) sw_sync (sync_merge)
[83.190555] Starting subtest: sync_merge
[83.191649] Subtest sync_merge: SUCCESS (0.000s)
[83.260430] [004/133] (934s left) gem_pread (self)
[83.315105] Subtest self: SKIP (0.000s)
[83.375383] [005/133] (934s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[83.501769] Starting subtest: flip-vs-cursor-crc-atomic
[84.170537] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.667s)
[85.017196] [006/133] (933s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[85.112274] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[85.114937] Starting dynamic subtest: pipe-A-eDP-1
[85.176814] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[85.466417] Starting dynamic subtest: pipe-B-eDP-1
[86.305220] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.845s)
[86.612141] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.499s)
[87.467376] [007/133] (930s left) gem_exec_gttfill (multigpu-basic)
[87.640673] Starting subtest: multigpu-basic
[103.147837] Subtest multigpu-basic: SUCCESS (15.506s)
[103.194472] [008/133] (914s left) kms_psr (fbc-pr-basic)
[103.320959] Starting subtest: fbc-pr-basic
[103.322772] Subtest fbc-pr-basic: SKIP (0.000s)
[103.396694] [009/133] (914s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[103.501393] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[110.353430] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.849s)
[110.538692] [010/133] (907s left) gem_exec_whisper (basic-sync)
[110.600748] Starting subtest: basic-sync
[116.564052] Subtest basic-sync: SUCCESS (5.961s)
[116.752713] [011/133] (901s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[116.851904] Starting subtest: yf-tiled-32bpp-rotate-270
[116.853900] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[116.964357] [012/133] (901s left) gem_exec_schedule (u-fairslice)
[117.035360] Starting subtest: u-fairslice
[117.036406] Starting dynamic subtest: rcs0
[119.058838] Dynamic subtest rcs0: SUCCESS (2.021s)
[119.066918] Starting dynamic subtest: bcs0
[121.076320] Dynamic subtest bcs0: SUCCESS (2.017s)
[121.084559] Starting dynamic subtest: vcs0
[123.094215] Dynamic subtest vcs0: SUCCESS (2.018s)
[123.100572] Starting dynamic subtest: vcs1
[125.112274] Dynamic subtest vcs1: SUCCESS (2.017s)
[125.118372] Starting dynamic subtest: vecs0
[127.131253] Dynamic subtest vecs0: SUCCESS (2.018s)
[127.142799] Starting dynamic subtest: ccs0
[129.150079] Dynamic subtest ccs0: SUCCESS (2.020s)
[129.151093] Subtest u-fairslice: SUCCESS (12.113s)
[129.454357] [013/133] (888s left) kms_psr (psr2-suspend)
[129.549075] Starting subtest: psr2-suspend
[129.550048] Starting dynamic subtest: eDP-1
[148.013537] Dynamic subtest eDP-1: SUCCESS (3.394s)
[148.014811] Subtest psr2-suspend: SUCCESS (3.395s)
[154.824132] [014/133] (869s left) syncobj_wait (multi-wait-all-submitted)
[154.897997] Starting subtest: multi-wait-all-submitted
[155.000361] Subtest multi-wait-all-submitted: SUCCESS (0.102s)
[155.090640] [015/133] (869s left) gem_softpin (noreloc-s3)
[155.158250] Starting subtest: noreloc-S3
[175.299504] Subtest noreloc-S3: SUCCESS (5.387s)
[178.454338] [016/133] (848s left) i915_pm_freq_api (freq-basic-api)
[178.522025] Starting subtest: freq-basic-api
[178.522991] Starting dynamic subtest: gt0
[178.527581] Dynamic subtest gt0: SUCCESS (0.006s)
[178.536775] Starting dynamic subtest: gt1
[178.537597] Dynamic subtest gt1: SUCCESS (0.003s)
[178.538431] Subtest freq-basic-api: SUCCESS (0.009s)
[178.659233] [017/133] (848s left) kms_psr (fbc-psr-no-drrs)
[178.752266] Starting subtest: fbc-psr-no-drrs
[178.753243] Starting dynamic subtest: eDP-1
[178.755041] Dynamic subtest eDP-1: SKIP (0.000s)
[178.756743] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[178.829627] [018/133] (848s left) kms_cursor_edge_walk (256x256-left-edge)
[178.913840] Starting subtest: 256x256-left-edge
[178.914771] Starting dynamic subtest: pipe-A-eDP-1
[182.378568] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.475s)
[182.386389] Starting dynamic subtest: pipe-D-eDP-1
[185.498080] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[185.499022] Subtest 256x256-left-edge: SUCCESS (6.601s)
[186.311183] [019/133] (841s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[186.429267] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[186.431176] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[186.553182] [020/133] (841s left) syncobj_timeline (wait-all-for-submit-complex)
[186.618347] Starting subtest: wait-all-for-submit-complex
[186.834358] Subtest wait-all-for-submit-complex: SUCCESS (0.214s)
[186.910306] [021/133] (840s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[187.143380] Starting subtest: cursor-rapid-movement-32x10
[187.185921] Subtest cursor-rapid-movement-32x10: SKIP (0.040s)
[187.502861] [022/133] (840s left) prime_busy (after)
[187.564032] Starting subtest: after
[187.567357] Starting dynamic subtest: rcs0
[187.624177] Dynamic subtest rcs0: SUCCESS (0.056s)
[187.633425] Starting dynamic subtest: bcs0
[187.707676] Dynamic subtest bcs0: SUCCESS (0.083s)
[187.716757] Starting dynamic subtest: vcs0
[187.746752] Dynamic subtest vcs0: SUCCESS (0.039s)
[187.756264] Starting dynamic subtest: vcs1
[187.787124] Dynamic subtest vcs1: SUCCESS (0.040s)
[187.794907] Starting dynamic subtest: vecs0
[187.826747] Dynamic subtest vecs0: SUCCESS (0.039s)
[187.834723] Starting dynamic subtest: ccs0
[187.866807] Dynamic subtest ccs0: SUCCESS (0.040s)
[187.976518] Subtest after: SUCCESS (0.410s)
[188.054623] [023/133] (839s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[188.116832] Subtest writes-after-reads: SKIP (0.000s)
[188.176770] [024/133] (839s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[188.283141] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[188.285085] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[188.404648] [025/133] (839s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[188.489412] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[188.491215] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[188.577665] [026/133] (839s left) kms_flip (2x-flip-vs-modeset)
[188.675304] Starting subtest: 2x-flip-vs-modeset
[188.685616] Subtest 2x-flip-vs-modeset: SKIP (0.011s)
[188.810372] [027/133] (838s left) gen7_exec_parse (basic-offset)
[188.882224] Subtest basic-offset: SKIP (0.000s)
[188.966217] [028/133] (838s left) i915_drm_fdinfo (busy-check-all)
[189.002351] Starting subtest: busy-check-all
[189.003315] Starting dynamic subtest: rcs0
[189.010338] Dynamic subtest rcs0: SKIP (0.007s)
[189.017938] Starting dynamic subtest: bcs0
[189.019781] Dynamic subtest bcs0: SKIP (0.000s)
[189.026741] Starting dynamic subtest: vcs0
[189.028430] Dynamic subtest vcs0: SKIP (0.000s)
[189.035310] Starting dynamic subtest: vcs1
[189.037322] Dynamic subtest vcs1: SKIP (0.000s)
[189.044242] Starting dynamic subtest: vecs0
[189.046169] Dynamic subtest vecs0: SKIP (0.000s)
[189.053067] Starting dynamic subtest: ccs0
[189.054884] Dynamic subtest ccs0: SKIP (0.000s)
[189.056743] Subtest busy-check-all: SKIP (0.010s)
[189.086409] [029/133] (838s left) gem_exec_reloc (basic-gtt-active)
[189.183688] Subtest basic-gtt-active: SKIP (0.000s)
[189.250078] [030/133] (838s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[189.338070] Starting subtest: 4-tiled-8bpp-rotate-270
[189.358758] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.020s)
[189.497153] [031/133] (838s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[189.603114] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[189.604830] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[189.731631] [032/133] (838s left) gem_mmap_wc (coherency)
[189.787543] Subtest coherency: SKIP (0.000s)
[189.841978] [033/133] (838s left) gem_softpin (evict-snoop)
[189.906502] Starting subtest: evict-snoop
[189.908427] Subtest evict-snoop: SKIP (0.000s)
[190.044851] [034/133] (837s left) kms_setmode (basic)
[190.097203] Starting subtest: basic
[190.421248] Starting dynamic subtest: pipe-A-eDP-1
[193.913830] Dynamic subtest pipe-A-eDP-1: FAIL (3.454s)
[194.211642] Starting dynamic subtest: pipe-B-eDP-1
[197.655511] Dynamic subtest pipe-B-eDP-1: FAIL (3.402s)
[197.905321] Subtest basic: FAIL (7.814s)
[198.709492] [035/133] (829s left) i915_selftest (perf)
[202.247519] Starting subtest: perf
[202.249255] Starting dynamic subtest: engine_cs
[206.589696] Dynamic subtest engine_cs: SUCCESS (4.340s)
[206.597757] Starting dynamic subtest: request
[213.872304] Dynamic subtest request: SUCCESS (7.278s)
[213.880316] Starting dynamic subtest: migrate
[218.261359] Dynamic subtest migrate: SUCCESS (4.387s)
[218.275588] Starting dynamic subtest: region
[224.239039] Dynamic subtest region: SUCCESS (5.976s)
[224.240233] Subtest perf: SUCCESS (21.986s)
[224.258949] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[224.260393] [036/133] (803s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[225.759311] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[225.761150] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[225.849830] [FACT kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)] new: kernel.kmod_is_loaded.i915: true
[225.852851] [037/133] (802s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[225.949537] Starting subtest: plane-scaler-unity-scaling-with-rotation
[225.950391] Starting dynamic subtest: pipe-A
[226.187583] Dynamic subtest pipe-A: SUCCESS (0.238s)
[226.194827] Starting dynamic subtest: pipe-B
[227.457671] Dynamic subtest pipe-B: SUCCESS (1.270s)
[227.464903] Starting dynamic subtest: pipe-C
[228.782159] Dynamic subtest pipe-C: SUCCESS (1.324s)
[228.789810] Starting dynamic subtest: pipe-D
[230.110087] Dynamic subtest pipe-D: SUCCESS (1.327s)
[230.111070] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.162s)
[231.296848] [038/133] (796s left) gem_mmap_gtt (basic-read)
[231.351524] Subtest basic-read: SKIP (0.000s)
[231.405369] [039/133] (796s left) kms_properties (plane-properties-atomic)
[231.502785] Starting subtest: plane-properties-atomic
[231.503659] Starting dynamic subtest: pipe-A-eDP-1
[232.064365] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.561s)
[232.071517] Starting dynamic subtest: pipe-B-eDP-1
[233.364775] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.299s)
[233.376283] Starting dynamic subtest: pipe-C-eDP-1
[234.596443] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.231s)
[234.603494] Starting dynamic subtest: pipe-D-eDP-1
[235.838413] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.242s)
[235.839273] Subtest plane-properties-atomic: SUCCESS (4.338s)
[236.645911] [040/133] (791s left) kms_color (invalid-gamma-lut-sizes)
[236.748745] Starting subtest: invalid-gamma-lut-sizes
[236.749621] Starting dynamic subtest: pipe-A
[237.041554] Dynamic subtest pipe-A: SUCCESS (0.293s)
[237.050461] Starting dynamic subtest: pipe-B
[237.051301] Dynamic subtest pipe-B: SUCCESS (0.000s)
[237.057829] Starting dynamic subtest: pipe-C
[237.058723] Dynamic subtest pipe-C: SUCCESS (0.000s)
[237.065503] Starting dynamic subtest: pipe-D
[237.066348] Dynamic subtest pipe-D: SUCCESS (0.000s)
[237.067223] Subtest invalid-gamma-lut-sizes: SUCCESS (0.296s)
[237.868288] [041/133] (790s left) dmabuf_sync_file (export-before-signal)
[237.926408] Starting subtest: export-before-signal
[237.927288] Subtest export-before-signal: SUCCESS (0.001s)
[237.987520] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[237.989201] [042/133] (790s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[238.080776] Starting subtest: psr-1p-pri-indfb-multidraw
[260.209900] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.138s)
[260.353856] [043/133] (767s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[260.457661] Starting subtest: compare-crc-sanitycheck-xr24
[260.458519] Starting dynamic subtest: pipe-A-eDP-1
[261.116598] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.659s)
[261.124772] Starting dynamic subtest: pipe-B-eDP-1
[262.362208] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.243s)
[262.374023] Starting dynamic subtest: pipe-C-eDP-1
[263.539272] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.177s)
[263.547069] Starting dynamic subtest: pipe-D-eDP-1
[264.781034] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.241s)
[264.781942] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.326s)
[265.643714] [044/133] (762s left) gem_readwrite (new-obj)
[265.711178] Subtest new-obj: SKIP (0.000s)
[265.779494] [045/133] (762s left) i915_pm_freq_mult (media-freq)
[265.832544] Starting subtest: media-freq
[265.833524] Starting dynamic subtest: gt0
[265.835277] Dynamic subtest gt0: SKIP (0.000s)
[265.844202] Starting dynamic subtest: gt1
[265.845839] Dynamic subtest gt1: SKIP (0.000s)
[265.847417] Subtest media-freq: SKIP (0.002s)
[265.952060] [046/133] (762s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[266.049433] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[266.051151] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[266.168289] [047/133] (762s left) kms_cursor_crc (cursor-offscreen-512x170)
[266.436929] Starting subtest: cursor-offscreen-512x170
[266.438559] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[266.607723] [048/133] (761s left) perf (i915-ref-count)
[266.689589] Starting subtest: i915-ref-count
[266.749941] Subtest i915-ref-count: SUCCESS (0.059s)
[266.811504] [049/133] (761s left) kms_async_flips (alternate-sync-async-flip)
[266.900702] Starting subtest: alternate-sync-async-flip
[267.275555] Starting dynamic subtest: pipe-A-eDP-1
[270.038793] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.763s)
[270.413617] Starting dynamic subtest: pipe-B-eDP-1
[273.165150] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.758s)
[273.529756] Starting dynamic subtest: pipe-C-eDP-1
[276.297614] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.773s)
[276.660227] Starting dynamic subtest: pipe-D-eDP-1
[279.433726] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.779s)
[279.434743] Subtest alternate-sync-async-flip: SUCCESS (12.530s)
[280.627821] [050/133] (747s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[280.699983] Starting subtest: invalid-multi-wait-unsubmitted
[280.700860] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[280.821323] [051/133] (747s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[280.917639] Starting subtest: primary-y-tiled-reflect-x-270
[280.919371] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[281.008809] [052/133] (747s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[281.102952] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[281.103925] Starting dynamic subtest: pipe-A-eDP-1
[281.914493] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.809s)
[281.922302] Starting dynamic subtest: pipe-B-eDP-1
[283.427295] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.511s)
[283.434675] Starting dynamic subtest: pipe-C-eDP-1
[284.912914] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.483s)
[284.921255] Starting dynamic subtest: pipe-D-eDP-1
[286.433436] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.519s)
[286.434331] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.327s)
[287.615500] [053/133] (740s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[287.714071] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[294.167183] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.454s)
[294.335936] [054/133] (734s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[294.448003] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[294.449821] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[294.564896] [055/133] (734s left) gem_mmap_offset (close-race)
[294.620576] Starting subtest: close-race
[314.735364] Subtest close-race: SUCCESS (20.110s)
[314.891790] [056/133] (713s left) vgem_basic (dmabuf-export)
[314.938178] Starting subtest: dmabuf-export
[314.994868] Subtest dmabuf-export: SUCCESS (0.057s)
[315.112712] [057/133] (713s left) syncobj_eventfd (timeline-wait)
[315.175794] Starting subtest: timeline-wait
[315.186255] Subtest timeline-wait: SUCCESS (0.010s)
[315.309837] [058/133] (713s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[315.411547] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[315.413227] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[315.501048] [059/133] (713s left) gem_exec_whisper (basic-normal-all)
[315.574686] Starting subtest: basic-normal-all
[317.626166] Subtest basic-normal-all: SUCCESS (2.050s)
[317.818844] [060/133] (711s left) kms_big_fb (linear-8bpp-rotate-0)
[317.914748] Starting subtest: linear-8bpp-rotate-0
[319.072565] Subtest linear-8bpp-rotate-0: SUCCESS (1.158s)
[319.226098] [061/133] (709s left) fbdev (unaligned-write)
[319.270620] Starting subtest: unaligned-write
[319.372680] Subtest unaligned-write: SUCCESS (0.101s)
[319.453049] [062/133] (709s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[319.553800] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[319.554871] Starting dynamic subtest: pipe-A-eDP-1
[320.351926] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.796s)
[320.363790] Starting dynamic subtest: pipe-B-eDP-1
[321.870685] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.518s)
[321.878401] Starting dynamic subtest: pipe-C-eDP-1
[323.333199] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.461s)
[323.341090] Starting dynamic subtest: pipe-D-eDP-1
[324.787023] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.453s)
[324.787861] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.233s)
[325.981635] [063/133] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[326.078306] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[326.080161] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[326.197296] [064/133] (702s left) gem_exec_reloc (basic-wc-read-active)
[326.254588] Subtest basic-wc-read-active: SKIP (0.000s)
[326.333584] [065/133] (702s left) gem_userptr_blits (nohangcheck)
[326.407571] Starting subtest: nohangcheck
[326.700437] Subtest nohangcheck: SUCCESS (0.289s)
[326.843670] [066/133] (702s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[326.935533] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[326.936833] Starting dynamic subtest: A-eDP1
[335.819689] Dynamic subtest A-eDP1: SUCCESS (8.884s)
[335.829689] Starting dynamic subtest: B-eDP1
[344.744294] Dynamic subtest B-eDP1: SUCCESS (8.921s)
[344.754050] Starting dynamic subtest: C-eDP1
[353.666624] Dynamic subtest C-eDP1: SUCCESS (8.924s)
[353.676948] Starting dynamic subtest: D-eDP1
[362.587765] Dynamic subtest D-eDP1: SUCCESS (8.922s)
[362.591328] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.663s)
[363.811536] [067/133] (665s left) kms_getfb (getfb2-handle-zero)
[363.906065] Starting subtest: getfb2-handle-zero
[363.907094] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[364.011739] [068/133] (665s left) gem_partial_pwrite_pread (writes-after-reads)
[364.063290] Subtest writes-after-reads: SKIP (0.000s)
[364.126760] [069/133] (665s left) prime_mmap (test_map_unmap)
[364.194003] Starting subtest: test_map_unmap
[364.195004] Starting dynamic subtest: test_map_unmap-smem
[364.227909] Dynamic subtest test_map_unmap-smem: SUCCESS (0.033s)
[364.229096] Subtest test_map_unmap: SUCCESS (0.034s)
[364.351371] [070/133] (664s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[364.440947] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[364.441863] Starting dynamic subtest: pipe-A-eDP-1
[364.464153] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[364.472559] Starting dynamic subtest: pipe-B-eDP-1
[364.494166] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[364.501747] Starting dynamic subtest: pipe-C-eDP-1
[364.523510] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[364.531516] Starting dynamic subtest: pipe-D-eDP-1
[364.553143] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[364.554760] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[366.696080] [071/133] (662s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[366.788054] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[366.789714] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[366.882229] [072/133] (662s left) kms_prime (basic-crc-vgem)
[366.961428] Starting subtest: basic-crc-vgem
[366.962294] Starting dynamic subtest: second-to-first
[367.953956] Dynamic subtest second-to-first: SUCCESS (0.994s)
[367.954810] Subtest basic-crc-vgem: SUCCESS (0.994s)
[368.051159] [073/133] (661s left) gem_ctx_persistence (legacy-engines-hang)
[368.148204] Starting subtest: legacy-engines-hang
[368.149253] Starting dynamic subtest: render
[368.323198] Dynamic subtest render: SUCCESS (0.174s)
[368.335267] Starting dynamic subtest: bsd1
[368.451356] Dynamic subtest bsd1: SUCCESS (0.125s)
[368.464074] Starting dynamic subtest: bsd2
[368.581352] Dynamic subtest bsd2: SUCCESS (0.127s)
[368.593168] Starting dynamic subtest: blt
[368.689408] Dynamic subtest blt: SUCCESS (0.105s)
[368.701508] Starting dynamic subtest: vebox
[368.800429] Dynamic subtest vebox: SUCCESS (0.108s)
[368.802157] Subtest legacy-engines-hang: SUCCESS (0.653s)
[368.886432] [074/133] (660s left) kms_psr (fbc-pr-sprite-plane-move)
[368.980582] Starting subtest: fbc-pr-sprite-plane-move
[368.982270] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[369.061714] [075/133] (660s left) vgem_slow (nohang)
[369.106434] Starting subtest: nohang
[379.152318] Subtest nohang: SUCCESS (10.045s)
[379.225012] [076/133] (650s left) gem_ctx_persistence (engines-hostile)
[379.282084] Starting subtest: engines-hostile
[379.283240] Starting dynamic subtest: rcs0
[379.446456] Dynamic subtest rcs0: SUCCESS (0.163s)
[379.459134] Starting dynamic subtest: bcs0
[379.556776] Dynamic subtest bcs0: SUCCESS (0.108s)
[379.568187] Starting dynamic subtest: vcs0
[379.680341] Dynamic subtest vcs0: SUCCESS (0.121s)
[379.692002] Starting dynamic subtest: vcs1
[379.798430] Dynamic subtest vcs1: SUCCESS (0.116s)
[379.810484] Starting dynamic subtest: vecs0
[379.928407] Dynamic subtest vecs0: SUCCESS (0.128s)
[379.940961] Starting dynamic subtest: ccs0
[380.009875] Dynamic subtest ccs0: SUCCESS (0.080s)
[380.012949] Subtest engines-hostile: SUCCESS (0.729s)
[380.159057] [077/133] (649s left) syncobj_basic (bad-destroy)
[380.222567] Starting subtest: bad-destroy
[380.223375] Subtest bad-destroy: SUCCESS (0.000s)
[380.342689] [078/133] (649s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[380.439055] Starting subtest: fbc-rgb565-draw-blt
[384.685696] Subtest fbc-rgb565-draw-blt: SUCCESS (4.248s)
[385.883255] [079/133] (643s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[385.964316] Starting subtest: psr2-cursor-plane-update-sf
[385.965228] Starting dynamic subtest: pipe-A-eDP-1
[386.531845] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.567s)
[386.545040] Starting dynamic subtest: pipe-B-eDP-1
[387.866181] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.334s)
[387.867095] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.904s)
[388.731668] [080/133] (640s left) kms_lease (lease-invalid-plane)
[388.828654] Starting subtest: lease-invalid-plane
[388.829536] Starting dynamic subtest: pipe-A-eDP-1
[388.830376] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[388.838415] Starting dynamic subtest: pipe-B-eDP-1
[388.839314] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[388.847104] Starting dynamic subtest: pipe-C-eDP-1
[388.847986] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[388.855611] Starting dynamic subtest: pipe-D-eDP-1
[388.856543] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[388.857379] Subtest lease-invalid-plane: SUCCESS (0.004s)
[388.908952] [081/133] (640s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[389.001092] Starting subtest: yf-tiled-8bpp-rotate-0
[389.003039] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[389.124595] [082/133] (640s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[389.204921] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[389.206670] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[389.337515] [083/133] (640s left) kms_atomic (crtc-invalid-params-fence)
[389.441897] Starting subtest: crtc-invalid-params-fence
[389.442782] Starting dynamic subtest: pipe-A-eDP-1
[389.847039] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.404s)
[389.848016] Subtest crtc-invalid-params-fence: SUCCESS (0.405s)
[390.668371] [084/133] (638s left) gem_softpin (32b-excludes-last-page)
[390.727240] Starting subtest: 32b-excludes-last-page
[390.730750] Subtest 32b-excludes-last-page: SUCCESS (0.004s)
[390.885668] [085/133] (638s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[390.986385] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[390.988130] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[391.111868] [086/133] (638s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[391.207406] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[391.209326] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[391.326888] [087/133] (638s left) prime_vgem (basic-read)
[391.419314] Starting subtest: basic-read
[391.424971] Subtest basic-read: SKIP (0.005s)
[391.552994] [088/133] (638s left) kms_plane (pixel-format-yf-tiled-ccs-modifier)
[391.649076] Starting subtest: pixel-format-yf-tiled-ccs-modifier
[391.676578] Starting dynamic subtest: pipe-A-plane-0
[391.678874] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[391.687884] Starting dynamic subtest: pipe-A-plane-3
[391.690014] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[391.697517] Starting dynamic subtest: pipe-A-plane-5
[391.699546] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[392.750818] Starting dynamic subtest: pipe-B-plane-0
[392.752704] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[392.762596] Starting dynamic subtest: pipe-B-plane-3
[392.764627] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[392.774530] Starting dynamic subtest: pipe-B-plane-5
[392.776928] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[393.061128] Subtest pixel-format-yf-tiled-ccs-modifier: SKIP (1.411s)
[393.945969] [089/133] (635s left) syncobj_timeline (invalid-query-zero-handles)
[394.002524] Starting subtest: invalid-query-zero-handles
[394.003515] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[394.122307] [090/133] (635s left) perf_pmu (invalid-open)
[394.152647] Starting subtest: invalid-open
[394.153676] Subtest invalid-open: SUCCESS (0.000s)
[394.259120] [091/133] (635s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[394.369275] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[394.371170] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[394.494319] [092/133] (635s left) kms_busy (extended-modeset-hang-oldfb)
[394.587843] Starting subtest: extended-modeset-hang-oldfb
[394.588734] Starting dynamic subtest: pipe-A
[406.380180] Dynamic subtest pipe-A: SUCCESS (11.788s)
[406.389359] Starting dynamic subtest: pipe-D
[418.864041] Dynamic subtest pipe-D: SUCCESS (12.476s)
[418.865039] Subtest extended-modeset-hang-oldfb: SUCCESS (24.269s)
[419.676472] [093/133] (610s left) gem_exec_fence (syncobj-timeline-chain-engines)
[420.228284] Starting subtest: syncobj-timeline-chain-engines
[420.329361] Subtest syncobj-timeline-chain-engines: SUCCESS (0.103s)
[421.850630] [094/133] (608s left) kms_rotation_crc (primary-rotation-90)
[421.946736] Starting subtest: primary-rotation-90
[421.948421] Subtest primary-rotation-90: SKIP (0.001s)
[422.026075] [095/133] (607s left) kms_chamelium_hpd (dp-hpd-fast)
[422.404577] Subtest dp-hpd-fast: SKIP (0.000s)
[423.172020] [096/133] (606s left) gem_create (create-ext-cpu-access-sanity-check)
[423.332381] Starting subtest: create-ext-cpu-access-sanity-check
[423.335233] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[423.424064] [097/133] (606s left) vgem_basic (bad-flag)
[423.467906] Starting subtest: bad-flag
[423.468862] Subtest bad-flag: SUCCESS (0.000s)
[423.532443] [098/133] (606s left) kms_pm_backlight (fade-with-dpms)
[423.627932] Starting subtest: fade-with-dpms
[425.777530] Starting dynamic subtest: eDP-1
[428.458985] Dynamic subtest eDP-1: SUCCESS (2.681s)
[428.459836] Subtest fade-with-dpms: SUCCESS (4.831s)
[429.273791] [099/133] (600s left) gem_exec_fence (basic-wait)
[429.453024] Starting subtest: basic-wait
[429.454220] Starting dynamic subtest: rcs0
[429.548877] Dynamic subtest rcs0: SUCCESS (0.096s)
[429.560523] Starting dynamic subtest: bcs0
[429.611748] Dynamic subtest bcs0: SUCCESS (0.061s)
[429.623256] Starting dynamic subtest: vcs0
[429.672543] Dynamic subtest vcs0: SUCCESS (0.059s)
[429.683580] Starting dynamic subtest: vcs1
[429.733247] Dynamic subtest vcs1: SUCCESS (0.059s)
[429.744861] Starting dynamic subtest: vecs0
[429.794379] Dynamic subtest vecs0: SUCCESS (0.059s)
[429.805395] Starting dynamic subtest: ccs0
[429.848421] Dynamic subtest ccs0: SUCCESS (0.052s)
[429.851293] Subtest basic-wait: SUCCESS (0.397s)
[431.847610] [100/133] (598s left) core_getclient (basic)
[431.907512] Starting subtest: basic
[431.908480] Subtest basic: SUCCESS (0.000s)
[432.024455] [101/133] (598s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[432.130350] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[432.132103] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[432.245936] [102/133] (597s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[432.311425] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[432.312293] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[432.457065] [103/133] (597s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[432.519554] Starting subtest: yf-tiled-ccs-to-yf-tiled
[432.521398] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[432.711068] [104/133] (597s left) gem_exec_schedule (pi-common)
[432.892700] Starting subtest: pi-common
[432.893748] Starting dynamic subtest: rcs0
[432.921626] Dynamic subtest rcs0: SUCCESS (0.030s)
[432.935231] Starting dynamic subtest: bcs0
[432.948248] Dynamic subtest bcs0: SUCCESS (0.026s)
[432.956411] Starting dynamic subtest: vcs0
[432.975275] Dynamic subtest vcs0: SUCCESS (0.026s)
[432.987183] Starting dynamic subtest: vcs1
[433.002639] Dynamic subtest vcs1: SUCCESS (0.027s)
[433.010488] Starting dynamic subtest: vecs0
[433.029282] Dynamic subtest vecs0: SUCCESS (0.026s)
[433.041664] Starting dynamic subtest: ccs0
[433.054684] Dynamic subtest ccs0: SUCCESS (0.025s)
[433.055545] Subtest pi-common: SUCCESS (0.164s)
[433.314037] [105/133] (597s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[433.368250] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[433.440677] [106/133] (596s left) kms_vblank (ts-continuation-dpms-rpm)
[433.531060] Starting subtest: ts-continuation-dpms-rpm
[433.532049] Starting dynamic subtest: pipe-A-eDP-1
[436.064514] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.532s)
[436.075442] Starting dynamic subtest: pipe-D-eDP-1
[438.302014] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.233s)
[438.303185] Subtest ts-continuation-dpms-rpm: SUCCESS (4.770s)
[439.102064] [107/133] (591s left) gem_ctx_shared (single-timeline)
[439.162884] Starting subtest: single-timeline
[439.179412] Subtest single-timeline: SUCCESS (0.017s)
[439.444162] [108/133] (591s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[439.540655] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[439.542355] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[439.639879] [109/133] (590s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[439.744936] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[439.746720] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[439.860181] [110/133] (590s left) gem_exec_params (invalid-ring2)
[439.923046] Starting subtest: invalid-ring2
[439.924134] Subtest invalid-ring2: SUCCESS (0.000s)
[440.042165] [111/133] (590s left) kms_dirtyfb (default-dirtyfb-ioctl)
[440.144129] Starting subtest: default-dirtyfb-ioctl
[440.145051] Starting dynamic subtest: A-eDP-1
[441.759938] Dynamic subtest A-eDP-1: SUCCESS (1.615s)
[441.760756] Subtest default-dirtyfb-ioctl: SUCCESS (1.616s)
[442.637898] [112/133] (587s left) kms_psr (pr-primary-render)
[442.731924] Starting subtest: pr-primary-render
[442.733544] Subtest pr-primary-render: SKIP (0.000s)
[442.812674] [113/133] (587s left) gem_ctx_bad_destroy (double-destroy)
[442.867251] Starting subtest: double-destroy
[442.868304] Subtest double-destroy: SUCCESS (0.000s)
[442.946554] [114/133] (587s left) kms_plane_scaling (planes-downscale-factor-0-75)
[443.040276] Starting subtest: planes-downscale-factor-0-75
[443.041298] Starting dynamic subtest: pipe-A
[443.058039] Dynamic subtest pipe-A: SKIP (0.016s)
[443.066399] Starting dynamic subtest: pipe-B
[443.085531] Dynamic subtest pipe-B: SKIP (0.027s)
[443.093661] Starting dynamic subtest: pipe-C
[443.112073] Dynamic subtest pipe-C: SKIP (0.026s)
[443.119606] Starting dynamic subtest: pipe-D
[443.138963] Dynamic subtest pipe-D: SKIP (0.026s)
[443.140548] Subtest planes-downscale-factor-0-75: SKIP (0.099s)
[443.257442] [115/133] (587s left) kms_plane_multiple (tiling-none)
[443.341909] Starting subtest: tiling-none
[443.342801] Starting dynamic subtest: pipe-A-eDP-1
[447.052392] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.710s)
[447.061777] Starting dynamic subtest: pipe-B-eDP-1
[451.493006] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.437s)
[451.501153] Starting dynamic subtest: pipe-C-eDP-1
[455.807191] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.313s)
[455.815308] Starting dynamic subtest: pipe-D-eDP-1
[460.155630] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.346s)
[460.156554] Subtest tiling-none: SUCCESS (16.813s)
[460.991788] [116/133] (569s left) kms_draw_crc (draw-method-mmap-cpu)
[461.089768] Starting subtest: draw-method-mmap-cpu
[461.090729] Starting dynamic subtest: xrgb8888-untiled
[461.300355] Dynamic subtest xrgb8888-untiled: SUCCESS (0.211s)
[461.308888] Starting dynamic subtest: rgb565-untiled
[461.500355] Dynamic subtest rgb565-untiled: SUCCESS (0.199s)
[461.508685] Starting dynamic subtest: xrgb2101010-untiled
[461.689399] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.188s)
[461.698090] Starting dynamic subtest: xbgr16161616f-untiled
[461.889439] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.199s)
[461.897955] Starting dynamic subtest: xrgb8888-xtiled
[462.022579] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[462.034107] Starting dynamic subtest: rgb565-xtiled
[462.155988] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[462.164497] Starting dynamic subtest: xrgb2101010-xtiled
[462.278372] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[462.288163] Starting dynamic subtest: xbgr16161616f-xtiled
[462.411574] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[462.421232] Starting dynamic subtest: xrgb8888-4tiled
[462.544944] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[462.552747] Starting dynamic subtest: rgb565-4tiled
[462.667506] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[462.677837] Starting dynamic subtest: xrgb2101010-4tiled
[462.800615] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s)
[462.808910] Starting dynamic subtest: xbgr16161616f-4tiled
[462.933726] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[462.934573] Subtest draw-method-mmap-cpu: SUCCESS (1.845s)
[463.054101] [117/133] (567s left) kms_psr (psr-primary-page-flip)
[463.157016] Starting subtest: psr-primary-page-flip
[463.157898] Starting dynamic subtest: eDP-1
[464.867972] Dynamic subtest eDP-1: SUCCESS (1.711s)
[464.868882] Subtest psr-primary-page-flip: SUCCESS (1.712s)
[465.740169] [118/133] (565s left) kms_content_protection (dp-mst-type-0-hdcp14)
[465.879878] Starting subtest: dp-mst-type-0-hdcp14
[465.881940] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[466.031888] [119/133] (564s left) api_intel_allocator (alloc-simple)
[466.094632] Starting subtest: alloc-simple
[466.097204] Subtest alloc-simple: SUCCESS (0.004s)
[466.241999] [120/133] (564s left) gem_spin_batch (resubmit-new-all)
[466.300526] Starting subtest: resubmit-new-all
[466.301512] Starting dynamic subtest: rcs0
[466.315171] Dynamic subtest rcs0: SUCCESS (0.014s)
[466.325286] Starting dynamic subtest: bcs0
[466.326162] Dynamic subtest bcs0: SUCCESS (0.010s)
[466.334310] Starting dynamic subtest: vcs0
[466.335254] Dynamic subtest vcs0: SUCCESS (0.008s)
[466.343610] Starting dynamic subtest: vcs1
[466.344942] Dynamic subtest vcs1: SUCCESS (0.009s)
[466.355818] Starting dynamic subtest: vecs0
[466.356685] Dynamic subtest vecs0: SUCCESS (0.010s)
[466.364935] Starting dynamic subtest: ccs0
[466.365769] Dynamic subtest ccs0: SUCCESS (0.008s)
[466.366606] Subtest resubmit-new-all: SUCCESS (0.064s)
[467.807277] [121/133] (563s left) kms_joiner (invalid-modeset-ultra-joiner)
[467.917519] Starting subtest: invalid-modeset-ultra-joiner
[467.919831] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[467.995868] [122/133] (562s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[468.111305] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[468.113143] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[468.270332] [123/133] (562s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[468.657954] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[469.440594] [124/133] (561s left) gem_userptr_blits (unsync-overlap)
[469.508255] Subtest unsync-overlap: SKIP (0.000s)
[469.652960] [125/133] (561s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[469.742747] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[469.744447] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[469.854036] [126/133] (561s left) kms_sequence (get-busy)
[469.954813] Starting subtest: get-busy
[469.955663] Starting dynamic subtest: pipe-A-eDP-1
[471.882780] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.928s)
[471.892575] Starting dynamic subtest: pipe-B-eDP-1
[474.464438] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.580s)
[474.472522] Starting dynamic subtest: pipe-C-eDP-1
[477.079665] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.613s)
[477.087880] Starting dynamic subtest: pipe-D-eDP-1
[479.617810] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.537s)
[479.618734] Subtest get-busy: SUCCESS (9.663s)
[480.422007] [127/133] (550s left) gem_exec_balancer (hang)
[480.694260] Starting subtest: hang
[482.780892] Subtest hang: SUCCESS (2.087s)
[482.915084] [128/133] (548s left) kms_cdclk (plane-scaling)
[483.015545] Starting subtest: plane-scaling
[483.016452] Starting dynamic subtest: pipe-A-eDP-1
[483.300553] Dynamic subtest pipe-A-eDP-1: SKIP (0.284s)
[483.308685] Starting dynamic subtest: pipe-B-eDP-1
[483.310503] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[483.320716] Starting dynamic subtest: pipe-C-eDP-1
[483.322525] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[483.332724] Starting dynamic subtest: pipe-D-eDP-1
[483.334799] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[483.336732] Subtest plane-scaling: SKIP (0.292s)
[484.143811] [129/133] (547s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[484.243644] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[484.245336] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[484.367421] [130/133] (546s left) kms_flip (flip-vs-wf_vblank-interruptible)
[484.461485] Starting subtest: flip-vs-wf_vblank-interruptible
[484.463996] Starting dynamic subtest: A-eDP1
[486.384251] Dynamic subtest A-eDP1: SUCCESS (1.920s)
[486.394652] Starting dynamic subtest: B-eDP1
[488.312893] Dynamic subtest B-eDP1: SUCCESS (1.927s)
[488.324966] Starting dynamic subtest: C-eDP1
[490.231314] Dynamic subtest C-eDP1: SUCCESS (1.917s)
[490.246697] Starting dynamic subtest: D-eDP1
[492.074520] Dynamic subtest D-eDP1: SUCCESS (1.842s)
[492.076730] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.616s)
[493.309916] [131/133] (537s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[493.400737] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[493.402539] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[493.517707] [132/133] (537s left) gem_mmap_wc (set-cache-level)
[493.564001] Subtest set-cache-level: SKIP (0.000s)
[493.622917] [133/133] (537s left) gem_exec_params (cliprects_ptr-dirt)
[493.685087] Starting subtest: cliprects_ptr-dirt
[493.686083] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[493.808118] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: perf_pmu
results: parsing output: 2/ for test: sw_sync
results: parsing output: 3/ for test: gem_pread
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: kms_atomic_transition
results: parsing output: 6/ for test: gem_exec_gttfill
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_whisper
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: gem_exec_schedule
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: i915_pm_freq_api
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: kms_cursor_edge_walk
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: prime_busy
results: parsing output: 22/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_flip_scaled_crc
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: gen7_exec_parse
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_mmap_wc
results: parsing output: 32/ for test: gem_softpin
results: parsing output: 33/ for test: kms_setmode
results: parsing output: 34/ for test: i915_selftest
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: kms_plane_scaling
results: parsing output: 37/ for test: gem_mmap_gtt
results: parsing output: 38/ for test: kms_properties
results: parsing output: 39/ for test: kms_color
results: parsing output: 40/ for test: dmabuf_sync_file
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pipe_crc_basic
results: parsing output: 43/ for test: gem_readwrite
results: parsing output: 44/ for test: i915_pm_freq_mult
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_cursor_crc
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: kms_async_flips
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: kms_rotation_crc
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_mmap_offset
results: parsing output: 55/ for test: vgem_basic
results: parsing output: 56/ for test: syncobj_eventfd
results: parsing output: 57/ for test: kms_flip_scaled_crc
results: parsing output: 58/ for test: gem_exec_whisper
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: fbdev
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_exec_reloc
results: parsing output: 64/ for test: gem_userptr_blits
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: kms_getfb
results: parsing output: 67/ for test: gem_partial_pwrite_pread
results: parsing output: 68/ for test: prime_mmap
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_prime
results: parsing output: 72/ for test: gem_ctx_persistence
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: vgem_slow
results: parsing output: 75/ for test: gem_ctx_persistence
results: parsing output: 76/ for test: syncobj_basic
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_psr2_sf
results: parsing output: 79/ for test: kms_lease
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_atomic
results: parsing output: 83/ for test: gem_softpin
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: prime_vgem
results: parsing output: 87/ for test: kms_plane
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: perf_pmu
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_busy
results: parsing output: 92/ for test: gem_exec_fence
results: parsing output: 93/ for test: kms_rotation_crc
results: parsing output: 94/ for test: kms_chamelium_hpd
results: parsing output: 95/ for test: gem_create
results: parsing output: 96/ for test: vgem_basic
results: parsing output: 97/ for test: kms_pm_backlight
results: parsing output: 98/ for test: gem_exec_fence
results: parsing output: 99/ for test: core_getclient
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: gem_mmap_gtt
results: parsing output: 105/ for test: kms_vblank
results: parsing output: 106/ for test: gem_ctx_shared
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_exec_params
results: parsing output: 110/ for test: kms_dirtyfb
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: gem_ctx_bad_destroy
results: parsing output: 113/ for test: kms_plane_scaling
results: parsing output: 114/ for test: kms_plane_multiple
results: parsing output: 115/ for test: kms_draw_crc
results: parsing output: 116/ for test: kms_psr
results: parsing output: 117/ for test: kms_content_protection
results: parsing output: 118/ for test: api_intel_allocator
results: parsing output: 119/ for test: gem_spin_batch
results: parsing output: 120/ for test: kms_joiner
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: kms_chamelium_frames
results: parsing output: 123/ for test: gem_userptr_blits
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_sequence
results: parsing output: 126/ for test: gem_exec_balancer
results: parsing output: 127/ for test: kms_cdclk
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_mmap_wc
results: parsing output: 132/ for test: gem_exec_params
Done.