[58.989352] Initializing watchdogs
[58.989426]   /dev/watchdog0
[59.003551] [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]
[59.007792] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.009891] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.013129] [001/133] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[59.129453] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[62.860987] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.735s)
[63.973098] [002/133] (955s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[64.073176] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[68.473552] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (4.404s)
[69.687134] [003/133] (949s left) kms_flip (flip-vs-panning-vs-hang)
[69.778957] Starting subtest: flip-vs-panning-vs-hang
[69.781837] Starting dynamic subtest: A-eDP1
[109.375062] Dynamic subtest A-eDP1: SUCCESS (39.596s)
[109.383651] Starting dynamic subtest: D-eDP1
[159.362212] Dynamic subtest D-eDP1: SUCCESS (49.984s)
[159.364570] Subtest flip-vs-panning-vs-hang: SUCCESS (89.584s)
[160.543353] [004/133] (859s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[160.643879] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[160.648572] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[160.743366] [005/133] (858s left) gem_exec_schedule (smoketest)
[160.931048] Starting subtest: smoketest
[160.933542] Starting dynamic subtest: rcs0
[166.488041] Dynamic subtest rcs0: SUCCESS (5.506s)
[166.496729] Starting dynamic subtest: bcs0
[172.002749] Dynamic subtest bcs0: SUCCESS (5.508s)
[172.016463] Starting dynamic subtest: vcs0
[177.495371] Dynamic subtest vcs0: SUCCESS (5.491s)
[177.506673] Starting dynamic subtest: vcs1
[182.974475] Dynamic subtest vcs1: SUCCESS (5.485s)
[182.983273] Starting dynamic subtest: vecs0
[188.477832] Dynamic subtest vecs0: SUCCESS (5.503s)
[188.492473] Starting dynamic subtest: ccs0
[193.972607] Dynamic subtest ccs0: SUCCESS (5.490s)
[193.974663] Subtest smoketest: SUCCESS (32.988s)
[194.103399] [006/133] (825s left) gem_ctx_bad_destroy (invalid-pad)
[194.155052] Starting subtest: invalid-pad
[194.157528] Subtest invalid-pad: SUCCESS (0.000s)
[194.226593] [007/133] (825s left) kms_frontbuffer_tracking (fbc-1p-rte)
[194.325811] Starting subtest: fbc-1p-rte
[199.812958] Subtest fbc-1p-rte: SUCCESS (5.492s)
[200.943323] [008/133] (818s left) kms_vblank (query-forked-busy-hang)
[201.049419] Starting subtest: query-forked-busy-hang
[201.051525] Starting dynamic subtest: pipe-A-eDP-1
[227.655778] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.606s)
[227.669881] Starting dynamic subtest: pipe-D-eDP-1
[253.646839] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.988s)
[253.649136] Subtest query-forked-busy-hang: SUCCESS (52.598s)
[254.479760] [009/133] (765s left) perf_pmu (frequency)
[254.533934] Starting subtest: frequency
[254.536793] Starting dynamic subtest: gt0
[256.646439] Dynamic subtest gt0: SUCCESS (2.106s)
[256.655688] Starting dynamic subtest: idle-gt0
[257.151261] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[257.160905] Starting dynamic subtest: gt1
[261.904311] Dynamic subtest gt1: SUCCESS (4.749s)
[261.916342] Starting dynamic subtest: idle-gt1
[262.425843] Dynamic subtest idle-gt1: SUCCESS (0.504s)
[262.428474] Subtest frequency: SUCCESS (7.875s)
[262.526865] [010/133] (757s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[262.637099] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[262.639581] Starting dynamic subtest: pipe-A-eDP-1
[269.163851] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.531s)
[269.178659] Starting dynamic subtest: pipe-B-eDP-1
[276.164912] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.998s)
[276.166803] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (13.530s)
[277.023899] [011/133] (742s left) kms_flip (dpms-vs-vblank-race)
[277.109871] Starting subtest: dpms-vs-vblank-race
[277.112823] Starting dynamic subtest: A-eDP1
[282.094424] Dynamic subtest A-eDP1: SUCCESS (4.982s)
[282.103894] Starting dynamic subtest: B-eDP1
[285.611085] Dynamic subtest B-eDP1: SUCCESS (3.515s)
[285.620479] Starting dynamic subtest: C-eDP1
[290.228822] Dynamic subtest C-eDP1: SUCCESS (4.617s)
[290.236878] Starting dynamic subtest: D-eDP1
[294.853676] Dynamic subtest D-eDP1: SUCCESS (4.622s)
[294.856094] Subtest dpms-vs-vblank-race: SUCCESS (17.745s)
[295.732932] [012/133] (724s left) perf_pmu (busy-idle)
[295.772728] Starting subtest: busy-idle
[295.775027] Starting dynamic subtest: rcs0
[296.458951] Dynamic subtest rcs0: SUCCESS (0.687s)
[296.469832] Starting dynamic subtest: bcs0
[297.122297] Dynamic subtest bcs0: SUCCESS (0.662s)
[297.133781] Starting dynamic subtest: vcs0
[298.900889] Dynamic subtest vcs0: SUCCESS (1.776s)
[298.911910] Starting dynamic subtest: vcs1
[300.884932] Dynamic subtest vcs1: SUCCESS (1.983s)
[300.895679] Starting dynamic subtest: vecs0
[302.933220] Dynamic subtest vecs0: SUCCESS (2.046s)
[302.942666] Starting dynamic subtest: ccs0
[303.602503] Dynamic subtest ccs0: SUCCESS (0.669s)
[303.605065] Subtest busy-idle: SUCCESS (7.832s)
[303.689231] [013/133] (716s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[303.803497] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[303.807544] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[303.905308] [014/133] (716s left) kms_plane_alpha_blend (alpha-basic)
[303.989640] Starting subtest: alpha-basic
[304.276669] Starting dynamic subtest: pipe-A-eDP-1
[306.200712] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.921s)
[306.223938] Starting dynamic subtest: pipe-D-eDP-1
[308.918422] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.701s)
[308.920759] Subtest alpha-basic: SUCCESS (4.931s)
[309.720887] [015/133] (710s left) kms_force_connector_basic (force-edid)
[309.797660] Starting subtest: force-edid
[310.166625] Subtest force-edid: SUCCESS (0.369s)
[310.305354] [016/133] (709s left) kms_prime (basic-modeset-hybrid)
[310.387523] Subtest basic-modeset-hybrid: SKIP (0.000s)
[310.549325] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.vgem: true
[310.553704] [017/133] (709s left) kms_flip (2x-flip-vs-fences-interruptible)
[310.645573] Starting subtest: 2x-flip-vs-fences-interruptible
[310.648991] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[310.749235] [018/133] (709s left) i915_pm_rps (engine-order)
[310.817713] Starting subtest: engine-order
[315.950113] Subtest engine-order: SUCCESS (5.130s)
[316.061538] [019/133] (704s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[316.171434] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[317.184294] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.012s)
[317.306005] [020/133] (702s left) dmabuf_sync_file (export-wait-after-attach)
[317.348837] Starting subtest: export-wait-after-attach
[317.350789] Subtest export-wait-after-attach: SUCCESS (0.001s)
[317.416083] [021/133] (702s left) kms_prop_blob (invalid-set-prop)
[317.465805] Starting subtest: invalid-set-prop
[317.468224] Subtest invalid-set-prop: SUCCESS (0.000s)
[317.559874] [022/133] (702s left) kms_psr (pr-cursor-blt)
[317.667281] Starting subtest: pr-cursor-blt
[317.670661] Subtest pr-cursor-blt: SKIP (0.000s)
[317.753294] [023/133] (702s left) gem_busy (extended)
[317.933544] Starting subtest: extended
[317.935849] Starting dynamic subtest: rcs0
[318.013995] Dynamic subtest rcs0: SUCCESS (0.082s)
[318.025255] Starting dynamic subtest: bcs0
[318.055826] Dynamic subtest bcs0: SUCCESS (0.041s)
[318.065496] Starting dynamic subtest: vcs0
[319.894856] Dynamic subtest vcs0: SUCCESS (1.835s)
[319.906623] Starting dynamic subtest: vcs1
[321.941488] Dynamic subtest vcs1: SUCCESS (2.044s)
[321.954485] Starting dynamic subtest: vecs0
[323.925078] Dynamic subtest vecs0: SUCCESS (1.982s)
[323.936994] Starting dynamic subtest: ccs0
[323.969973] Dynamic subtest ccs0: SUCCESS (0.045s)
[323.972484] Subtest extended: SUCCESS (6.040s)
[324.197462] [024/133] (696s left) gem_gpgpu_fill (basic)
[324.262796] Starting subtest: basic
[324.265277] Starting dynamic subtest: smem
[324.272829] Dynamic subtest smem: SUCCESS (0.010s)
[324.275121] Subtest basic: SUCCESS (0.011s)
[324.409039] [025/133] (696s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[324.514520] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[324.518683] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[324.607870] [026/133] (695s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[324.989838] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[325.817518] [027/133] (694s left) gem_request_retire (retire-vma-not-inactive)
[325.869586] Starting subtest: retire-vma-not-inactive
[325.933702] Subtest retire-vma-not-inactive: SUCCESS (0.061s)
[326.057845] [028/133] (694s left) gem_exec_whisper (basic-forked)
[326.127028] Starting subtest: basic-forked
[333.133543] Subtest basic-forked: SUCCESS (7.007s)
[333.318539] [029/133] (687s left) gem_lmem_swapping (verify-ccs)
[333.358761] Subtest verify-ccs: SKIP (0.000s)
[333.404079] [030/133] (687s left) kms_color (legacy-gamma)
[333.502479] Starting subtest: legacy-gamma
[333.504719] Starting dynamic subtest: pipe-A-eDP-1
[334.243993] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.741s)
[334.253631] Starting dynamic subtest: pipe-B-eDP-1
[335.619473] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.375s)
[335.628380] Starting dynamic subtest: pipe-C-eDP-1
[336.954340] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.334s)
[336.964224] Starting dynamic subtest: pipe-D-eDP-1
[338.252682] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.299s)
[338.255238] Subtest legacy-gamma: SUCCESS (4.752s)
[339.131996] [031/133] (681s left) gem_workarounds (suspend-resume-fd)
[339.204513] Starting subtest: suspend-resume-fd
[355.747305] Subtest suspend-resume-fd: SUCCESS (1.299s)
[355.890973] [032/133] (664s left) gem_ctx_param (invalid-size-set)
[355.956732] Starting subtest: invalid-size-set
[355.959156] Subtest invalid-size-set: SUCCESS (0.001s)
[356.070404] [033/133] (664s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[356.128473] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[356.233419] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.104s)
[356.314633] [034/133] (664s left) i915_hangman (gt-engine-hang)
[356.371857] Starting subtest: gt-engine-hang
[356.374183] Starting dynamic subtest: rcs0
[358.150922] Dynamic subtest rcs0: SUCCESS (1.775s)
[358.164351] Starting dynamic subtest: bcs0
[360.130181] Dynamic subtest bcs0: SUCCESS (1.979s)
[360.147950] Starting dynamic subtest: vcs0
[362.114354] Dynamic subtest vcs0: SUCCESS (1.981s)
[362.126579] Starting dynamic subtest: vcs1
[364.098289] Dynamic subtest vcs1: SUCCESS (1.981s)
[364.110682] Starting dynamic subtest: vecs0
[366.144060] Dynamic subtest vecs0: SUCCESS (2.044s)
[366.156281] Starting dynamic subtest: ccs0
[368.129984] Dynamic subtest ccs0: SUCCESS (1.983s)
[368.132850] Subtest gt-engine-hang: SUCCESS (11.755s)
[368.212765] [035/133] (652s left) gem_userptr_blits (create-destroy-unsync)
[368.285292] Subtest create-destroy-unsync: SKIP (0.000s)
[368.436235] [036/133] (652s left) kms_cursor_edge_walk (256x256-right-edge)
[368.527584] Starting subtest: 256x256-right-edge
[368.529865] Starting dynamic subtest: pipe-A-eDP-1
[371.971066] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.443s)
[371.983856] Starting dynamic subtest: pipe-D-eDP-1
[375.156926] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.184s)
[375.159362] Subtest 256x256-right-edge: SUCCESS (6.630s)
[376.031551] [037/133] (644s left) gem_exec_schedule (preempt-engines)
[376.108828] Starting subtest: preempt-engines
[376.111099] Starting dynamic subtest: rcs0
[378.396133] Dynamic subtest rcs0: SUCCESS (2.294s)
[378.411016] Starting dynamic subtest: bcs0
[380.616283] Dynamic subtest bcs0: SUCCESS (2.218s)
[380.625628] Starting dynamic subtest: vcs0
[382.907396] Dynamic subtest vcs0: SUCCESS (2.289s)
[382.919838] Starting dynamic subtest: vcs1
[385.251976] Dynamic subtest vcs1: SUCCESS (2.342s)
[385.262716] Starting dynamic subtest: vecs0
[387.582758] Dynamic subtest vecs0: SUCCESS (2.328s)
[387.593092] Starting dynamic subtest: ccs0
[389.886044] Dynamic subtest ccs0: SUCCESS (2.301s)
[389.888362] Subtest preempt-engines: SUCCESS (13.783s)
[390.241267] [038/133] (630s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[390.352615] Starting subtest: primary-y-tiled-reflect-x-90
[390.357187] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[390.424026] [039/133] (630s left) prime_mmap (test_forked_cpu_write)
[390.483127] Starting subtest: test_forked_cpu_write
[390.485140] Starting dynamic subtest: test_forked_cpu_write-smem
[390.490104] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.009s)
[390.492442] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[390.596023] [040/133] (630s left) kms_cursor_crc (cursor-offscreen-128x128)
[390.805057] Starting subtest: cursor-offscreen-128x128
[390.807296] Starting dynamic subtest: pipe-A-eDP-1
[392.741945] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.934s)
[392.775779] Starting dynamic subtest: pipe-D-eDP-1
[395.300819] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.538s)
[395.302941] Subtest cursor-offscreen-128x128: SUCCESS (4.496s)
[396.135682] [041/133] (624s left) kms_psr (psr-sprite-plane-onoff)
[396.242515] Starting subtest: psr-sprite-plane-onoff
[396.244722] Starting dynamic subtest: eDP-1
[398.042983] Dynamic subtest eDP-1: SUCCESS (1.798s)
[398.045302] Subtest psr-sprite-plane-onoff: SUCCESS (1.799s)
[398.839383] [042/133] (622s left) gem_set_tiling_vs_pwrite
[398.972907] [043/133] (622s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[399.086645] Starting subtest: protected-encrypted-src-copy-not-readible
[399.197012] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.112s)
[399.321954] [044/133] (621s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[399.365484] Starting subtest: multi-wait-for-submit-available-signaled
[399.367724] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[399.462079] [045/133] (621s left) kms_async_flips (async-flip-dpms)
[399.550505] Starting subtest: async-flip-dpms
[399.924978] Starting dynamic subtest: pipe-A-eDP-1
[403.788727] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.862s)
[404.167400] Starting dynamic subtest: pipe-B-eDP-1
[407.073632] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.913s)
[407.456333] Starting dynamic subtest: pipe-C-eDP-1
[410.278737] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.828s)
[410.653132] Starting dynamic subtest: pipe-D-eDP-1
[413.471860] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.827s)
[413.474365] Subtest async-flip-dpms: SUCCESS (13.922s)
[414.629774] [046/133] (606s left) kms_psr (fbc-psr2-cursor-blt)
[414.727996] Starting subtest: fbc-psr2-cursor-blt
[414.730278] Starting dynamic subtest: eDP-1
[414.734672] Dynamic subtest eDP-1: SKIP (0.000s)
[414.739045] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[414.813615] [047/133] (606s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[414.927107] Starting subtest: yf-tiled-64bpp-rotate-270
[414.931624] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[415.037589] [048/133] (606s left) gem_close_race (multigpu-basic-process)
[415.113530] Starting subtest: multigpu-basic-process
[415.118173] Subtest multigpu-basic-process: SKIP (0.000s)
[415.358953] [049/133] (606s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[415.460233] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[415.462560] Starting dynamic subtest: pipe-A-eDP-1
[415.485216] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[415.496922] Starting dynamic subtest: pipe-B-eDP-1
[415.519204] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[415.527734] Starting dynamic subtest: pipe-C-eDP-1
[415.549319] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[415.560711] Starting dynamic subtest: pipe-D-eDP-1
[415.581904] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[415.585848] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[417.742880] [050/133] (603s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[417.845786] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[425.153912] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.308s)
[425.277620] [051/133] (596s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[425.821243] Starting subtest: syncobj-timeline-invalid-flags
[425.823254] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[427.245164] [052/133] (594s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[427.343880] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[427.346134] Starting dynamic subtest: pipe-A
[427.364364] Dynamic subtest pipe-A: SKIP (0.017s)
[427.373667] Starting dynamic subtest: pipe-B
[427.394747] Dynamic subtest pipe-B: SKIP (0.029s)
[427.404884] Starting dynamic subtest: pipe-C
[427.423087] Dynamic subtest pipe-C: SKIP (0.029s)
[427.432488] Starting dynamic subtest: pipe-D
[427.453418] Dynamic subtest pipe-D: SKIP (0.029s)
[427.457554] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.106s)
[427.541610] [053/133] (594s left) gem_userptr_blits (coherency-unsync)
[427.613640] Subtest coherency-unsync: SKIP (0.000s)
[427.745478] [054/133] (593s left) kms_pwrite_crc
[429.340485] [055/133] (592s left) gem_linear_blits (basic)
[429.793128] Starting subtest: basic
[429.802111] Subtest basic: SUCCESS (0.009s)
[429.954073] [056/133] (591s left) gem_ctx_persistence (engines-hostile)
[430.069910] Starting subtest: engines-hostile
[430.071846] Starting dynamic subtest: rcs0
[430.156700] Dynamic subtest rcs0: SUCCESS (0.089s)
[430.170069] Starting dynamic subtest: bcs0
[430.253883] Dynamic subtest bcs0: SUCCESS (0.095s)
[430.268139] Starting dynamic subtest: vcs0
[430.355630] Dynamic subtest vcs0: SUCCESS (0.100s)
[430.367964] Starting dynamic subtest: vcs1
[430.444707] Dynamic subtest vcs1: SUCCESS (0.087s)
[430.456350] Starting dynamic subtest: vecs0
[430.544116] Dynamic subtest vecs0: SUCCESS (0.097s)
[430.557732] Starting dynamic subtest: ccs0
[430.637470] Dynamic subtest ccs0: SUCCESS (0.092s)
[430.639918] Subtest engines-hostile: SUCCESS (0.571s)
[430.720045] [057/133] (591s left) syncobj_wait (reset-unsignaled)
[430.782709] Starting subtest: reset-unsignaled
[430.784731] Subtest reset-unsignaled: SUCCESS (0.000s)
[430.893676] [058/133] (590s left) gem_workarounds (basic-read-fd)
[430.960796] Starting subtest: basic-read-fd
[430.972007] Subtest basic-read-fd: SUCCESS (0.011s)
[431.108848] [059/133] (590s left) gem_exec_balancer (nohangcheck)
[431.364181] Starting subtest: nohangcheck
[432.249961] Subtest nohangcheck: SUCCESS (0.885s)
[432.368777] [060/133] (589s left) gem_userptr_blits (probe)
[432.443836] Starting subtest: probe
[432.518519] Subtest probe: SUCCESS (0.077s)
[432.626894] [061/133] (589s left) gem_eio (wait-immediate)
[432.854527] Starting subtest: wait-immediate
[433.252322] Subtest wait-immediate: SUCCESS (0.398s)
[433.423195] [062/133] (588s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[433.527672] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[433.531101] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[433.647643] [063/133] (588s left) syncobj_timeline (etime-multi-wait-all-submitted)
[433.701071] Starting subtest: etime-multi-wait-all-submitted
[433.807217] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s)
[433.883568] [064/133] (588s left) gem_mmap_offset (blt-coherency)
[433.937775] Starting subtest: blt-coherency
[433.943061] Subtest blt-coherency: SUCCESS (0.007s)
[434.063898] [065/133] (588s left) gem_exec_params (invalid-bsd-ring)
[434.134027] Starting subtest: invalid-bsd-ring
[434.136235] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[434.257800] [066/133] (587s left) gem_softpin (full)
[434.327930] Starting subtest: full
[434.333431] Subtest full: SUCCESS (0.008s)
[434.467534] [067/133] (587s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[434.596886] Starting subtest: cursor-vs-flip-atomic-transitions
[464.607359] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.008s)
[465.436775] [068/133] (556s left) kms_getfb (getfb-addfb-different-handles)
[465.521926] Starting subtest: getfb-addfb-different-handles
[465.523764] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[465.618438] [069/133] (556s left) kms_content_protection (suspend-resume)
[465.754636] Starting subtest: suspend-resume
[465.759388] Subtest suspend-resume: SKIP (0.000s)
[465.895778] [070/133] (556s left) gen7_exec_parse (cmd-crossing-page)
[465.964664] Subtest cmd-crossing-page: SKIP (0.000s)
[466.033011] [071/133] (556s left) prime_mmap_coherency (write)
[466.102746] Starting subtest: write
[471.481267] Subtest write: SUCCESS (5.378s)
[471.632734] [072/133] (550s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[471.738729] Starting subtest: bad-aux-stride-y-tiled-ccs
[471.741045] Starting dynamic subtest: pipe-A-eDP-1
[471.764719] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[471.778033] Starting dynamic subtest: pipe-B-eDP-1
[471.800157] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[471.809775] Starting dynamic subtest: pipe-C-eDP-1
[471.831536] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[471.841264] Starting dynamic subtest: pipe-D-eDP-1
[471.863234] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[471.867334] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.005s)
[474.031935] [073/133] (548s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[474.119696] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[481.047881] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (6.931s)
[481.147899] [074/133] (541s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[481.340622] Starting subtest: cursor-rapid-movement-64x21
[481.387166] Subtest cursor-rapid-movement-64x21: SKIP (0.042s)
[481.645605] [075/133] (540s left) gem_ctx_param (root-set)
[481.708929] Starting subtest: root-set
[481.711417] Subtest root-set: SUCCESS (0.001s)
[481.834715] [076/133] (540s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[481.921880] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[481.926404] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[481.991400] [077/133] (540s left) perf_pmu (invalid-init)
[482.035726] Starting subtest: invalid-init
[482.037707] Subtest invalid-init: SUCCESS (0.000s)
[482.139839] [078/133] (540s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[482.247302] Starting subtest: 4-tiled-8bpp-rotate-90
[482.268520] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.020s)
[482.370523] [079/133] (540s left) gem_exec_balancer (waits)
[482.441238] Starting subtest: waits
[488.986786] Subtest waits: SUCCESS (6.543s)
[489.292987] [080/133] (533s left) prime_busy (hang)
[489.361890] Starting subtest: hang
[489.364140] Starting dynamic subtest: rcs0
[515.646577] Dynamic subtest rcs0: SUCCESS (26.280s)
[515.656442] Starting dynamic subtest: bcs0
[522.803282] Dynamic subtest bcs0: SUCCESS (7.154s)
[522.813008] Starting dynamic subtest: vcs0
[529.839511] Dynamic subtest vcs0: SUCCESS (7.035s)
[529.848897] Starting dynamic subtest: vcs1
[536.813438] Dynamic subtest vcs1: SUCCESS (6.974s)
[536.825670] Starting dynamic subtest: vecs0
[543.784584] Dynamic subtest vecs0: SUCCESS (6.967s)
[543.796405] Starting dynamic subtest: ccs0
[557.696510] Dynamic subtest ccs0: SUCCESS (13.910s)
[557.698910] Subtest hang: SUCCESS (68.330s)
[557.815573] [081/133] (464s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[557.920337] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[557.946733] Starting dynamic subtest: pipe-A-plane-0
[558.235249] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[558.245454] Starting dynamic subtest: pipe-A-plane-3
[558.479999] Dynamic subtest pipe-A-plane-3: SUCCESS (0.244s)
[559.577448] Starting dynamic subtest: pipe-B-plane-0
[559.861733] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[559.871847] Starting dynamic subtest: pipe-B-plane-3
[560.106051] Dynamic subtest pipe-B-plane-3: SUCCESS (0.242s)
[560.430387] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.510s)
[561.243804] [082/133] (461s left) kms_dp_link_training (non-uhbr-sst)
[561.325817] Starting subtest: non-uhbr-sst
[561.334917] Subtest non-uhbr-sst: SKIP (0.000s)
[561.401099] [083/133] (461s left) kms_chamelium_edid (dp-edid-read)
[561.789279] Subtest dp-edid-read: SKIP (0.000s)
[562.601696] [084/133] (460s left) kms_flip (flip-vs-fences)
[562.705407] Starting subtest: flip-vs-fences
[562.709712] Subtest flip-vs-fences: SKIP (0.000s)
[562.818289] [085/133] (460s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[562.915497] Starting subtest: psr2-cursor-plane-move-continuous-sf
[562.918026] Starting dynamic subtest: pipe-A-eDP-1
[573.267120] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.352s)
[573.278731] Starting dynamic subtest: pipe-B-eDP-1
[584.355286] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.088s)
[584.357613] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.443s)
[585.211710] [086/133] (437s left) gem_exec_reloc (basic-write-read-active)
[585.278964] Subtest basic-write-read-active: SKIP (0.000s)
[585.363988] [087/133] (437s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[585.454143] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[585.456473] Starting dynamic subtest: pipe-A-eDP-1
[585.516219] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[585.527690] Starting dynamic subtest: pipe-B-eDP-1
[585.585909] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[585.597400] Starting dynamic subtest: pipe-C-eDP-1
[585.655414] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[585.669478] Starting dynamic subtest: pipe-D-eDP-1
[585.726260] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[585.730384] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.009s)
[587.718103] [088/133] (435s left) api_intel_allocator (reopen)
[587.771715] Starting subtest: reopen
[587.774080] Subtest reopen: SUCCESS (0.004s)
[587.916530] [089/133] (435s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[588.023151] Starting subtest: yf-tiled-32bpp-rotate-180
[588.027594] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[588.125346] [090/133] (435s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[588.228608] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[588.233013] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[588.341011] [091/133] (434s left) kms_plane_multiple (tiling-y)
[588.436760] Starting subtest: tiling-y
[588.440978] Subtest tiling-y: SKIP (0.000s)
[588.512836] [092/133] (434s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[588.621625] Starting subtest: plane-downscale-factor-0-5-with-rotation
[588.623936] Starting dynamic subtest: pipe-A
[588.643099] Dynamic subtest pipe-A: SKIP (0.016s)
[588.654565] Starting dynamic subtest: pipe-B
[588.669596] Dynamic subtest pipe-B: SKIP (0.028s)
[588.679159] Starting dynamic subtest: pipe-C
[588.699936] Dynamic subtest pipe-C: SKIP (0.028s)
[588.709496] Starting dynamic subtest: pipe-D
[588.727200] Dynamic subtest pipe-D: SKIP (0.029s)
[588.730814] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.104s)
[588.845860] [093/133] (434s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[588.936959] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[588.938902] Starting dynamic subtest: pipe-A-eDP-1
[588.957368] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[588.970728] Starting dynamic subtest: pipe-B-eDP-1
[588.988479] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[588.998393] Starting dynamic subtest: pipe-C-eDP-1
[589.015506] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[589.024326] Starting dynamic subtest: pipe-D-eDP-1
[589.042346] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[589.045880] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[591.215683] [094/133] (432s left) perf (gen12-oa-tlb-invalidate)
[591.377612] Starting subtest: gen12-oa-tlb-invalidate
[591.379885] Starting dynamic subtest: 0-rcs0
[601.431390] Dynamic subtest 0-rcs0: SUCCESS (10.053s)
[601.441713] Starting dynamic subtest: 1-vcs0
[611.477698] Dynamic subtest 1-vcs0: SUCCESS (10.044s)
[611.480283] Subtest gen12-oa-tlb-invalidate: SUCCESS (20.099s)
[611.518476] [095/133] (411s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[611.615500] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[611.619561] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[611.725695] [096/133] (411s left) kms_psr (psr2-cursor-render)
[611.825861] Starting subtest: psr2-cursor-render
[611.828046] Starting dynamic subtest: eDP-1
[613.555712] Dynamic subtest eDP-1: SUCCESS (1.730s)
[613.558102] Subtest psr2-cursor-render: SUCCESS (1.731s)
[614.413823] [097/133] (409s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[614.515631] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[614.519995] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[614.628956] [098/133] (408s left) syncobj_wait (single-wait-all-submitted)
[614.690922] Starting subtest: single-wait-all-submitted
[614.800040] Subtest single-wait-all-submitted: SUCCESS (0.108s)
[614.892199] [099/133] (408s left) gem_exec_gttfill (multigpu-basic)
[615.069690] Starting subtest: multigpu-basic
[628.306823] Subtest multigpu-basic: SUCCESS (13.240s)
[628.364766] [100/133] (395s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[628.499290] Starting subtest: y-tiled-8bpp-rotate-90
[628.503872] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[628.585107] [101/133] (395s left) kms_cdclk (mode-transition)
[628.683953] Starting subtest: mode-transition
[628.686232] Starting dynamic subtest: pipe-A-eDP-1
[628.973919] Dynamic subtest pipe-A-eDP-1: SKIP (0.285s)
[628.984589] Starting dynamic subtest: pipe-B-eDP-1
[628.989207] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[628.999268] Starting dynamic subtest: pipe-C-eDP-1
[629.004168] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[629.013974] Starting dynamic subtest: pipe-D-eDP-1
[629.018389] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[629.022781] Subtest mode-transition: SKIP (0.293s)
[629.820735] [102/133] (393s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[629.923505] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[636.409898] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.488s)
[636.537034] [103/133] (387s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[636.636555] Starting subtest: x-tiled-32bpp-rotate-270
[636.655726] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[636.767862] [104/133] (387s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[636.867981] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[636.872314] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[636.953671] [105/133] (386s left) kms_joiner (basic-max-non-joiner)
[637.055165] Starting subtest: basic-max-non-joiner
[637.059608] Subtest basic-max-non-joiner: SKIP (0.000s)
[637.121062] [106/133] (386s left) gen9_exec_parse (shadow-peek)
[637.177723] Subtest shadow-peek: SKIP (0.000s)
[637.242467] [107/133] (386s left) kms_lease (possible-crtcs-filtering)
[637.339341] Starting subtest: possible-crtcs-filtering
[637.344356] Subtest possible-crtcs-filtering: SUCCESS (0.005s)
[637.457752] [108/133] (386s left) sysfs_heartbeat_interval (nopreempt)
[637.509770] Starting subtest: nopreempt
[637.511693] Starting dynamic subtest: vecs0
[647.148872] Dynamic subtest vecs0: SUCCESS (9.634s)
[647.160209] Starting dynamic subtest: ccs0
[651.144676] Dynamic subtest ccs0: SUCCESS (3.995s)
[651.156920] Starting dynamic subtest: vcs0
[661.152867] Dynamic subtest vcs0: SUCCESS (10.005s)
[661.169464] Starting dynamic subtest: bcs0
[665.117706] Dynamic subtest bcs0: SUCCESS (3.965s)
[665.129823] Starting dynamic subtest: rcs0
[669.134733] Dynamic subtest rcs0: SUCCESS (4.014s)
[669.147516] Starting dynamic subtest: vcs1
[679.136099] Dynamic subtest vcs1: SUCCESS (9.997s)
[679.138617] Subtest nopreempt: SUCCESS (41.622s)
[679.224906] [109/133] (344s left) gem_ctx_param (invalid-ctx-get)
[679.274499] Starting subtest: invalid-ctx-get
[679.276957] Subtest invalid-ctx-get: SUCCESS (0.000s)
[679.370893] [110/133] (344s left) i915_selftest (perf)
[682.700695] Starting subtest: perf
[682.703628] Starting dynamic subtest: engine_cs
[687.081059] Dynamic subtest engine_cs: SUCCESS (4.379s)
[687.091454] Starting dynamic subtest: request
[694.251403] Dynamic subtest request: SUCCESS (7.169s)
[694.262693] Starting dynamic subtest: migrate
[698.559973] Dynamic subtest migrate: SUCCESS (4.307s)
[698.570975] Starting dynamic subtest: region
[704.332550] Dynamic subtest region: SUCCESS (5.771s)
[704.335087] Subtest perf: SUCCESS (21.632s)
[704.364399] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[704.365211] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[704.368494] [111/133] (319s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[705.934964] Starting subtest: 4-tiled-16bpp-rotate-0
[707.217098] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.283s)
[707.333397] [FACT kms_big_fb (4-tiled-16bpp-rotate-0)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[707.334626] [FACT kms_big_fb (4-tiled-16bpp-rotate-0)] new: kernel.kmod_is_loaded.i915: true
[707.338358] [112/133] (316s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[707.435204] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[714.954050] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.522s)
[715.124842] [113/133] (309s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[715.218098] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[715.222033] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[715.323717] [114/133] (308s left) kms_addfb_basic (bad-pitch-1024)
[715.374683] Starting subtest: bad-pitch-1024
[715.377115] Subtest bad-pitch-1024: SUCCESS (0.000s)
[715.499991] [115/133] (308s left) gem_exec_reloc (basic-cpu-gtt-active)
[715.555420] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[715.623203] [116/133] (308s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[715.722366] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[719.712242] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (3.993s)
[720.909971] [117/133] (303s left) kms_draw_crc (draw-method-mmap-cpu)
[721.006049] Starting subtest: draw-method-mmap-cpu
[721.007989] Starting dynamic subtest: xrgb8888-untiled
[721.235890] Dynamic subtest xrgb8888-untiled: SUCCESS (0.232s)
[721.245561] Starting dynamic subtest: rgb565-untiled
[721.447247] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[721.457431] Starting dynamic subtest: xrgb2101010-untiled
[721.658314] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[721.669396] Starting dynamic subtest: xbgr16161616f-untiled
[721.857764] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.199s)
[721.867579] Starting dynamic subtest: xrgb8888-xtiled
[721.991106] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[722.000578] Starting dynamic subtest: rgb565-xtiled
[722.113481] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[722.124918] Starting dynamic subtest: xrgb2101010-xtiled
[722.235690] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.120s)
[722.244937] Starting dynamic subtest: xbgr16161616f-xtiled
[722.368638] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[722.378729] Starting dynamic subtest: xrgb8888-4tiled
[722.513462] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[722.522762] Starting dynamic subtest: rgb565-4tiled
[722.646756] Dynamic subtest rgb565-4tiled: SUCCESS (0.132s)
[722.658243] Starting dynamic subtest: xrgb2101010-4tiled
[722.780154] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.131s)
[722.791725] Starting dynamic subtest: xbgr16161616f-4tiled
[722.924512] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.142s)
[722.926340] Subtest draw-method-mmap-cpu: SUCCESS (1.922s)
[723.020106] [118/133] (301s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[725.264427] Starting subtest: dpms-mode-unset-lpsp
[729.310572] Subtest dpms-mode-unset-lpsp: SUCCESS (4.046s)
[729.702587] [119/133] (294s left) vgem_basic (dmabuf-export)
[729.752662] Starting subtest: dmabuf-export
[729.775213] Subtest dmabuf-export: SUCCESS (0.026s)
[729.869389] [120/133] (294s left) kms_sequence (get-busy)
[729.962803] Starting subtest: get-busy
[729.964741] Starting dynamic subtest: pipe-A-eDP-1
[731.906679] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.943s)
[731.917708] Starting dynamic subtest: pipe-B-eDP-1
[734.488887] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.583s)
[734.500117] Starting dynamic subtest: pipe-C-eDP-1
[737.098699] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.608s)
[737.112185] Starting dynamic subtest: pipe-D-eDP-1
[739.640751] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.540s)
[739.643084] Subtest get-busy: SUCCESS (9.680s)
[740.442030] [121/133] (284s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[740.513438] Starting subtest: yf-tiled-to-vebox-x-tiled
[740.518089] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[740.702135] [122/133] (283s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[740.766826] Starting subtest: basic-batch-kernel-default-cmd
[740.771310] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[740.953046] [123/133] (283s left) syncobj_timeline (single-wait-all-available-submitted)
[741.020029] Starting subtest: single-wait-all-available-submitted
[741.022389] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[741.128758] [124/133] (283s left) gem_ctx_persistence (file)
[741.207187] Starting subtest: file
[741.338452] Subtest file: SUCCESS (0.131s)
[741.470420] [125/133] (283s left) gem_pread (exhaustion)
[741.533461] Subtest exhaustion: SKIP (0.000s)
[741.591803] [126/133] (283s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[741.675006] Starting subtest: fbc-psr2-sprite-plane-onoff
[741.677391] Starting dynamic subtest: eDP-1
[741.681880] Dynamic subtest eDP-1: SKIP (0.000s)
[741.686312] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[741.755283] [127/133] (283s left) api_intel_bb (render)
[741.813341] Starting subtest: render
[741.815782] Starting dynamic subtest: render-none-512
[741.909674] Dynamic subtest render-none-512: SUCCESS (0.098s)
[741.921479] Starting dynamic subtest: render-none-1024
[742.288718] Dynamic subtest render-none-1024: SUCCESS (0.376s)
[742.301926] Starting dynamic subtest: render-x-512
[742.374950] Dynamic subtest render-x-512: SUCCESS (0.087s)
[742.386503] Starting dynamic subtest: render-x-1024
[742.718315] Dynamic subtest render-x-1024: SUCCESS (0.342s)
[742.729938] Starting dynamic subtest: render-y-512
[742.861869] Dynamic subtest render-y-512: SUCCESS (0.141s)
[742.873684] Starting dynamic subtest: render-y-1024
[743.439249] Dynamic subtest render-y-1024: SUCCESS (0.576s)
[743.441746] Subtest render: SUCCESS (1.628s)
[743.566254] [128/133] (281s left) gem_ctx_shared (q-promotion)
[743.634455] Starting subtest: Q-promotion
[743.641325] Starting dynamic subtest: rcs0
[743.730274] Dynamic subtest rcs0: SUCCESS (0.098s)
[743.748187] Starting dynamic subtest: vcs0
[743.816002] Dynamic subtest vcs0: SUCCESS (0.085s)
[743.833842] Starting dynamic subtest: vcs1
[743.892037] Dynamic subtest vcs1: SUCCESS (0.073s)
[743.909592] Starting dynamic subtest: vecs0
[743.965635] Dynamic subtest vecs0: SUCCESS (0.073s)
[743.982993] Starting dynamic subtest: ccs0
[744.058385] Dynamic subtest ccs0: SUCCESS (0.090s)
[744.060791] Subtest Q-promotion: SUCCESS (0.427s)
[745.273570] [129/133] (279s left) syncobj_timeline (invalid-signal-bad-pad)
[745.330225] Starting subtest: invalid-signal-bad-pad
[745.332665] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[745.434473] [130/133] (279s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[745.529146] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[745.533614] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[745.628235] [131/133] (279s left) gem_tiled_partial_pwrite_pread (writes)
[745.685586] Subtest writes: SKIP (0.000s)
[745.746579] [132/133] (279s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[745.798814] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[745.801180] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[745.893601] [133/133] (279s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[746.001004] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[752.806739] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.808s)
[752.928772] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_flip
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: gem_ctx_bad_destroy
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_vblank
results: parsing output: 8/ for test: perf_pmu
results: parsing output: 9/ for test: kms_psr2_sf
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: perf_pmu
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_plane_alpha_blend
results: parsing output: 14/ for test: kms_force_connector_basic
results: parsing output: 15/ for test: kms_prime
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: i915_pm_rps
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: dmabuf_sync_file
results: parsing output: 20/ for test: kms_prop_blob
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: gem_busy
results: parsing output: 23/ for test: gem_gpgpu_fill
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_chamelium_hpd
results: parsing output: 26/ for test: gem_request_retire
results: parsing output: 27/ for test: gem_exec_whisper
results: parsing output: 28/ for test: gem_lmem_swapping
results: parsing output: 29/ for test: kms_color
results: parsing output: 30/ for test: gem_workarounds
results: parsing output: 31/ for test: gem_ctx_param
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: i915_hangman
results: parsing output: 34/ for test: gem_userptr_blits
results: parsing output: 35/ for test: kms_cursor_edge_walk
results: parsing output: 36/ for test: gem_exec_schedule
results: parsing output: 37/ for test: kms_rotation_crc
results: parsing output: 38/ for test: prime_mmap
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 42/ for test: gem_pxp
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: kms_async_flips
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_close_race
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: kms_plane_scaling
results: parsing output: 52/ for test: gem_userptr_blits
results: parsing output: 53/ for test: kms_pwrite_crc
results: parsing output: 54/ for test: gem_linear_blits
results: parsing output: 55/ for test: gem_ctx_persistence
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: gem_workarounds
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: gem_userptr_blits
results: parsing output: 60/ for test: gem_eio
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: gem_mmap_offset
results: parsing output: 64/ for test: gem_exec_params
results: parsing output: 65/ for test: gem_softpin
results: parsing output: 66/ for test: kms_cursor_legacy
results: parsing output: 67/ for test: kms_getfb
results: parsing output: 68/ for test: kms_content_protection
results: parsing output: 69/ for test: gen7_exec_parse
results: parsing output: 70/ for test: prime_mmap_coherency
results: parsing output: 71/ for test: kms_ccs
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_cursor_crc
results: parsing output: 74/ for test: gem_ctx_param
results: parsing output: 75/ for test: kms_flip_scaled_crc
results: parsing output: 76/ for test: perf_pmu
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: gem_exec_balancer
results: parsing output: 79/ for test: prime_busy
results: parsing output: 80/ for test: kms_plane
results: parsing output: 81/ for test: kms_dp_link_training
results: parsing output: 82/ for test: kms_chamelium_edid
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_psr2_sf
results: parsing output: 85/ for test: gem_exec_reloc
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: api_intel_allocator
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_plane_multiple
results: parsing output: 91/ for test: kms_plane_scaling
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: perf
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_psr
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: gem_exec_gttfill
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: kms_cdclk
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: kms_flip_scaled_crc
results: parsing output: 104/ for test: kms_joiner
results: parsing output: 105/ for test: gen9_exec_parse
results: parsing output: 106/ for test: kms_lease
results: parsing output: 107/ for test: sysfs_heartbeat_interval
results: parsing output: 108/ for test: gem_ctx_param
results: parsing output: 109/ for test: i915_selftest
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_addfb_basic
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_draw_crc
results: parsing output: 117/ for test: kms_pm_rpm
results: parsing output: 118/ for test: vgem_basic
results: parsing output: 119/ for test: kms_sequence
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: gem_exec_flush
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: gem_ctx_persistence
results: parsing output: 124/ for test: gem_pread
results: parsing output: 125/ for test: kms_psr
results: parsing output: 126/ for test: api_intel_bb
results: parsing output: 127/ for test: gem_ctx_shared
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 131/ for test: syncobj_wait
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.