[57.708817] Initializing watchdogs
[57.708861]   /dev/watchdog0
[57.726883] [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.731182] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.733436] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.736825] [001/133] (960s left) i915_pm_rps (basic-api)
[57.816976] Starting subtest: basic-api
[57.821180] Subtest basic-api: SKIP (0.001s)
[57.927608] [002/133] (960s left) gem_softpin (safe-alignment)
[57.985737] Starting subtest: safe-alignment
[58.029638] Subtest safe-alignment: SUCCESS (0.044s)
[59.972693] [003/133] (958s left) gem_exec_whisper (basic-fds-forked)
[60.041799] Starting subtest: basic-fds-forked
[84.317547] Subtest basic-fds-forked: SUCCESS (24.288s)
[84.505856] [004/133] (933s left) syncobj_wait (single-wait-signaled)
[84.565425] Starting subtest: single-wait-signaled
[84.567786] Subtest single-wait-signaled: SUCCESS (0.000s)
[84.680149] [005/133] (933s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[84.783145] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[84.785394] Starting dynamic subtest: pipe-A
[85.968916] Dynamic subtest pipe-A: SUCCESS (1.186s)
[85.977142] Starting dynamic subtest: pipe-B
[88.278963] Dynamic subtest pipe-B: SUCCESS (2.309s)
[88.289852] Starting dynamic subtest: pipe-C
[89.524296] Dynamic subtest pipe-C: SUCCESS (1.244s)
[89.533258] Starting dynamic subtest: pipe-D
[90.764016] Dynamic subtest pipe-D: SUCCESS (1.239s)
[90.766410] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.981s)
[91.966025] [006/133] (926s left) gen9_exec_parse (cmd-crossing-page)
[92.036883] Subtest cmd-crossing-page: SKIP (0.000s)
[92.093850] [007/133] (926s left) gem_flink_race (flink_name)
[92.126853] Starting subtest: flink_name
[97.567862] Subtest flink_name: SUCCESS (5.386s)
[97.648176] [008/133] (920s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[97.755063] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[104.562965] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.810s)
[104.742020] [009/133] (913s left) api_intel_bb (render)
[104.813281] Starting subtest: render
[104.816093] Starting dynamic subtest: render-none-512
[104.917809] Dynamic subtest render-none-512: SUCCESS (0.105s)
[104.927710] Starting dynamic subtest: render-none-1024
[105.288972] Dynamic subtest render-none-1024: SUCCESS (0.369s)
[105.299782] Starting dynamic subtest: render-x-512
[105.372762] Dynamic subtest render-x-512: SUCCESS (0.084s)
[105.383330] Starting dynamic subtest: render-x-1024
[105.706351] Dynamic subtest render-x-1024: SUCCESS (0.332s)
[105.716745] Starting dynamic subtest: render-y-512
[105.790911] Dynamic subtest render-y-512: SUCCESS (0.085s)
[105.801270] Starting dynamic subtest: render-y-1024
[106.127768] Dynamic subtest render-y-1024: SUCCESS (0.335s)
[106.130917] Subtest render: SUCCESS (1.314s)
[106.272618] [010/133] (912s left) gem_mmap_gtt (basic-small-copy-xy)
[106.330726] Subtest basic-small-copy-XY: SKIP (0.000s)
[106.421242] [011/133] (912s left) gem_exec_fence (basic-busy-all)
[106.489916] Starting subtest: basic-busy-all
[106.597034] Subtest basic-busy-all: SUCCESS (0.110s)
[108.999838] [FACT gem_exec_fence (basic-busy-all)] new: kernel.kmod_is_loaded.vgem: true
[109.002968] [012/133] (909s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[109.108641] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[109.112815] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[109.237192] [013/133] (909s left) kms_atomic_transition (plane-toggle-modeset-transition)
[109.333962] Starting subtest: plane-toggle-modeset-transition
[109.335979] Starting dynamic subtest: pipe-A-eDP-1
[122.027348] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.694s)
[122.037281] Starting dynamic subtest: pipe-B-eDP-1
[134.453727] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.424s)
[134.456480] Subtest plane-toggle-modeset-transition: SUCCESS (25.123s)
[135.268719] [014/133] (883s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[135.385061] Starting subtest: fbcpsr-rgb565-draw-pwrite
[142.286780] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (6.906s)
[142.446049] [015/133] (876s left) kms_flip (2x-modeset-vs-vblank-race)
[142.547410] Starting subtest: 2x-modeset-vs-vblank-race
[142.558220] Subtest 2x-modeset-vs-vblank-race: SKIP (0.010s)
[142.645031] [016/133] (876s left) gem_mmap_gtt (basic-small-bo-tiledy)
[142.707255] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[142.760226] [017/133] (876s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[142.850802] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[142.854976] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[142.967363] [018/133] (875s left) perf (polling-small-buf)
[143.086464] Starting subtest: polling-small-buf
[143.178443] Subtest polling-small-buf: SUCCESS (0.091s)
[143.229641] [019/133] (875s left) gem_ctx_param (non-root-set)
[143.300515] Starting subtest: non-root-set
[143.321570] Subtest non-root-set: SUCCESS (0.021s)
[143.427626] [020/133] (875s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[143.532267] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[144.178718] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.648s)
[144.382253] [021/133] (874s left) kms_vblank (wait-forked-hang)
[144.466085] Starting subtest: wait-forked-hang
[144.468307] Starting dynamic subtest: pipe-A-eDP-1
[170.653323] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.185s)
[170.667881] Starting dynamic subtest: pipe-D-eDP-1
[185.678863] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.024s)
[185.681106] Subtest wait-forked-hang: SUCCESS (41.212s)
[186.567354] [022/133] (832s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[186.633142] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[186.698459] [023/133] (832s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[186.790281] Starting subtest: invalid-modeset-force-ultra-joiner
[186.794393] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[186.868575] [024/133] (832s left) kms_pm_rpm (basic-rte)
[186.898592] Starting subtest: basic-rte
[190.542962] Subtest basic-rte: SUCCESS (3.646s)
[192.080190] [025/133] (827s left) gem_exec_fence (syncobj-wait)
[192.502888] Starting subtest: syncobj-wait
[193.919787] Subtest syncobj-wait: SUCCESS (1.415s)
[194.289952] [026/133] (824s left) kms_getfb (getfb-repeated-different-handles)
[194.384092] Starting subtest: getfb-repeated-different-handles
[194.386227] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[194.494146] [027/133] (824s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[194.580319] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[194.582597] Starting dynamic subtest: pipe-A-default-mode
[197.289392] Dynamic subtest pipe-A-default-mode: SUCCESS (2.709s)
[197.291707] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.710s)
[198.176104] [028/133] (821s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[198.276671] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[198.278925] Starting dynamic subtest: pipe-A-eDP-1
[198.303128] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[198.311537] Starting dynamic subtest: pipe-B-eDP-1
[198.333916] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[198.341538] Starting dynamic subtest: pipe-C-eDP-1
[198.364028] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[198.371817] Starting dynamic subtest: pipe-D-eDP-1
[198.395053] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[198.398902] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[200.529764] [029/133] (818s left) kms_cursor_crc (cursor-sliding-512x512)
[200.808718] Starting subtest: cursor-sliding-512x512
[200.813060] Subtest cursor-sliding-512x512: SKIP (0.001s)
[201.000621] [030/133] (818s left) api_intel_allocator (reserve-simple)
[201.062838] Starting subtest: reserve-simple
[201.065300] Subtest reserve-simple: SUCCESS (0.003s)
[201.222327] [031/133] (818s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[201.327466] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[201.331749] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[201.426423] [032/133] (818s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[201.529622] Starting subtest: 4-tiled-8bpp-rotate-270
[201.549436] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.019s)
[201.688845] [033/133] (817s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[201.789038] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[205.937253] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.152s)
[207.151880] [034/133] (812s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[207.545025] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[208.333042] [035/133] (811s left) gem_pwrite (basic-self)
[208.395197] Subtest basic-self: SKIP (0.000s)
[208.464741] [036/133] (811s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[208.599074] Starting subtest: basic-flip-before-cursor-atomic
[209.532301] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.931s)
[210.383246] [037/133] (809s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[210.476921] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[210.481263] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[211.614820] [038/133] (808s left) kms_pm_backlight (bad-brightness)
[211.711818] Starting subtest: bad-brightness
[211.763233] Starting dynamic subtest: eDP-1
[212.046626] Dynamic subtest eDP-1: SUCCESS (0.283s)
[212.049124] Subtest bad-brightness: SUCCESS (0.336s)
[212.957342] [039/133] (806s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[213.079842] Starting subtest: 2x-long-cursor-vs-flip-legacy
[213.084482] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[213.246662] [040/133] (806s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[213.350093] Starting subtest: y-tiled-32bpp-rotate-0
[213.354472] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[213.467169] [041/133] (806s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[213.581691] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[220.981832] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.404s)
[221.119196] [042/133] (798s left) kms_psr (fbc-psr-primary-mmap-cpu)
[221.223217] Starting subtest: fbc-psr-primary-mmap-cpu
[221.225507] Starting dynamic subtest: eDP-1
[221.229863] Dynamic subtest eDP-1: SKIP (0.000s)
[221.234265] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s)
[221.309133] [043/133] (798s left) kms_sequence (get-forked-busy)
[221.398215] Starting subtest: get-forked-busy
[221.400510] Starting dynamic subtest: pipe-A-eDP-1
[223.320986] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.920s)
[223.334747] Starting dynamic subtest: pipe-B-eDP-1
[225.901778] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.579s)
[225.910861] Starting dynamic subtest: pipe-C-eDP-1
[228.519691] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.619s)
[228.529570] Starting dynamic subtest: pipe-D-eDP-1
[231.160993] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.638s)
[231.163231] Subtest get-forked-busy: SUCCESS (9.761s)
[231.995998] [044/133] (787s left) kms_concurrent (multi-plane-atomic-lowres)
[232.098028] Starting subtest: multi-plane-atomic-lowres
[232.100351] Starting dynamic subtest: pipe-A-eDP-1
[232.576662] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.478s)
[232.579105] Subtest multi-plane-atomic-lowres: SUCCESS (0.479s)
[233.398045] [045/133] (786s left) gem_exec_schedule (reorder-wide)
[233.584777] Subtest reorder-wide: SKIP (0.000s)
[233.775875] [046/133] (786s left) prime_vgem (basic-write)
[233.844299] Starting subtest: basic-write
[233.854828] Subtest basic-write: SKIP (0.009s)
[233.963929] [047/133] (786s left) kms_hdmi_inject (inject-4k)
[234.335578] Starting subtest: inject-4k
[234.653708] Subtest inject-4k: SUCCESS (0.319s)
[235.189742] [048/133] (784s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[235.289867] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[235.292198] Starting dynamic subtest: pipe-A-eDP-1
[235.428566] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.130s)
[235.439063] Starting dynamic subtest: pipe-B-eDP-1
[235.457210] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[235.465270] Starting dynamic subtest: pipe-C-eDP-1
[235.483184] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[235.490474] Starting dynamic subtest: pipe-D-eDP-1
[235.508757] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[235.510616] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.139s)
[237.638548] [049/133] (782s left) syncobj_timeline (invalid-query-zero-handles)
[237.704826] Starting subtest: invalid-query-zero-handles
[237.706945] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[237.834931] [050/133] (782s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[237.890788] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[238.001176] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.108s)
[238.097355] [051/133] (782s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[238.187221] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[238.189475] Starting dynamic subtest: pipe-A-eDP-1
[238.247090] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[238.256668] Starting dynamic subtest: pipe-B-eDP-1
[238.313232] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[238.323064] Starting dynamic subtest: pipe-C-eDP-1
[238.379510] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[238.388746] Starting dynamic subtest: pipe-D-eDP-1
[238.446101] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[238.450647] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.008s)
[240.421379] [052/133] (779s left) kms_pipe_crc_basic (nonblocking-crc)
[240.514235] Starting subtest: nonblocking-crc
[240.516507] Starting dynamic subtest: pipe-A-eDP-1
[241.240234] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.725s)
[241.249216] Starting dynamic subtest: pipe-B-eDP-1
[242.694578] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.452s)
[242.703817] Starting dynamic subtest: pipe-C-eDP-1
[244.057326] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.361s)
[244.066252] Starting dynamic subtest: pipe-D-eDP-1
[245.461798] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.405s)
[245.464195] Subtest nonblocking-crc: SUCCESS (4.950s)
[246.334595] [053/133] (773s left) gem_ctx_shared (q-smoketest)
[246.405818] Starting subtest: Q-smoketest
[246.408550] Starting dynamic subtest: rcs0
[251.999892] Dynamic subtest rcs0: SUCCESS (5.491s)
[252.012145] Starting dynamic subtest: bcs0
[257.431560] Dynamic subtest bcs0: SUCCESS (5.485s)
[257.442973] Starting dynamic subtest: vcs0
[262.979247] Dynamic subtest vcs0: SUCCESS (5.489s)
[262.992155] Starting dynamic subtest: vcs1
[268.444752] Dynamic subtest vcs1: SUCCESS (5.467s)
[268.458222] Starting dynamic subtest: vecs0
[273.930644] Dynamic subtest vecs0: SUCCESS (5.487s)
[273.939499] Starting dynamic subtest: ccs0
[279.428821] Dynamic subtest ccs0: SUCCESS (5.495s)
[279.431301] Subtest Q-smoketest: SUCCESS (32.924s)
[279.528811] [054/133] (740s left) kms_async_flips (async-flip-dpms)
[279.640161] Starting subtest: async-flip-dpms
[280.011050] Starting dynamic subtest: pipe-A-eDP-1
[283.867822] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.856s)
[284.250555] Starting dynamic subtest: pipe-B-eDP-1
[287.068291] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.823s)
[287.434520] Starting dynamic subtest: pipe-C-eDP-1
[290.256386] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.827s)
[290.633820] Starting dynamic subtest: pipe-D-eDP-1
[293.468281] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.843s)
[293.470603] Subtest async-flip-dpms: SUCCESS (13.831s)
[294.631833] [055/133] (725s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[294.736103] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[294.738356] Starting dynamic subtest: pipe-A-eDP-1
[294.747497] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[294.758458] Starting dynamic subtest: pipe-B-eDP-1
[294.767351] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[294.776128] Starting dynamic subtest: pipe-C-eDP-1
[294.784173] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[294.791485] Starting dynamic subtest: pipe-D-eDP-1
[294.799790] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[294.803817] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.004s)
[297.021402] [056/133] (723s left) kms_psr (psr-cursor-plane-move)
[297.108123] Starting subtest: psr-cursor-plane-move
[297.110373] Starting dynamic subtest: eDP-1
[298.799294] Dynamic subtest eDP-1: SUCCESS (1.689s)
[298.801571] Subtest psr-cursor-plane-move: SUCCESS (1.690s)
[299.621349] [057/133] (720s left) syncobj_wait (invalid-signal-illegal-handle)
[299.687938] Starting subtest: invalid-signal-illegal-handle
[299.689882] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[299.808466] [058/133] (720s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[299.913904] Starting subtest: psr-rgb101010-draw-mmap-cpu
[306.828909] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (6.918s)
[306.976669] [059/133] (713s left) prime_mmap (test_aperture_limit)
[307.041129] Starting subtest: test_aperture_limit
[307.043340] Starting dynamic subtest: test_aperture_limit-smem
[307.050338] Dynamic subtest test_aperture_limit-smem: SKIP (0.007s)
[307.054461] Subtest test_aperture_limit: SKIP (0.008s)
[307.169186] [060/133] (713s left) gem_mmap_gtt (basic-write-read)
[307.225051] Subtest basic-write-read: SKIP (0.000s)
[307.289216] [061/133] (713s left) gem_huc_copy (huc-copy)
[307.354332] Starting subtest: huc-copy
[307.359236] Subtest huc-copy: SUCCESS (0.003s)
[307.509995] [062/133] (713s left) gem_exec_reloc (basic-gtt-wc-active)
[307.566595] Subtest basic-gtt-wc-active: SKIP (0.000s)
[307.657080] [063/133] (713s left) kms_color (ctm-negative)
[307.762569] Starting subtest: ctm-negative
[307.764354] Starting dynamic subtest: pipe-A-eDP-1
[308.469097] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.706s)
[308.477506] Starting dynamic subtest: pipe-B-eDP-1
[309.901480] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.431s)
[309.912598] Starting dynamic subtest: pipe-C-eDP-1
[311.243022] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.342s)
[311.252653] Starting dynamic subtest: pipe-D-eDP-1
[312.649107] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.405s)
[312.651388] Subtest ctm-negative: SUCCESS (4.888s)
[313.522371] [064/133] (707s left) syncobj_basic (bad-create-flags)
[313.585928] Starting subtest: bad-create-flags
[313.588080] Subtest bad-create-flags: SUCCESS (0.000s)
[313.713317] [065/133] (707s left) gem_exec_schedule (preemptive-hang)
[313.896618] Starting subtest: preemptive-hang
[313.898519] Starting dynamic subtest: rcs0
[323.005581] Dynamic subtest rcs0: SUCCESS (9.110s)
[323.016308] Starting dynamic subtest: bcs0
[332.034100] Dynamic subtest bcs0: SUCCESS (9.021s)
[332.046048] Starting dynamic subtest: vcs0
[341.058020] Dynamic subtest vcs0: SUCCESS (9.023s)
[341.067813] Starting dynamic subtest: vcs1
[350.017079] Dynamic subtest vcs1: SUCCESS (8.957s)
[350.029717] Starting dynamic subtest: vecs0
[359.042544] Dynamic subtest vecs0: SUCCESS (9.019s)
[359.053460] Starting dynamic subtest: ccs0
[368.002468] Dynamic subtest ccs0: SUCCESS (8.964s)
[368.005035] Subtest preemptive-hang: SUCCESS (54.106s)
[368.247378] [066/133] (652s left) gem_mmap_offset (open-flood)
[368.318439] Starting subtest: open-flood
[389.812149] Subtest open-flood: SUCCESS (21.496s)
[389.950196] [067/133] (630s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[390.062143] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[398.473565] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.415s)
[398.611453] [068/133] (622s left) syncobj_wait (wait-for-submit-snapshot)
[398.680560] Starting subtest: wait-for-submit-snapshot
[398.761046] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[398.846516] [069/133] (622s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[399.235516] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[400.076852] [070/133] (620s left) kms_flip (dpms-off-confusion-interruptible)
[400.172226] Starting subtest: dpms-off-confusion-interruptible
[400.174535] Starting dynamic subtest: A-eDP1
[408.917659] Dynamic subtest A-eDP1: SUCCESS (8.710s)
[408.926133] Starting dynamic subtest: B-eDP1
[417.596126] Dynamic subtest B-eDP1: SUCCESS (8.678s)
[417.606497] Starting dynamic subtest: C-eDP1
[426.201081] Dynamic subtest C-eDP1: SUCCESS (8.591s)
[426.210948] Starting dynamic subtest: D-eDP1
[434.804267] Dynamic subtest D-eDP1: SUCCESS (8.600s)
[434.806472] Subtest dpms-off-confusion-interruptible: SUCCESS (34.589s)
[435.932115] [071/133] (585s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[436.033382] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[436.059175] Starting dynamic subtest: pipe-A-plane-0
[436.347587] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[436.356636] Starting dynamic subtest: pipe-A-plane-3
[436.580854] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[437.651405] Starting dynamic subtest: pipe-B-plane-0
[438.000060] Dynamic subtest pipe-B-plane-0: SUCCESS (0.355s)
[438.009679] Starting dynamic subtest: pipe-B-plane-3
[438.233320] Dynamic subtest pipe-B-plane-3: SUCCESS (0.231s)
[438.544869] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.513s)
[439.407989] [072/133] (581s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[439.515455] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[439.519555] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[439.649412] [073/133] (581s left) perf (non-system-wide-paranoid)
[439.757508] Starting subtest: non-system-wide-paranoid
[439.784028] Subtest non-system-wide-paranoid: SUCCESS (0.027s)
[439.846631] [074/133] (581s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[439.946697] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[439.951066] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[440.025822] [075/133] (581s left) kms_pm_rpm (pm-caching)
[442.063426] Starting subtest: pm-caching
[442.069158] Subtest pm-caching: SKIP (0.000s)
[442.456803] [076/133] (578s left) perf (short-reads)
[442.539962] Starting subtest: short-reads
[442.551574] Subtest short-reads: SUCCESS (0.011s)
[442.599659] [077/133] (578s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[442.701288] Starting subtest: plane-all-transition-nonblocking-fencing
[442.703551] Starting dynamic subtest: pipe-A-eDP-1
[445.194263] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[445.503353] Starting dynamic subtest: pipe-B-eDP-1
[448.700722] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.206s)
[449.008092] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.309s)
[449.906157] [078/133] (571s left) gem_busy (busy)
[449.980071] Starting subtest: busy
[449.982553] Starting dynamic subtest: all-engines
[450.012998] Dynamic subtest all-engines: SUCCESS (0.032s)
[450.026845] Starting dynamic subtest: rcs0
[451.868781] Dynamic subtest rcs0: SUCCESS (1.853s)
[451.878828] Starting dynamic subtest: bcs0
[451.932199] Dynamic subtest bcs0: SUCCESS (0.060s)
[451.941777] Starting dynamic subtest: vcs0
[451.971219] Dynamic subtest vcs0: SUCCESS (0.040s)
[451.979918] Starting dynamic subtest: vcs1
[453.913405] Dynamic subtest vcs1: SUCCESS (1.939s)
[453.921741] Starting dynamic subtest: vecs0
[455.896649] Dynamic subtest vecs0: SUCCESS (1.980s)
[455.906424] Starting dynamic subtest: ccs0
[457.881585] Dynamic subtest ccs0: SUCCESS (1.984s)
[457.883609] Subtest busy: SUCCESS (7.900s)
[458.269008] [079/133] (563s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[458.374703] Starting subtest: extended-pageflip-modeset-hang-oldfb
[458.376580] Starting dynamic subtest: pipe-A
[466.295255] Dynamic subtest pipe-A: SUCCESS (7.921s)
[466.307090] Starting dynamic subtest: pipe-D
[474.877735] Dynamic subtest pipe-D: SUCCESS (8.574s)
[474.879938] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.502s)
[475.714289] [080/133] (545s left) kms_fb_coherency (memset-crc)
[475.812080] Starting subtest: memset-crc
[475.814413] Starting dynamic subtest: mmap-offset-wc
[476.176727] Dynamic subtest mmap-offset-wc: SUCCESS (0.364s)
[476.479039] Starting dynamic subtest: mmap-offset-uc
[482.582503] Dynamic subtest mmap-offset-uc: SUCCESS (6.109s)
[482.878017] Subtest memset-crc: SUCCESS (7.066s)
[483.698104] [081/133] (537s left) gem_vm_create (invalid-create)
[483.767526] Starting subtest: invalid-create
[483.770063] Subtest invalid-create: SUCCESS (0.002s)
[483.897991] [082/133] (537s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[483.999995] Starting subtest: yf-tiled-8bpp-rotate-270
[484.004288] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[484.139054] [083/133] (537s left) gem_ctx_persistence (saturated-hostile)
[484.209432] Starting subtest: saturated-hostile
[484.211354] Starting dynamic subtest: rcs0
[485.068058] Dynamic subtest rcs0: SUCCESS (0.861s)
[485.080979] Starting dynamic subtest: bcs0
[485.882802] Dynamic subtest bcs0: SUCCESS (0.812s)
[485.893278] Starting dynamic subtest: vcs0
[486.693711] Dynamic subtest vcs0: SUCCESS (0.809s)
[486.704958] Starting dynamic subtest: vcs1
[487.504617] Dynamic subtest vcs1: SUCCESS (0.809s)
[487.515835] Starting dynamic subtest: vecs0
[488.313421] Dynamic subtest vecs0: SUCCESS (0.807s)
[488.324446] Starting dynamic subtest: ccs0
[489.167153] Dynamic subtest ccs0: SUCCESS (0.851s)
[489.169824] Subtest saturated-hostile: SUCCESS (4.962s)
[489.259748] [084/133] (532s left) gem_exec_fence (syncobj-timeline-export)
[489.850051] Starting subtest: syncobj-timeline-export
[489.860997] Subtest syncobj-timeline-export: SUCCESS (0.013s)
[491.037947] [085/133] (530s left) syncobj_timeline (wait-zero-handles)
[491.109485] Starting subtest: wait-zero-handles
[491.111777] Subtest wait-zero-handles: SUCCESS (0.000s)
[491.219927] [086/133] (530s left) kms_big_fb (linear-32bpp-rotate-0)
[491.323711] Starting subtest: linear-32bpp-rotate-0
[492.769259] Subtest linear-32bpp-rotate-0: SUCCESS (1.445s)
[492.958205] [087/133] (528s left) kms_psr (psr-sprite-blt)
[493.063826] Starting subtest: psr-sprite-blt
[493.066052] Starting dynamic subtest: eDP-1
[494.776370] Dynamic subtest eDP-1: SUCCESS (1.710s)
[494.778292] Subtest psr-sprite-blt: SUCCESS (1.712s)
[495.604919] [088/133] (526s left) gem_eio (wait-10ms)
[495.825725] Starting subtest: wait-10ms
[496.155072] Subtest wait-10ms: SUCCESS (0.329s)
[496.317383] [089/133] (525s left) gem_pxp (verify-pxp-stale-buf-execution)
[496.429964] Starting subtest: verify-pxp-stale-buf-execution
[497.490776] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.062s)
[497.650398] [090/133] (524s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[497.758875] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[497.761278] Starting dynamic subtest: pipe-A-eDP-1
[497.855418] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[497.865223] Starting dynamic subtest: pipe-B-eDP-1
[499.028081] Dynamic subtest pipe-B-eDP-1: SKIP (1.169s)
[499.032695] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.271s)
[500.295643] [091/133] (521s left) gem_exec_balancer (nop)
[500.362648] Starting subtest: nop
[546.966711] Subtest nop: SUCCESS (46.604s)
[547.302404] [092/133] (474s left) kms_psr (fbc-pr-primary-mmap-cpu)
[547.415092] Starting subtest: fbc-pr-primary-mmap-cpu
[547.419254] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[547.500834] [093/133] (474s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[547.613358] Starting subtest: 4-tiled-16bpp-rotate-180
[548.859781] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.246s)
[549.036087] [094/133] (472s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[549.093096] Subtest no-spare-fences-busy: SKIP (0.000s)
[549.170595] [095/133] (472s left) gem_mmap_gtt (basic-read-write)
[549.225938] Subtest basic-read-write: SKIP (0.000s)
[549.292165] [096/133] (472s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[549.379484] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[549.383101] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[549.507521] [097/133] (472s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[549.631150] Starting subtest: short-flip-before-cursor-atomic-transitions
[550.580564] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.947s)
[551.406726] [098/133] (470s left) kms_cursor_legacy (forked-bo)
[551.532247] Starting subtest: forked-bo
[551.534514] Starting dynamic subtest: pipe-A
[557.035202] Dynamic subtest pipe-A: SUCCESS (5.450s)
[557.044765] Starting dynamic subtest: pipe-B
[562.497240] Dynamic subtest pipe-B: SUCCESS (5.460s)
[562.507737] Starting dynamic subtest: pipe-C
[567.966794] Dynamic subtest pipe-C: SUCCESS (5.467s)
[567.978219] Starting dynamic subtest: pipe-D
[573.444495] Dynamic subtest pipe-D: SUCCESS (5.475s)
[573.453882] Starting dynamic subtest: all-pipes
[578.964453] Dynamic subtest all-pipes: SUCCESS (5.518s)
[578.966672] Subtest forked-bo: SUCCESS (27.382s)
[579.084111] [099/133] (443s left) prime_mmap (test_map_unmap)
[579.154766] Starting subtest: test_map_unmap
[579.156771] Starting dynamic subtest: test_map_unmap-smem
[579.184296] Dynamic subtest test_map_unmap-smem: SUCCESS (0.031s)
[579.186710] Subtest test_map_unmap: SUCCESS (0.032s)
[579.315845] [100/133] (442s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[579.375605] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[579.380092] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[579.569341] [101/133] (442s left) dmabuf (all-tests)
[579.604621] Starting subtest: all-tests
[579.606793] Starting dynamic subtest: sanitycheck
[579.772562] Dynamic subtest sanitycheck: SUCCESS (0.167s)
[579.782741] Starting dynamic subtest: dma_fence
[580.054416] Dynamic subtest dma_fence: SUCCESS (0.279s)
[580.066323] Starting dynamic subtest: dma_fence_chain
[588.317514] Dynamic subtest dma_fence_chain: SUCCESS (8.261s)
[588.329365] Starting dynamic subtest: dma_fence_unwrap
[588.447586] Dynamic subtest dma_fence_unwrap: SUCCESS (0.127s)
[588.459222] Starting dynamic subtest: dma_resv
[588.574731] Dynamic subtest dma_resv: SUCCESS (0.125s)
[588.577652] Subtest all-tests: SUCCESS (8.970s)
[588.656404] [102/133] (433s left) kms_flip (plain-flip-fb-recreate-interruptible)
[588.754240] Starting subtest: plain-flip-fb-recreate-interruptible
[588.756537] Starting dynamic subtest: A-eDP1
[597.636499] Dynamic subtest A-eDP1: SUCCESS (8.881s)
[597.647295] Starting dynamic subtest: B-eDP1
[606.453398] Dynamic subtest B-eDP1: SUCCESS (8.815s)
[606.465201] Starting dynamic subtest: C-eDP1
[615.324900] Dynamic subtest C-eDP1: SUCCESS (8.869s)
[615.333831] Starting dynamic subtest: D-eDP1
[624.118411] Dynamic subtest D-eDP1: SUCCESS (8.792s)
[624.122427] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.368s)
[625.376980] [103/133] (396s left) kms_psr (fbc-psr2-no-drrs)
[625.467435] Starting subtest: fbc-psr2-no-drrs
[625.469424] Starting dynamic subtest: eDP-1
[625.473621] Dynamic subtest eDP-1: SKIP (0.000s)
[625.477818] Subtest fbc-psr2-no-drrs: SKIP (0.001s)
[625.553674] [104/133] (396s left) perf_pmu (busy-check-all)
[625.597938] Starting subtest: busy-check-all
[625.600352] Starting dynamic subtest: rcs0
[626.155928] Dynamic subtest rcs0: SUCCESS (0.559s)
[626.167389] Starting dynamic subtest: bcs0
[626.707418] Dynamic subtest bcs0: SUCCESS (0.549s)
[626.718828] Starting dynamic subtest: vcs0
[627.925808] Dynamic subtest vcs0: SUCCESS (1.214s)
[627.937777] Starting dynamic subtest: vcs1
[629.910805] Dynamic subtest vcs1: SUCCESS (1.981s)
[629.922875] Starting dynamic subtest: vecs0
[631.893315] Dynamic subtest vecs0: SUCCESS (1.981s)
[631.906198] Starting dynamic subtest: ccs0
[632.448886] Dynamic subtest ccs0: SUCCESS (0.554s)
[632.451502] Subtest busy-check-all: SUCCESS (6.853s)
[632.599793] [105/133] (389s left) kms_psr (fbc-psr-suspend)
[632.698696] Starting subtest: fbc-psr-suspend
[632.700951] Starting dynamic subtest: eDP-1
[632.705078] Dynamic subtest eDP-1: SKIP (0.000s)
[632.709135] Subtest fbc-psr-suspend: SKIP (0.001s)
[632.805799] [106/133] (389s left) gem_mmap_gtt (basic)
[632.866825] Subtest basic: SKIP (0.000s)
[632.934487] [107/133] (389s left) perf_pmu (init-busy)
[632.975067] Starting subtest: init-busy
[632.977260] Starting dynamic subtest: rcs0
[632.979586] Dynamic subtest rcs0: SUCCESS (0.001s)
[632.992759] Starting dynamic subtest: bcs0
[632.994907] Dynamic subtest bcs0: SUCCESS (0.000s)
[633.005647] Starting dynamic subtest: vcs0
[633.007802] Dynamic subtest vcs0: SUCCESS (0.001s)
[633.015802] Starting dynamic subtest: vcs1
[633.017999] Dynamic subtest vcs1: SUCCESS (0.001s)
[633.025762] Starting dynamic subtest: vecs0
[633.027797] Dynamic subtest vecs0: SUCCESS (0.000s)
[633.038826] Starting dynamic subtest: ccs0
[633.041404] Dynamic subtest ccs0: SUCCESS (0.000s)
[633.043792] Subtest init-busy: SUCCESS (0.007s)
[633.085678] [108/133] (389s left) i915_suspend (debugfs-reader)
[633.142101] Starting subtest: debugfs-reader
[651.054792] Subtest debugfs-reader: SUCCESS (3.259s)
[651.215022] [109/133] (371s left) gem_ctx_freq (sysfs)
[651.287330] Starting subtest: sysfs
[651.290355] Starting dynamic subtest: gt0
[656.831501] Dynamic subtest gt0: SUCCESS (5.587s)
[656.843428] Starting dynamic subtest: gt1
[698.585723] Dynamic subtest gt1: SUCCESS (41.799s)
[698.588570] Subtest sysfs: SUCCESS (47.389s)
[698.727565] [110/133] (324s left) kms_plane_multiple (tiling-y)
[698.836369] Starting subtest: tiling-y
[698.841204] Subtest tiling-y: SKIP (0.000s)
[698.923268] [111/133] (323s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[699.029905] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[699.034523] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[699.151673] [112/133] (323s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[699.204108] Starting subtest: etime-single-wait-all-available-unsubmitted
[699.311971] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.109s)
[699.403722] [113/133] (323s left) i915_drm_fdinfo (isolation)
[699.449367] Starting subtest: isolation
[699.452093] Starting dynamic subtest: rcs0
[699.479234] Dynamic subtest rcs0: SKIP (0.029s)
[699.490549] Starting dynamic subtest: bcs0
[699.495358] Dynamic subtest bcs0: SKIP (0.000s)
[699.504464] Starting dynamic subtest: vcs0
[699.510124] Dynamic subtest vcs0: SKIP (0.000s)
[699.520232] Starting dynamic subtest: vcs1
[699.525346] Dynamic subtest vcs1: SKIP (0.000s)
[699.534388] Starting dynamic subtest: vecs0
[699.539407] Dynamic subtest vecs0: SKIP (0.000s)
[699.548542] Starting dynamic subtest: ccs0
[699.553706] Dynamic subtest ccs0: SKIP (0.000s)
[699.558548] Subtest isolation: SKIP (0.035s)
[699.596240] [114/133] (323s left) gem_exec_reloc (basic-softpin)
[699.664695] Subtest basic-softpin: SKIP (0.000s)
[699.753115] [115/133] (323s left) kms_frontbuffer_tracking (fbc-2p-rte)
[699.850365] Starting subtest: fbc-2p-rte
[699.855000] Subtest fbc-2p-rte: SKIP (0.000s)
[699.981769] [116/133] (323s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[700.070366] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[700.075737] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.000s)
[700.161051] [117/133] (322s left) gem_render_copy (y-tiled)
[700.223326] Starting subtest: y-tiled
[700.229289] Subtest y-tiled: SKIP (0.000s)
[700.418269] [118/133] (322s left) syncobj_timeline (wait-any-snapshot)
[700.477412] Starting subtest: wait-any-snapshot
[700.558574] Subtest wait-any-snapshot: SUCCESS (0.082s)
[700.651006] [119/133] (322s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[700.712773] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[700.715214] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[700.834950] [120/133] (322s left) gem_busy (close-race)
[700.898940] Starting subtest: close-race
[726.556328] Subtest close-race: SUCCESS (25.662s)
[726.960434] [121/133] (296s left) kms_flip (2x-dpms-vs-vblank-race)
[727.121348] Starting subtest: 2x-dpms-vs-vblank-race
[727.129547] Subtest 2x-dpms-vs-vblank-race: SKIP (0.010s)
[727.264799] [122/133] (296s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[727.369146] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[728.449412] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.080s)
[728.625938] [123/133] (294s left) perf_pmu (frequency)
[728.668345] Starting subtest: frequency
[728.675010] Starting dynamic subtest: gt0
[730.870415] Dynamic subtest gt0: SUCCESS (2.193s)
[730.882200] Starting dynamic subtest: idle-gt0
[731.376630] Dynamic subtest idle-gt0: SUCCESS (0.505s)
[731.386569] Starting dynamic subtest: gt1
[735.559730] Dynamic subtest gt1: SUCCESS (4.179s)
[735.569647] Starting dynamic subtest: idle-gt1
[736.092142] Dynamic subtest idle-gt1: SUCCESS (0.533s)
[736.094795] Subtest frequency: SUCCESS (7.427s)
[736.208831] [124/133] (287s left) kms_lease (lease-unleased-connector)
[736.311550] Starting subtest: lease-unleased-connector
[736.314333] Starting dynamic subtest: pipe-A-eDP-1
[736.320436] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.008s)
[736.331276] Starting dynamic subtest: pipe-B-eDP-1
[736.334525] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[736.345858] Starting dynamic subtest: pipe-C-eDP-1
[736.348396] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[736.357437] Starting dynamic subtest: pipe-D-eDP-1
[736.359953] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[736.362197] Subtest lease-unleased-connector: SUCCESS (0.035s)
[736.462949] [125/133] (287s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[736.564393] Starting subtest: psr-rgb565-draw-render
[743.580491] Subtest psr-rgb565-draw-render: SUCCESS (7.020s)
[743.706992] [126/133] (279s left) i915_hangman (engine-engine-error)
[743.785066] Starting subtest: engine-engine-error
[743.787906] Starting dynamic subtest: rcs0
[745.529884] Dynamic subtest rcs0: SUCCESS (1.743s)
[745.543673] Starting dynamic subtest: bcs0
[747.512530] Dynamic subtest bcs0: SUCCESS (1.979s)
[747.527903] Starting dynamic subtest: vcs0
[749.560507] Dynamic subtest vcs0: SUCCESS (2.044s)
[749.578132] Starting dynamic subtest: vcs1
[751.479447] Dynamic subtest vcs1: SUCCESS (1.914s)
[751.492302] Starting dynamic subtest: vecs0
[752.568921] Dynamic subtest vecs0: SUCCESS (1.085s)
[752.583014] Starting dynamic subtest: ccs0
[754.552790] Dynamic subtest ccs0: SUCCESS (1.981s)
[754.555828] Subtest engine-engine-error: SUCCESS (10.763s)
[754.647527] [127/133] (268s left) gem_exec_fence (basic-wait)
[754.833461] Starting subtest: basic-wait
[754.836869] Starting dynamic subtest: rcs0
[754.921427] Dynamic subtest rcs0: SUCCESS (0.088s)
[754.935878] Starting dynamic subtest: bcs0
[754.988886] Dynamic subtest bcs0: SUCCESS (0.065s)
[755.000555] Starting dynamic subtest: vcs0
[755.048565] Dynamic subtest vcs0: SUCCESS (0.058s)
[755.059617] Starting dynamic subtest: vcs1
[755.111560] Dynamic subtest vcs1: SUCCESS (0.061s)
[755.123187] Starting dynamic subtest: vecs0
[755.175505] Dynamic subtest vecs0: SUCCESS (0.062s)
[755.187127] Starting dynamic subtest: ccs0
[755.232556] Dynamic subtest ccs0: SUCCESS (0.055s)
[755.235536] Subtest basic-wait: SUCCESS (0.400s)
[756.586418] [128/133] (267s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[756.900524] Starting subtest: cursor-rapid-movement-max-size
[756.908514] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[757.052344] [129/133] (266s left) kms_cursor_crc (cursor-offscreen-128x42)
[757.361297] Starting subtest: cursor-offscreen-128x42
[757.455121] Subtest cursor-offscreen-128x42: SKIP (0.089s)
[757.713633] [130/133] (265s left) gem_ctx_engines (invalid-engines)
[757.779511] Starting subtest: invalid-engines
[757.788241] Subtest invalid-engines: SUCCESS (0.008s)
[758.045571] [131/133] (265s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[758.149393] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[758.154216] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[758.257364] [132/133] (265s left) gem_flink_race (flink_close)
[758.292224] Starting subtest: flink_close
[763.414227] Subtest flink_close: SUCCESS (5.099s)
[763.486382] [133/133] (260s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[763.610880] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[763.615795] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[763.737297] Closing watchdogs
results: parsing output: 0/ for test: i915_pm_rps
results: parsing output: 1/ for test: gem_softpin
results: parsing output: 2/ for test: gem_exec_whisper
results: parsing output: 3/ for test: syncobj_wait
results: parsing output: 4/ for test: kms_plane_scaling
results: parsing output: 5/ for test: gen9_exec_parse
results: parsing output: 6/ for test: gem_flink_race
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: api_intel_bb
results: parsing output: 9/ for test: gem_mmap_gtt
results: parsing output: 10/ for test: gem_exec_fence
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_atomic_transition
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: perf
results: parsing output: 18/ for test: gem_ctx_param
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_vblank
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: kms_joiner
results: parsing output: 23/ for test: kms_pm_rpm
results: parsing output: 24/ for test: gem_exec_fence
results: parsing output: 25/ for test: kms_getfb
results: parsing output: 26/ for test: kms_flip_scaled_crc
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_cursor_crc
results: parsing output: 29/ for test: api_intel_allocator
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_chamelium_hpd
results: parsing output: 34/ for test: gem_pwrite
results: parsing output: 35/ for test: kms_cursor_legacy
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: kms_pm_backlight
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: kms_sequence
results: parsing output: 43/ for test: kms_concurrent
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: prime_vgem
results: parsing output: 46/ for test: kms_hdmi_inject
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_pipe_crc_basic
results: parsing output: 52/ for test: gem_ctx_shared
results: parsing output: 53/ for test: kms_async_flips
results: parsing output: 54/ for test: kms_ccs
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: prime_mmap
results: parsing output: 59/ for test: gem_mmap_gtt
results: parsing output: 60/ for test: gem_huc_copy
results: parsing output: 61/ for test: gem_exec_reloc
results: parsing output: 62/ for test: kms_color
results: parsing output: 63/ for test: syncobj_basic
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: gem_mmap_offset
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: kms_chamelium_edid
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: kms_plane
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: perf
results: parsing output: 73/ for test: kms_psr2_sf
results: parsing output: 74/ for test: kms_pm_rpm
results: parsing output: 75/ for test: perf
results: parsing output: 76/ for test: kms_atomic_transition
results: parsing output: 77/ for test: gem_busy
results: parsing output: 78/ for test: kms_busy
results: parsing output: 79/ for test: kms_fb_coherency
results: parsing output: 80/ for test: gem_vm_create
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: gem_ctx_persistence
results: parsing output: 83/ for test: gem_exec_fence
results: parsing output: 84/ for test: syncobj_timeline
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: gem_eio
results: parsing output: 88/ for test: gem_pxp
results: parsing output: 89/ for test: kms_psr2_sf
results: parsing output: 90/ for test: gem_exec_balancer
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: gem_fenced_exec_thrash
results: parsing output: 94/ for test: gem_mmap_gtt
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: prime_mmap
results: parsing output: 99/ for test: gem_render_copy
results: parsing output: 100/ for test: dmabuf
results: parsing output: 101/ for test: kms_flip
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: perf_pmu
results: parsing output: 104/ for test: kms_psr
results: parsing output: 105/ for test: gem_mmap_gtt
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: i915_suspend
results: parsing output: 108/ for test: gem_ctx_freq
results: parsing output: 109/ for test: kms_plane_multiple
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: i915_drm_fdinfo
results: parsing output: 113/ for test: gem_exec_reloc
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_flip_scaled_crc
results: parsing output: 116/ for test: gem_render_copy
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: gem_busy
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: kms_lease
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: i915_hangman
results: parsing output: 126/ for test: gem_exec_fence
results: parsing output: 127/ for test: kms_cursor_crc
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: gem_ctx_engines
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_flink_race
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.