[57.847456] Initializing watchdogs
[57.847506]   /dev/watchdog0
[57.860950] [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.864862] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.866931] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.870274] [001/131] (960s left) gem_exec_schedule (preempt-user)
[57.938162] Starting subtest: preempt-user
[57.940219] Starting dynamic subtest: rcs0
[57.955840] Dynamic subtest rcs0: SUCCESS (0.023s)
[57.964949] Starting dynamic subtest: bcs0
[57.976997] Dynamic subtest bcs0: SUCCESS (0.021s)
[57.983461] Starting dynamic subtest: vcs0
[57.999730] Dynamic subtest vcs0: SUCCESS (0.023s)
[58.005909] Starting dynamic subtest: vcs1
[58.025094] Dynamic subtest vcs1: SUCCESS (0.025s)
[58.031512] Starting dynamic subtest: vecs0
[58.045603] Dynamic subtest vecs0: SUCCESS (0.020s)
[58.054889] Starting dynamic subtest: ccs0
[58.072628] Dynamic subtest ccs0: SUCCESS (0.027s)
[58.074958] Subtest preempt-user: SUCCESS (0.140s)
[60.034108] [002/131] (958s left) gem_exec_schedule (u-lateslice)
[60.111492] Starting subtest: u-lateslice
[60.113484] Starting dynamic subtest: rcs0
[60.130706] Dynamic subtest rcs0: SUCCESS (0.028s)
[60.138906] Starting dynamic subtest: bcs0
[60.150017] Dynamic subtest bcs0: SUCCESS (0.019s)
[60.156270] Starting dynamic subtest: vcs0
[60.171769] Dynamic subtest vcs0: SUCCESS (0.021s)
[60.177774] Starting dynamic subtest: vcs1
[60.191458] Dynamic subtest vcs1: SUCCESS (0.020s)
[60.198228] Starting dynamic subtest: vecs0
[60.212674] Dynamic subtest vecs0: SUCCESS (0.021s)
[60.218916] Starting dynamic subtest: ccs0
[60.232749] Dynamic subtest ccs0: SUCCESS (0.020s)
[60.235132] Subtest u-lateslice: SUCCESS (0.130s)
[62.028407] [003/131] (956s left) gem_exec_create (basic)
[62.093869] Starting subtest: basic
[62.096282] Starting dynamic subtest: smem
[64.202625] Dynamic subtest smem: SUCCESS (2.094s)
[64.205077] Subtest basic: SUCCESS (2.094s)
[64.362843] [004/131] (954s left) gem_bad_reloc (negative-reloc-lut)
[64.426566] Subtest negative-reloc-lut: SKIP (0.000s)
[64.476236] [005/131] (954s left) gem_userptr_blits (input-checking)
[64.547239] Starting subtest: input-checking
[64.549114] Subtest input-checking: SUCCESS (0.000s)
[64.682127] [006/131] (953s left) kms_atomic_interruptible (legacy-dpms)
[64.780944] Starting subtest: legacy-dpms
[64.783796] Starting dynamic subtest: pipe-A-eDP-1
[72.227998] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.445s)
[72.230267] Subtest legacy-dpms: SUCCESS (7.445s)
[73.049063] [007/131] (945s left) gem_exec_capture (capture-recoverable)
[73.117471] Starting subtest: capture-recoverable
[73.122054] Subtest capture-recoverable: SUCCESS (0.003s)
[73.232648] [008/131] (945s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[73.359472] Starting subtest: verify-pxp-execution-after-suspend-resume
[80.301389] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (6.940s)
[80.417632] [009/131] (938s left) gem_mmap_gtt (zero-extend)
[80.473978] Subtest zero-extend: SKIP (0.000s)
[80.527834] [010/131] (938s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[80.637675] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[87.212704] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.575s)
[87.348186] [011/131] (931s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[87.460435] Starting subtest: yf-tiled-addfb-size-overflow
[87.464685] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[87.557134] [012/131] (931s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[87.655297] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[94.610565] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (6.956s)
[94.717491] [013/131] (924s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[94.821328] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[101.368557] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (6.549s)
[101.509896] [014/131] (917s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-25)
[101.599940] Starting subtest: planes-upscale-20x20-downscale-factor-0-25
[101.602299] Starting dynamic subtest: pipe-A
[101.617076] Dynamic subtest pipe-A: SKIP (0.011s)
[101.624339] Starting dynamic subtest: pipe-B
[101.639292] Dynamic subtest pipe-B: SKIP (0.023s)
[101.646552] Starting dynamic subtest: pipe-C
[101.661313] Dynamic subtest pipe-C: SKIP (0.023s)
[101.668748] Starting dynamic subtest: pipe-D
[101.686459] Dynamic subtest pipe-D: SKIP (0.024s)
[101.690288] Subtest planes-upscale-20x20-downscale-factor-0-25: SKIP (0.081s)
[101.771557] [015/131] (917s left) kms_prime (d3hot)
[103.824226] Subtest D3hot: SKIP (0.000s)
[103.906283] [FACT kms_prime (d3hot)] new: kernel.kmod_is_loaded.amdgpu: true
[103.906805] [FACT kms_prime (d3hot)] new: kernel.kmod_is_loaded.vgem: true
[103.906931] [FACT kms_prime (d3hot)] new: kernel.kmod_is_loaded.xe: true
[103.909868] [016/131] (914s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[104.004518] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[105.081940] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.079s)
[105.222205] [017/131] (913s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[105.280167] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[105.284776] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[105.457210] [018/131] (913s left) api_intel_bb (render)
[105.517243] Starting subtest: render
[105.519976] Starting dynamic subtest: render-none-512
[105.617770] Dynamic subtest render-none-512: SUCCESS (0.101s)
[105.627304] Starting dynamic subtest: render-none-1024
[105.993461] Dynamic subtest render-none-1024: SUCCESS (0.374s)
[106.002721] Starting dynamic subtest: render-x-512
[106.081585] Dynamic subtest render-x-512: SUCCESS (0.088s)
[106.092071] Starting dynamic subtest: render-x-1024
[106.421971] Dynamic subtest render-x-1024: SUCCESS (0.339s)
[106.431813] Starting dynamic subtest: render-y-512
[106.506399] Dynamic subtest render-y-512: SUCCESS (0.086s)
[106.516443] Starting dynamic subtest: render-y-1024
[106.848701] Dynamic subtest render-y-1024: SUCCESS (0.340s)
[106.851673] Subtest render: SUCCESS (1.331s)
[106.965563] [019/131] (911s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[107.065825] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[107.070284] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[107.170199] [020/131] (911s left) gem_exec_whisper (basic-queues-all)
[107.237419] Starting subtest: basic-queues-all
[117.833762] Subtest basic-queues-all: SUCCESS (10.592s)
[118.007327] [021/131] (901s left) kms_chamelium_hpd (hdmi-hpd-after-suspend)
[118.390795] Subtest hdmi-hpd-after-suspend: SKIP (0.000s)
[119.206389] [022/131] (899s left) kms_psr (fbc-psr-primary-mmap-cpu)
[119.301105] Starting subtest: fbc-psr-primary-mmap-cpu
[119.303421] Starting dynamic subtest: eDP-1
[119.307499] Dynamic subtest eDP-1: SKIP (0.000s)
[119.311565] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s)
[119.373144] [023/131] (899s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[119.479439] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[119.483519] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[119.572864] [024/131] (899s left) perf_pmu (init-sema)
[119.613164] Starting subtest: init-sema
[119.615481] Starting dynamic subtest: rcs0
[119.617724] Dynamic subtest rcs0: SUCCESS (0.002s)
[119.625475] Starting dynamic subtest: bcs0
[119.627432] Dynamic subtest bcs0: SUCCESS (0.000s)
[119.635955] Starting dynamic subtest: vcs0
[119.637819] Dynamic subtest vcs0: SUCCESS (0.000s)
[119.644647] Starting dynamic subtest: vcs1
[119.646546] Dynamic subtest vcs1: SUCCESS (0.000s)
[119.653376] Starting dynamic subtest: vecs0
[119.655246] Dynamic subtest vecs0: SUCCESS (0.000s)
[119.662074] Starting dynamic subtest: ccs0
[119.663917] Dynamic subtest ccs0: SUCCESS (0.000s)
[119.665757] Subtest init-sema: SUCCESS (0.005s)
[119.724139] [025/131] (899s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[119.810057] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[119.812470] Starting dynamic subtest: pipe-A-default-mode
[120.979789] Dynamic subtest pipe-A-default-mode: SKIP (1.164s)
[120.984165] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.165s)
[121.114052] [026/131] (898s left) kms_chamelium_edid (hdmi-edid-stress-resolution-4k)
[121.490956] Subtest hdmi-edid-stress-resolution-4k: SKIP (0.000s)
[122.247865] [027/131] (896s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[122.342127] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[122.344501] Starting dynamic subtest: pipe-A-eDP-1
[122.367066] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[122.376435] Starting dynamic subtest: pipe-B-eDP-1
[122.397698] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[122.404651] Starting dynamic subtest: pipe-C-eDP-1
[122.425832] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[122.432645] Starting dynamic subtest: pipe-D-eDP-1
[122.453875] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[122.457789] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[124.525790] [028/131] (894s left) kms_chamelium_audio (dp-audio)
[124.925404] Subtest dp-audio: SKIP (0.000s)
[125.726766] [029/131] (893s left) kms_async_flips (invalid-async-flip)
[125.825990] Starting subtest: invalid-async-flip
[125.830581] Subtest invalid-async-flip: SKIP (0.000s)
[126.971438] [030/131] (892s left) gem_exec_params (secure-non-root)
[127.020913] Starting subtest: secure-non-root
[127.025583] Subtest secure-non-root: SKIP (0.000s)
[127.127987] [031/131] (892s left) kms_properties (get_properties-sanity-atomic)
[127.215838] Starting subtest: get_properties-sanity-atomic
[127.218116] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[127.286613] [032/131] (892s left) gem_softpin (full)
[127.344323] Starting subtest: full
[127.352427] Subtest full: SUCCESS (0.008s)
[127.478244] [033/131] (891s left) gem_close_race (basic-process)
[127.559562] Starting subtest: basic-process
[127.645470] Subtest basic-process: SUCCESS (0.086s)
[127.820080] [034/131] (891s left) gem_userptr_blits (unsync-overlap)
[127.890050] Subtest unsync-overlap: SKIP (0.000s)
[128.026918] [035/131] (891s left) kms_flip (basic-flip-vs-modeset)
[128.131216] Starting subtest: basic-flip-vs-modeset
[128.133574] Starting dynamic subtest: A-eDP1
[130.672442] Dynamic subtest A-eDP1: SUCCESS (2.539s)
[130.680853] Starting dynamic subtest: B-eDP1
[132.818181] Dynamic subtest B-eDP1: SUCCESS (2.145s)
[132.826171] Starting dynamic subtest: C-eDP1
[134.964876] Dynamic subtest C-eDP1: SUCCESS (2.146s)
[134.973579] Starting dynamic subtest: D-eDP1
[137.108933] Dynamic subtest D-eDP1: SUCCESS (2.144s)
[137.111818] Subtest basic-flip-vs-modeset: SUCCESS (8.979s)
[137.905853] [036/131] (881s left) kms_psr (psr-no-drrs)
[138.009766] Starting subtest: psr-no-drrs
[138.012193] Starting dynamic subtest: eDP-1
[139.680130] Dynamic subtest eDP-1: SUCCESS (1.670s)
[139.682537] Subtest psr-no-drrs: SUCCESS (1.670s)
[140.461053] [037/131] (879s left) syncobj_basic (bad-fd-to-handle)
[140.518209] Starting subtest: bad-fd-to-handle
[140.520461] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[140.598794] [038/131] (878s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[140.707302] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[140.711430] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[140.819807] [039/131] (878s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[140.923445] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[140.927689] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[141.019320] [040/131] (878s left) perf_pmu (busy)
[141.058765] Starting subtest: busy
[141.061124] Starting dynamic subtest: rcs0
[141.662924] Dynamic subtest rcs0: SUCCESS (0.604s)
[141.670856] Starting dynamic subtest: bcs0
[142.220138] Dynamic subtest bcs0: SUCCESS (0.556s)
[142.230900] Starting dynamic subtest: vcs0
[143.915057] Dynamic subtest vcs0: SUCCESS (1.692s)
[143.925940] Starting dynamic subtest: vcs1
[145.962317] Dynamic subtest vcs1: SUCCESS (2.046s)
[145.973259] Starting dynamic subtest: vecs0
[147.947373] Dynamic subtest vecs0: SUCCESS (1.984s)
[147.958079] Starting dynamic subtest: ccs0
[148.514453] Dynamic subtest ccs0: SUCCESS (0.568s)
[148.516926] Subtest busy: SUCCESS (7.455s)
[148.623357] [041/131] (871s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[148.739854] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[148.743837] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[148.840460] [042/131] (870s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[153.796517] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[153.799091] Starting dynamic subtest: pipe-A-eDP-1
[160.310219] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.511s)
[160.318269] Starting dynamic subtest: pipe-B-eDP-1
[166.963089] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.651s)
[166.965522] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.165s)
[167.835426] [043/131] (851s left) kms_cursor_crc (cursor-offscreen-128x42)
[168.021657] Starting subtest: cursor-offscreen-128x42
[168.063359] Subtest cursor-offscreen-128x42: SKIP (0.035s)
[168.272864] [044/131] (851s left) kms_cursor_crc (cursor-sliding-128x128)
[168.458337] Starting subtest: cursor-sliding-128x128
[168.463349] Starting dynamic subtest: pipe-A-eDP-1
[171.916143] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.453s)
[171.943998] Starting dynamic subtest: pipe-D-eDP-1
[176.068752] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.130s)
[176.070928] Subtest cursor-sliding-128x128: SUCCESS (7.608s)
[176.883722] [045/131] (842s left) gem_lmem_swapping (heavy-verify-multi)
[176.931065] Subtest heavy-verify-multi: SKIP (0.000s)
[176.966720] [046/131] (842s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[177.079029] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[177.081395] Starting dynamic subtest: A-eDP1
[180.580989] Dynamic subtest A-eDP1: SUCCESS (3.499s)
[180.590082] Starting dynamic subtest: B-eDP1
[183.653224] Dynamic subtest B-eDP1: SUCCESS (3.072s)
[183.662483] Starting dynamic subtest: C-eDP1
[186.725671] Dynamic subtest C-eDP1: SUCCESS (3.069s)
[186.733719] Starting dynamic subtest: D-eDP1
[189.810049] Dynamic subtest D-eDP1: SUCCESS (3.085s)
[189.812472] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (12.732s)
[190.593641] [047/131] (829s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[190.702121] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[190.706097] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[190.806285] [048/131] (829s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[190.870523] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[190.935641] [049/131] (829s left) api_intel_allocator (reloc-allocator)
[190.987539] Starting subtest: reloc-allocator
[190.990117] Starting dynamic subtest: basic
[190.992646] Dynamic subtest basic: SUCCESS (0.003s)
[191.000999] Starting dynamic subtest: parallel-one
[191.003424] Dynamic subtest parallel-one: SUCCESS (0.008s)
[191.012516] Starting dynamic subtest: print
[191.014792] Dynamic subtest print: SUCCESS (0.000s)
[191.022482] Starting dynamic subtest: reuse
[191.024605] Dynamic subtest reuse: SUCCESS (0.000s)
[191.032242] Starting dynamic subtest: fork-reopen-allocator
[191.034469] Dynamic subtest fork-reopen-allocator: SUCCESS (0.015s)
[191.036606] Subtest reloc-allocator: SUCCESS (0.028s)
[191.139108] [050/131] (828s left) gem_render_copy_redux (normal)
[191.203200] Starting subtest: normal
[191.537859] Subtest normal: SUCCESS (0.335s)
[191.672147] [051/131] (828s left) kms_big_fb (linear-32bpp-rotate-90)
[191.776743] Starting subtest: linear-32bpp-rotate-90
[191.795250] Subtest linear-32bpp-rotate-90: SKIP (0.018s)
[191.938952] [052/131] (828s left) gem_ctx_persistence (engines-queued)
[191.994093] Starting subtest: engines-queued
[191.996124] Starting dynamic subtest: rcs0
[192.082637] Dynamic subtest rcs0: SUCCESS (0.088s)
[192.093616] Starting dynamic subtest: bcs0
[192.112063] Dynamic subtest bcs0: SUCCESS (0.028s)
[192.119065] Starting dynamic subtest: vcs0
[193.931908] Dynamic subtest vcs0: SUCCESS (1.817s)
[193.942997] Starting dynamic subtest: vcs1
[195.913868] Dynamic subtest vcs1: SUCCESS (1.979s)
[195.924693] Starting dynamic subtest: vecs0
[197.961078] Dynamic subtest vecs0: SUCCESS (1.981s)
[197.971647] Starting dynamic subtest: ccs0
[198.048819] Dynamic subtest ccs0: SUCCESS (0.151s)
[198.051489] Subtest engines-queued: SUCCESS (6.054s)
[198.187203] [053/131] (821s left) sw_sync (sync_merge)
[198.226583] Starting subtest: sync_merge
[198.229205] Subtest sync_merge: SUCCESS (0.000s)
[198.279395] [054/131] (821s left) gem_exec_reloc (basic-cpu-noreloc)
[198.346088] Subtest basic-cpu-noreloc: SKIP (0.000s)
[198.421246] [055/131] (821s left) kms_psr (psr2-primary-mmap-cpu)
[198.526231] Starting subtest: psr2-primary-mmap-cpu
[198.528558] Starting dynamic subtest: eDP-1
[200.267758] Dynamic subtest eDP-1: SUCCESS (1.740s)
[200.270212] Subtest psr2-primary-mmap-cpu: SUCCESS (1.741s)
[201.048339] [056/131] (819s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[201.114464] Starting subtest: single-wait-for-submit-available-signaled
[201.116328] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[201.224429] [057/131] (819s left) gem_exec_balancer (bonded-true-hang)
[201.462462] Starting subtest: bonded-true-hang
[201.467553] Subtest bonded-true-hang: SKIP (0.000s)
[201.575901] [058/131] (818s left) kms_getfb (getfb2-handle-zero)
[201.636907] Starting subtest: getfb2-handle-zero
[201.639408] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[201.744612] [059/131] (818s left) gem_exec_schedule (noreorder)
[201.936623] Starting subtest: noreorder
[201.939139] Starting dynamic subtest: rcs0
[202.028434] Dynamic subtest rcs0: SUCCESS (0.091s)
[202.040089] Starting dynamic subtest: vcs0
[203.979482] Dynamic subtest vcs0: SUCCESS (1.948s)
[203.993547] Starting dynamic subtest: vcs1
[205.914055] Dynamic subtest vcs1: SUCCESS (1.932s)
[205.924903] Starting dynamic subtest: vecs0
[207.898305] Dynamic subtest vecs0: SUCCESS (1.980s)
[207.912137] Starting dynamic subtest: ccs0
[207.949557] Dynamic subtest ccs0: SUCCESS (0.053s)
[207.952022] Subtest noreorder: SUCCESS (6.013s)
[208.127248] [060/131] (812s left) sysfs_timeslice_duration (timeout)
[208.193175] Starting subtest: timeout
[208.195615] Starting dynamic subtest: vecs0
[217.899571] Dynamic subtest vecs0: SUCCESS (9.701s)
[217.909849] Starting dynamic subtest: ccs0
[219.240241] Dynamic subtest ccs0: SUCCESS (1.341s)
[219.250895] Starting dynamic subtest: vcs0
[228.903947] Dynamic subtest vcs0: SUCCESS (9.659s)
[228.917105] Starting dynamic subtest: bcs0
[229.538770] Dynamic subtest bcs0: SUCCESS (0.634s)
[229.549231] Starting dynamic subtest: rcs0
[230.887410] Dynamic subtest rcs0: SUCCESS (1.345s)
[230.898073] Starting dynamic subtest: vcs1
[240.959459] Dynamic subtest vcs1: SUCCESS (10.070s)
[240.962102] Subtest timeout: SUCCESS (32.761s)
[241.029518] [061/131] (779s left) kms_joiner (basic-max-non-joiner)
[241.134074] Starting subtest: basic-max-non-joiner
[241.138333] Subtest basic-max-non-joiner: SKIP (0.000s)
[241.193201] [062/131] (779s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[241.298793] Starting subtest: fbc-rgb565-draw-mmap-cpu
[245.326704] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.033s)
[246.428966] [063/131] (774s left) syncobj_timeline (signal-array)
[246.491178] Starting subtest: signal-array
[246.493541] Subtest signal-array: SUCCESS (0.000s)
[246.585088] [064/131] (773s left) sriov_basic (enable-vfs-autoprobe-on)
[246.650121] Starting subtest: enable-vfs-autoprobe-on
[246.652606] Starting dynamic subtest: numvfs-1
[246.733862] Dynamic subtest numvfs-1: FAIL (0.034s)
[246.743311] Starting dynamic subtest: numvfs-2
[246.819070] Dynamic subtest numvfs-2: FAIL (0.004s)
[246.828584] Starting dynamic subtest: numvfs-3
[246.898189] Dynamic subtest numvfs-3: FAIL (0.003s)
[246.905546] Starting dynamic subtest: numvfs-4
[246.974829] Dynamic subtest numvfs-4: FAIL (0.003s)
[246.982268] Starting dynamic subtest: numvfs-5
[247.051151] Dynamic subtest numvfs-5: FAIL (0.003s)
[247.058929] Starting dynamic subtest: numvfs-6
[247.128405] Dynamic subtest numvfs-6: FAIL (0.004s)
[247.136155] Starting dynamic subtest: numvfs-7
[247.207255] Dynamic subtest numvfs-7: FAIL (0.004s)
[247.217080] Starting dynamic subtest: numvfs-random
[247.290981] Dynamic subtest numvfs-random: FAIL (0.007s)
[247.300381] Starting dynamic subtest: numvfs-all
[247.372061] Dynamic subtest numvfs-all: FAIL (0.485s)
[247.373799] Subtest enable-vfs-autoprobe-on: FAIL (0.554s)
[247.404062] [065/131] (773s left) gem_ctx_create (basic-files)
[247.471259] Starting subtest: basic-files
[249.793962] Subtest basic-files: SUCCESS (2.322s)
[250.965110] [066/131] (769s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[251.061223] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[251.063687] Starting dynamic subtest: pipe-A-eDP-1
[251.558180] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.496s)
[251.568813] Starting dynamic subtest: pipe-B-eDP-1
[251.589501] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[251.598690] Starting dynamic subtest: pipe-C-eDP-1
[251.617149] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[251.624068] Starting dynamic subtest: pipe-D-eDP-1
[251.642841] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[251.644634] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.510s)
[253.771969] [067/131] (766s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[253.878184] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[258.424695] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.551s)
[259.561157] [068/131] (761s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[259.665877] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[259.670311] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[259.765143] [069/131] (760s left) gen3_render_tiledx_blits
[259.881332] [070/131] (760s left) perf (invalid-open-flags)
[259.981687] Starting subtest: invalid-open-flags
[259.983977] Subtest invalid-open-flags: SUCCESS (0.000s)
[260.015643] [071/131] (760s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[260.127742] Starting subtest: fbc-rgb565-draw-mmap-gtt
[260.132161] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[260.228973] [072/131] (760s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[260.326051] Starting subtest: coverage-vs-premult-vs-constant
[260.756116] Starting dynamic subtest: pipe-A-eDP-1
[261.306900] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.549s)
[261.316606] Starting dynamic subtest: pipe-D-eDP-1
[262.618069] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.310s)
[262.621232] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.291s)
[263.403536] [073/131] (757s left) kms_flip (2x-flip-vs-wf_vblank)
[263.498352] Starting subtest: 2x-flip-vs-wf_vblank
[263.508782] Subtest 2x-flip-vs-wf_vblank: SKIP (0.010s)
[263.582622] [074/131] (757s left) kms_rotation_crc (exhaust-fences)
[263.690794] Starting subtest: exhaust-fences
[263.695626] Subtest exhaust-fences: SKIP (0.000s)
[263.757233] [075/131] (757s left) i915_pm_sseu (full-enable)
[263.818757] Subtest full-enable: SKIP (0.000s)
[263.916970] [076/131] (756s left) kms_cursor_edge_walk (128x128-right-edge)
[264.005179] Starting subtest: 128x128-right-edge
[264.007549] Starting dynamic subtest: pipe-A-eDP-1
[267.453764] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.446s)
[267.463108] Starting dynamic subtest: pipe-D-eDP-1
[270.561293] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.104s)
[270.563476] Subtest 128x128-right-edge: SUCCESS (6.552s)
[271.379096] [077/131] (749s left) kms_lease (lease-get)
[271.484081] Starting subtest: lease-get
[271.486429] Starting dynamic subtest: pipe-A-eDP-1
[271.488696] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[271.497669] Starting dynamic subtest: pipe-B-eDP-1
[271.499803] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[271.507247] Starting dynamic subtest: pipe-C-eDP-1
[271.509353] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[271.516750] Starting dynamic subtest: pipe-D-eDP-1
[271.518843] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[271.521053] Subtest lease-get: SUCCESS (0.011s)
[271.618839] [078/131] (749s left) kms_pm_rpm (legacy-planes-dpms)
[273.152400] Starting subtest: legacy-planes-dpms
[273.155572] Starting dynamic subtest: plane-41
[281.715024] Dynamic subtest plane-41: SUCCESS (8.561s)
[281.726066] Starting dynamic subtest: plane-50
[290.305540] Dynamic subtest plane-50: SUCCESS (8.578s)
[290.316293] Starting dynamic subtest: plane-59
[298.858055] Dynamic subtest plane-59: SUCCESS (8.560s)
[298.869019] Starting dynamic subtest: plane-68
[307.316500] Dynamic subtest plane-68: SUCCESS (8.455s)
[307.319297] Subtest legacy-planes-dpms: SUCCESS (34.163s)
[307.670498] [079/131] (713s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[312.698771] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[312.701111] Starting dynamic subtest: pipe-A-eDP-1
[313.513859] Dynamic subtest pipe-A-eDP-1: SKIP (0.809s)
[313.522397] Starting dynamic subtest: pipe-B-eDP-1
[314.738193] Dynamic subtest pipe-B-eDP-1: SKIP (1.222s)
[314.742606] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (2.036s)
[315.911708] [080/131] (705s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[316.006096] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[316.008284] Starting dynamic subtest: pipe-A
[316.342676] Dynamic subtest pipe-A: SUCCESS (0.337s)
[316.352201] Starting dynamic subtest: pipe-B
[317.691500] Dynamic subtest pipe-B: SUCCESS (1.348s)
[317.701197] Starting dynamic subtest: pipe-C
[319.112293] Dynamic subtest pipe-C: SUCCESS (1.420s)
[319.121325] Starting dynamic subtest: pipe-D
[320.550132] Dynamic subtest pipe-D: SUCCESS (1.437s)
[320.552405] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.544s)
[321.708968] [081/131] (699s left) kms_flip (dpms-vs-vblank-race)
[321.804138] Starting subtest: dpms-vs-vblank-race
[321.806420] Starting dynamic subtest: A-eDP1
[326.770019] Dynamic subtest A-eDP1: SUCCESS (4.962s)
[326.780278] Starting dynamic subtest: B-eDP1
[331.363802] Dynamic subtest B-eDP1: SUCCESS (4.594s)
[331.373631] Starting dynamic subtest: C-eDP1
[335.949515] Dynamic subtest C-eDP1: SUCCESS (4.583s)
[335.958076] Starting dynamic subtest: D-eDP1
[340.540018] Dynamic subtest D-eDP1: SUCCESS (4.591s)
[340.542202] Subtest dpms-vs-vblank-race: SUCCESS (18.737s)
[341.410659] [082/131] (679s left) kms_flip (flip-vs-absolute-wf_vblank)
[341.501869] Starting subtest: flip-vs-absolute-wf_vblank
[341.504157] Starting dynamic subtest: A-eDP1
[350.458712] Dynamic subtest A-eDP1: SUCCESS (8.955s)
[350.468895] Starting dynamic subtest: B-eDP1
[359.385783] Dynamic subtest B-eDP1: SUCCESS (8.925s)
[359.393228] Starting dynamic subtest: C-eDP1
[368.352131] Dynamic subtest C-eDP1: SUCCESS (8.917s)
[368.361689] Starting dynamic subtest: D-eDP1
[377.224833] Dynamic subtest D-eDP1: SUCCESS (8.918s)
[377.229766] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.724s)
[378.442388] [083/131] (642s left) kms_psr (fbc-pr-cursor-blt)
[378.540847] Starting subtest: fbc-pr-cursor-blt
[378.544831] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[378.605485] [084/131] (642s left) kms_cursor_crc (cursor-onscreen-64x64)
[378.759121] Starting subtest: cursor-onscreen-64x64
[378.764474] Starting dynamic subtest: pipe-A-eDP-1
[381.241754] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.476s)
[381.273455] Starting dynamic subtest: pipe-D-eDP-1
[384.142176] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.875s)
[384.144270] Subtest cursor-onscreen-64x64: SUCCESS (5.381s)
[384.998156] [085/131] (636s left) gem_exec_fence (syncobj-timeline-signal)
[385.564998] Starting subtest: syncobj-timeline-signal
[385.571485] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[387.037919] [086/131] (634s left) gem_ctx_persistence (saturated-hostile)
[387.093681] Starting subtest: saturated-hostile
[387.095875] Starting dynamic subtest: rcs0
[387.919793] Dynamic subtest rcs0: SUCCESS (0.825s)
[387.932152] Starting dynamic subtest: bcs0
[388.740566] Dynamic subtest bcs0: SUCCESS (0.819s)
[388.751731] Starting dynamic subtest: vcs0
[389.533487] Dynamic subtest vcs0: SUCCESS (0.792s)
[389.543574] Starting dynamic subtest: vcs1
[390.347113] Dynamic subtest vcs1: SUCCESS (0.808s)
[390.357077] Starting dynamic subtest: vecs0
[391.156428] Dynamic subtest vecs0: SUCCESS (0.812s)
[391.168384] Starting dynamic subtest: ccs0
[391.985534] Dynamic subtest ccs0: SUCCESS (0.827s)
[391.988225] Subtest saturated-hostile: SUCCESS (4.892s)
[392.060785] [087/131] (629s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-gtt)
[392.156024] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-gtt
[392.160140] Subtest psr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[392.241650] [088/131] (629s left) syncobj_basic (bad-destroy)
[392.303769] Starting subtest: bad-destroy
[392.306583] Subtest bad-destroy: SUCCESS (0.000s)
[392.392666] [089/131] (628s left) kms_dp_aux_dev
[392.756541] [090/131] (628s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[392.860212] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[392.864836] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[393.941294] [091/131] (627s left) gem_mmap_offset (partial-mmap)
[394.012058] Starting subtest: partial-mmap
[394.014247] Starting dynamic subtest: smem0
[394.016739] Dynamic subtest smem0: SUCCESS (0.004s)
[394.018767] Subtest partial-mmap: SUCCESS (0.005s)
[394.136305] [092/131] (627s left) device_reset (unbind-cold-reset-rebind)
[394.182523] Starting subtest: unbind-cold-reset-rebind
[394.187105] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[394.249205] [093/131] (627s left) i915_query (test-query-geometry-subslices)
[394.310585] Starting subtest: test-query-geometry-subslices
[394.312808] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[394.426970] [094/131] (627s left) i915_module_load (reload-with-fault-injection)
[394.464451] Starting subtest: reload-with-fault-injection
[433.525498] Subtest reload-with-fault-injection: SUCCESS (39.062s)
[433.551889] [FACT i915_module_load (reload-with-fault-injection)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[433.552678] [FACT i915_module_load (reload-with-fault-injection)] deleted: kernel.kmod_is_loaded.i915: true
[433.555790] [095/131] (587s left) kms_vblank (wait-busy)
[434.918350] Starting subtest: wait-busy
[434.920617] Starting dynamic subtest: pipe-A-eDP-1
[436.831777] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.912s)
[436.843765] Starting dynamic subtest: pipe-D-eDP-1
[439.391859] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.556s)
[439.394227] Subtest wait-busy: SUCCESS (4.472s)
[440.190794] [FACT kms_vblank (wait-busy)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[440.192989] [FACT kms_vblank (wait-busy)] new: kernel.kmod_is_loaded.i915: true
[440.197334] [096/131] (581s left) gem_render_copy (linear)
[440.272722] Starting subtest: linear
[440.275130] Starting dynamic subtest: smem
[440.323339] Dynamic subtest smem: SUCCESS (0.050s)
[440.325701] Subtest linear: SUCCESS (0.051s)
[440.500379] [097/131] (581s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[440.601373] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[440.603731] Starting dynamic subtest: pipe-A-default-mode
[441.938587] Dynamic subtest pipe-A-default-mode: SKIP (1.332s)
[441.943060] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.333s)
[442.113614] [098/131] (579s left) gem_userptr_blits (dmabuf-unsync)
[442.196032] Subtest dmabuf-unsync: SKIP (0.000s)
[442.315644] [099/131] (579s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[442.418972] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[442.423511] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[442.506310] [100/131] (579s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[442.614304] Starting subtest: y-tiled-32bpp-rotate-270
[442.618347] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[442.710792] [101/131] (579s left) gem_exec_balancer (individual)
[442.776109] Starting subtest: individual
[444.939273] Subtest individual: SUCCESS (2.161s)
[445.257749] [102/131] (576s left) kms_flip (flip-vs-expired-vblank)
[445.359068] Starting subtest: flip-vs-expired-vblank
[445.361246] Starting dynamic subtest: A-eDP1
[454.053225] Dynamic subtest A-eDP1: SUCCESS (8.660s)
[454.061508] Starting dynamic subtest: B-eDP1
[462.781906] Dynamic subtest B-eDP1: SUCCESS (8.726s)
[462.793870] Starting dynamic subtest: C-eDP1
[471.505420] Dynamic subtest C-eDP1: SUCCESS (8.711s)
[471.512804] Starting dynamic subtest: D-eDP1
[480.231244] Dynamic subtest D-eDP1: SUCCESS (8.724s)
[480.233560] Subtest flip-vs-expired-vblank: SUCCESS (34.828s)
[481.334667] [103/131] (540s left) kms_psr (fbc-psr2-cursor-render)
[481.434752] Starting subtest: fbc-psr2-cursor-render
[481.437028] Starting dynamic subtest: eDP-1
[481.441143] Dynamic subtest eDP-1: SKIP (0.000s)
[481.445175] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[481.500491] [104/131] (540s left) kms_plane_scaling (intel-max-src-size)
[481.597517] Starting subtest: intel-max-src-size
[481.604556] Subtest intel-max-src-size: SKIP (0.000s)
[481.713778] [105/131] (540s left) gem_exec_reloc (basic-write-cpu-noreloc)
[481.776234] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[481.863909] [106/131] (540s left) gem_pxp (hw-rejects-pxp-buffer)
[481.996762] Starting subtest: hw-rejects-pxp-buffer
[482.001441] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[482.106716] [107/131] (539s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[482.203678] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[482.208179] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[482.323690] [108/131] (539s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[482.426100] Starting subtest: x-tiled-64bpp-rotate-90
[482.444169] Subtest x-tiled-64bpp-rotate-90: SKIP (0.017s)
[482.565259] [109/131] (539s left) kms_color (ctm-0-50)
[482.655744] Starting subtest: ctm-0-50
[482.657683] Starting dynamic subtest: pipe-A-eDP-1
[486.032813] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.376s)
[486.042552] Starting dynamic subtest: pipe-B-eDP-1
[489.990798] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.955s)
[489.999049] Starting dynamic subtest: pipe-C-eDP-1
[493.927243] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.936s)
[493.936444] Starting dynamic subtest: pipe-D-eDP-1
[497.900118] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.972s)
[497.902518] Subtest ctm-0-50: SUCCESS (15.243s)
[498.751160] [110/131] (523s left) syncobj_timeline (invalid-transfer-non-existent-point)
[498.816556] Starting subtest: invalid-transfer-non-existent-point
[498.818943] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[498.931456] [111/131] (523s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[498.996822] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[498.998758] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[499.105640] [112/131] (523s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[499.209445] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[499.213759] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[499.311048] [113/131] (522s left) gen9_exec_parse (shadow-peek)
[499.372965] Subtest shadow-peek: SKIP (0.000s)
[499.458943] [114/131] (522s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[499.566516] Starting subtest: 4-tiled-64bpp-rotate-180
[501.790533] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (2.223s)
[502.043097] [115/131] (520s left) kms_selftest (drm_dp_mst_helper)
[502.086025] Starting subtest: drm_dp_mst_helper
[502.105779] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[502.275412] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.170s)
[502.285149] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[502.397119] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.120s)
[502.406062] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[502.534199] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.136s)
[502.536070] Subtest drm_dp_mst_helper: SUCCESS (0.450s)
[502.741388] [116/131] (519s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[502.801936] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[502.904149] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.102s)
[502.972619] [117/131] (519s left) syncobj_wait (invalid-reset-one-illegal-handle)
[503.026692] Starting subtest: invalid-reset-one-illegal-handle
[503.028691] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[503.124642] [118/131] (519s left) gem_cs_tlb (engines)
[503.180966] Starting subtest: engines
[503.183423] Starting dynamic subtest: rcs0
[505.336820] Dynamic subtest rcs0: SUCCESS (2.152s)
[505.347690] Starting dynamic subtest: bcs0
[507.543818] Dynamic subtest bcs0: SUCCESS (2.153s)
[507.555307] Starting dynamic subtest: vcs0
[509.695946] Dynamic subtest vcs0: SUCCESS (2.152s)
[509.706975] Starting dynamic subtest: vcs1
[511.849939] Dynamic subtest vcs1: SUCCESS (2.153s)
[511.858853] Starting dynamic subtest: vecs0
[514.004988] Dynamic subtest vecs0: SUCCESS (2.155s)
[514.013187] Starting dynamic subtest: ccs0
[516.159556] Dynamic subtest ccs0: SUCCESS (2.154s)
[516.161549] Subtest engines: SUCCESS (12.924s)
[516.223314] [119/131] (506s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[516.317192] Starting subtest: sprite-rotation-90-pos-100-0
[516.321724] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[516.388352] [120/131] (506s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[516.511378] Starting subtest: flip-vs-cursor-crc-atomic
[517.115364] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.605s)
[517.977420] [121/131] (504s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[518.089095] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[518.091389] Starting dynamic subtest: pipe-A-eDP-1
[518.114235] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[518.123864] Starting dynamic subtest: pipe-B-eDP-1
[518.145398] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[518.153340] Starting dynamic subtest: pipe-C-eDP-1
[518.175225] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[518.183122] Starting dynamic subtest: pipe-D-eDP-1
[518.205171] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[518.209633] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[520.226426] [122/131] (502s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[520.326146] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[520.336695] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[520.410281] [123/131] (502s left) kms_flip (flip-vs-panning-vs-hang)
[520.505175] Starting subtest: flip-vs-panning-vs-hang
[520.507464] Starting dynamic subtest: A-eDP1
[570.389377] Dynamic subtest A-eDP1: SUCCESS (49.883s)
[570.398830] Starting dynamic subtest: D-eDP1
[620.434024] Dynamic subtest D-eDP1: SUCCESS (50.041s)
[620.436488] Subtest flip-vs-panning-vs-hang: SUCCESS (99.928s)
[621.598649] [124/131] (400s left) gem_partial_pwrite_pread (write)
[621.654589] Subtest write: SKIP (0.000s)
[621.714789] [125/131] (400s left) gen7_exec_parse (cmd-crossing-page)
[621.767841] Subtest cmd-crossing-page: SKIP (0.000s)
[621.831814] [126/131] (400s left) kms_vblank (query-idle-hang)
[621.928059] Starting subtest: query-idle-hang
[621.930383] Starting dynamic subtest: pipe-A-eDP-1
[648.665818] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.733s)
[648.682071] Starting dynamic subtest: pipe-D-eDP-1
[663.646994] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.979s)
[663.649512] Subtest query-idle-hang: SUCCESS (41.717s)
[664.443018] [127/131] (358s left) gem_sync (basic-all)
[664.521087] Starting subtest: basic-all
[667.883454] Subtest basic-all: SUCCESS (3.365s)
[668.066697] [128/131] (354s left) drm_fdinfo (memory-info-shared)
[668.107199] Starting subtest: memory-info-shared
[668.109456] Starting dynamic subtest: smem0
[668.168532] Dynamic subtest smem0: SUCCESS (0.062s)
[668.171221] Subtest memory-info-shared: SUCCESS (0.062s)
[668.235099] [129/131] (354s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[668.353428] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[668.357672] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[668.444954] [130/131] (354s left) sw_sync (timeline_closed_signaled)
[668.485045] Starting subtest: timeline_closed_signaled
[668.487317] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[668.538704] [131/131] (354s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[668.638169] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[668.640438] Starting dynamic subtest: pipe-A-eDP-1
[668.708499] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.069s)
[668.997693] Starting dynamic subtest: pipe-B-eDP-1
[669.753851] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.764s)
[670.032211] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.390s)
[670.888718] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_schedule
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: gem_exec_create
results: parsing output: 3/ for test: gem_bad_reloc
results: parsing output: 4/ for test: gem_userptr_blits
results: parsing output: 5/ for test: kms_atomic_interruptible
results: parsing output: 6/ for test: gem_exec_capture
results: parsing output: 7/ for test: gem_pxp
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_plane_scaling
results: parsing output: 14/ for test: kms_prime
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: api_intel_bb
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_whisper
results: parsing output: 20/ for test: kms_chamelium_hpd
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: perf_pmu
results: parsing output: 24/ for test: kms_flip_scaled_crc
results: parsing output: 25/ for test: kms_chamelium_edid
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: kms_chamelium_audio
results: parsing output: 28/ for test: kms_async_flips
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: kms_properties
results: parsing output: 31/ for test: gem_softpin
results: parsing output: 32/ for test: gem_close_race
results: parsing output: 33/ for test: gem_userptr_blits
results: parsing output: 34/ for test: kms_flip
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: syncobj_basic
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_psr2_sf
results: parsing output: 42/ for test: kms_cursor_crc
results: parsing output: 43/ for test: kms_cursor_crc
results: parsing output: 44/ for test: gem_lmem_swapping
results: parsing output: 45/ for test: kms_flip
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_pipe_b_c_ivb
results: parsing output: 48/ for test: api_intel_allocator
results: parsing output: 49/ for test: gem_render_copy_redux
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: gem_ctx_persistence
results: parsing output: 52/ for test: sw_sync
results: parsing output: 53/ for test: gem_exec_reloc
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: gem_exec_balancer
results: parsing output: 57/ for test: kms_getfb
results: parsing output: 58/ for test: gem_exec_schedule
results: parsing output: 59/ for test: sysfs_timeslice_duration
results: parsing output: 60/ for test: kms_joiner
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: sriov_basic
results: parsing output: 64/ for test: gem_ctx_create
results: parsing output: 65/ for test: kms_ccs
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gen3_render_tiledx_blits
results: parsing output: 69/ for test: perf
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_plane_alpha_blend
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: kms_rotation_crc
results: parsing output: 74/ for test: i915_pm_sseu
results: parsing output: 75/ for test: kms_cursor_edge_walk
results: parsing output: 76/ for test: kms_lease
results: parsing output: 77/ for test: kms_pm_rpm
results: parsing output: 78/ for test: kms_psr2_sf
results: parsing output: 79/ for test: kms_plane_scaling
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_cursor_crc
results: parsing output: 84/ for test: gem_exec_fence
results: parsing output: 85/ for test: gem_ctx_persistence
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: syncobj_basic
results: parsing output: 88/ for test: kms_dp_aux_dev
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: gem_mmap_offset
results: parsing output: 91/ for test: device_reset
results: parsing output: 92/ for test: i915_query
results: parsing output: 93/ for test: i915_module_load
results: parsing output: 94/ for test: kms_vblank
results: parsing output: 95/ for test: gem_render_copy
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: gem_userptr_blits
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: gem_exec_balancer
results: parsing output: 101/ for test: kms_flip
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: gem_exec_reloc
results: parsing output: 105/ for test: gem_pxp
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: kms_color
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gen9_exec_parse
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: kms_selftest
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: syncobj_wait
results: parsing output: 117/ for test: gem_cs_tlb
results: parsing output: 118/ for test: kms_rotation_crc
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: kms_flip
results: parsing output: 123/ for test: gem_partial_pwrite_pread
results: parsing output: 124/ for test: gen7_exec_parse
results: parsing output: 125/ for test: kms_vblank
results: parsing output: 126/ for test: gem_sync
results: parsing output: 127/ for test: drm_fdinfo
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: sw_sync
results: parsing output: 130/ for test: kms_atomic_transition
Done.