[63.380723] Initializing watchdogs
[63.380859]   /dev/watchdog0
[63.395692] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[63.399946] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.402154] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.405557] [001/133] (960s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[63.519097] Starting subtest: 2x-flip-vs-modeset-vs-hang
[63.529883] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.010s)
[63.616814] [002/133] (960s left) kms_invalid_mode (zero-hdisplay)
[63.705438] Starting subtest: zero-hdisplay
[63.707786] Starting dynamic subtest: pipe-A-eDP-1
[63.990484] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[64.005009] Starting dynamic subtest: pipe-B-eDP-1
[64.007651] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[64.020172] Starting dynamic subtest: pipe-C-eDP-1
[64.022500] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[64.029684] Starting dynamic subtest: pipe-D-eDP-1
[64.032107] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[64.034416] Subtest zero-hdisplay: SUCCESS (0.291s)
[64.858122] [003/133] (959s left) kms_plane_lowres (tiling-none)
[64.942850] Starting subtest: tiling-none
[64.945260] Starting dynamic subtest: pipe-A-eDP-1
[64.949979] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[65.237329] Starting dynamic subtest: pipe-B-eDP-1
[65.242100] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[65.249032] Starting dynamic subtest: pipe-C-eDP-1
[65.253769] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[65.260650] Starting dynamic subtest: pipe-D-eDP-1
[65.265347] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[65.269915] Subtest tiling-none: SKIP (0.295s)
[66.069293] [004/133] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[66.179144] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[66.183167] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[66.262299] [005/133] (957s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[66.370274] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[70.500601] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.137s)
[71.665598] [006/133] (952s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[71.770644] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[71.775094] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[71.857215] [007/133] (952s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[71.906308] Starting subtest: single-wait-all-for-submit-available-submitted
[71.908599] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[71.998653] [008/133] (952s left) gem_readwrite (read-bad-handle)
[72.051145] Subtest read-bad-handle: SKIP (0.000s)
[72.104573] [009/133] (952s left) api_intel_allocator (fork-simple-stress)
[72.164204] Starting subtest: fork-simple-stress
[77.558108] Subtest fork-simple-stress: SUCCESS (5.397s)
[77.671236] [010/133] (946s left) gem_wait (await)
[77.744096] Starting subtest: await
[77.746941] Starting dynamic subtest: all-engines
[78.881761] Dynamic subtest all-engines: SUCCESS (1.137s)
[78.898354] Starting dynamic subtest: rcs0
[79.941918] Dynamic subtest rcs0: SUCCESS (1.059s)
[79.957652] Starting dynamic subtest: bcs0
[81.000122] Dynamic subtest bcs0: SUCCESS (1.057s)
[81.015548] Starting dynamic subtest: vcs0
[82.056221] Dynamic subtest vcs0: SUCCESS (1.055s)
[82.070302] Starting dynamic subtest: vcs1
[83.109048] Dynamic subtest vcs1: SUCCESS (1.052s)
[83.124583] Starting dynamic subtest: vecs0
[84.161758] Dynamic subtest vecs0: SUCCESS (1.051s)
[84.176601] Starting dynamic subtest: ccs0
[85.219321] Dynamic subtest ccs0: SUCCESS (1.056s)
[85.221879] Subtest await: SUCCESS (7.473s)
[85.409860] [FACT gem_wait (await)] new: kernel.kmod_is_loaded.vgem: true
[85.413049] [011/133] (938s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[85.515795] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[85.518142] Starting dynamic subtest: pipe-A
[85.534806] Dynamic subtest pipe-A: SKIP (0.016s)
[85.543453] Starting dynamic subtest: pipe-B
[85.568581] Dynamic subtest pipe-B: SKIP (0.033s)
[85.576329] Starting dynamic subtest: pipe-C
[85.601843] Dynamic subtest pipe-C: SKIP (0.033s)
[85.609171] Starting dynamic subtest: pipe-D
[85.634062] Dynamic subtest pipe-D: SKIP (0.033s)
[85.638215] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.116s)
[85.727650] [012/133] (938s left) kms_prop_blob (blob-prop-validate)
[85.782829] Starting subtest: blob-prop-validate
[85.784846] Subtest blob-prop-validate: SUCCESS (0.000s)
[85.875116] [013/133] (938s left) kms_big_fb (linear-8bpp-rotate-270)
[85.971386] Starting subtest: linear-8bpp-rotate-270
[85.991928] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[86.102859] [014/133] (938s left) kms_pm_rpm (pm-caching)
[88.073649] Starting subtest: pm-caching
[88.078913] Subtest pm-caching: SKIP (0.000s)
[88.440191] [015/133] (936s left) gem_mmap_gtt (basic)
[88.499227] Subtest basic: SKIP (0.000s)
[88.555166] [016/133] (935s left) gem_mmap_offset (close-race)
[88.613415] Starting subtest: close-race
[108.727558] Subtest close-race: SUCCESS (20.112s)
[108.871098] [017/133] (915s left) gen9_exec_parse (basic-rejected)
[108.939603] Subtest basic-rejected: SKIP (0.000s)
[109.017770] [018/133] (915s left) kms_lease (lease-revoke)
[109.111294] Starting subtest: lease-revoke
[109.113663] Starting dynamic subtest: pipe-A-eDP-1
[109.142594] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.029s)
[109.150703] Starting dynamic subtest: pipe-B-eDP-1
[109.166277] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.024s)
[109.175178] Starting dynamic subtest: pipe-C-eDP-1
[109.193218] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.026s)
[109.201795] Starting dynamic subtest: pipe-D-eDP-1
[109.220428] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.026s)
[109.222665] Subtest lease-revoke: SUCCESS (0.109s)
[109.343221] [019/133] (915s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[109.451928] Starting subtest: fbcpsr-modesetfrombusy
[115.613041] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.167s)
[115.753381] [020/133] (908s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[115.839997] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[115.867173] Starting dynamic subtest: pipe-A-plane-0
[116.156078] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[116.166179] Starting dynamic subtest: pipe-A-plane-3
[116.389295] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[117.476873] Starting dynamic subtest: pipe-B-plane-0
[117.757447] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[117.766577] Starting dynamic subtest: pipe-B-plane-3
[117.990854] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[118.298734] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.458s)
[119.141306] [021/133] (905s left) gem_workarounds (suspend-resume)
[119.200466] Starting subtest: suspend-resume
[135.109699] Subtest suspend-resume: SUCCESS (1.372s)
[135.206786] [022/133] (889s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[135.322731] Starting subtest: yf-tiled-addfb-size-offset-overflow
[135.327016] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[135.415020] [023/133] (889s left) i915_suspend (sysfs-reader)
[135.471501] Starting subtest: sysfs-reader
[154.017291] Subtest sysfs-reader: SUCCESS (3.158s)
[154.108107] [024/133] (870s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[154.211790] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[154.215153] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[154.312816] [025/133] (870s left) kms_cursor_crc (cursor-random-max-size)
[154.618205] Starting subtest: cursor-random-max-size
[154.623513] Subtest cursor-random-max-size: SKIP (0.000s)
[154.741167] [026/133] (870s left) kms_force_connector_basic (prune-stale-modes)
[154.831895] Starting subtest: prune-stale-modes
[155.274958] Subtest prune-stale-modes: SUCCESS (0.452s)
[155.410191] [027/133] (869s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[155.510112] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[155.511963] Starting dynamic subtest: pipe-A-eDP-1
[155.532716] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[155.540911] Starting dynamic subtest: pipe-B-eDP-1
[155.560669] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[155.569321] Starting dynamic subtest: pipe-C-eDP-1
[155.588599] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[155.596942] Starting dynamic subtest: pipe-D-eDP-1
[155.616820] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[155.620248] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s)
[157.722612] [028/133] (867s left) kms_cursor_crc (cursor-sliding-256x85)
[157.981327] Starting subtest: cursor-sliding-256x85
[158.035798] Subtest cursor-sliding-256x85: SKIP (0.051s)
[158.211947] [029/133] (866s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[158.307298] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[158.309659] Starting dynamic subtest: pipe-A-default-mode
[159.425731] Dynamic subtest pipe-A-default-mode: SKIP (1.125s)
[159.430154] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.126s)
[159.570046] [030/133] (865s left) gem_unref_active_buffers
[159.786331] [031/133] (865s left) gem_ctx_param (invalid-set-no-zeromap)
[159.838424] Starting subtest: invalid-set-no-zeromap
[159.840756] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[159.912875] [032/133] (865s left) gem_exec_schedule (preempt-contexts)
[159.991301] Starting subtest: preempt-contexts
[159.993615] Starting dynamic subtest: rcs0
[160.045603] Dynamic subtest rcs0: SUCCESS (0.061s)
[160.056098] Starting dynamic subtest: bcs0
[160.098954] Dynamic subtest bcs0: SUCCESS (0.053s)
[160.112117] Starting dynamic subtest: vcs0
[160.149907] Dynamic subtest vcs0: SUCCESS (0.051s)
[160.161787] Starting dynamic subtest: vcs1
[160.209741] Dynamic subtest vcs1: SUCCESS (0.060s)
[160.222336] Starting dynamic subtest: vecs0
[160.251651] Dynamic subtest vecs0: SUCCESS (0.042s)
[160.261582] Starting dynamic subtest: ccs0
[160.299088] Dynamic subtest ccs0: SUCCESS (0.045s)
[160.301003] Subtest preempt-contexts: SUCCESS (0.314s)
[161.921082] [033/133] (863s left) syncobj_wait (invalid-signal-bad-pad)
[161.979082] Starting subtest: invalid-signal-bad-pad
[161.981377] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[162.065422] [034/133] (863s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[162.152478] Starting subtest: primary-y-tiled-reflect-x-180
[162.214158] Subtest primary-y-tiled-reflect-x-180: SKIP (0.059s)
[162.312564] [035/133] (862s left) gem_ctx_param (set-priority-invalid-size)
[162.363686] Starting subtest: set-priority-invalid-size
[162.366036] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[162.440417] [036/133] (862s left) core_setmaster (master-drop-set-root)
[162.498593] Starting subtest: master-drop-set-root
[162.509577] Subtest master-drop-set-root: SUCCESS (0.014s)
[162.558121] [037/133] (862s left) syncobj_timeline (wait-for-submit-delayed-submit)
[162.605830] Starting subtest: wait-for-submit-delayed-submit
[162.709592] Subtest wait-for-submit-delayed-submit: SUCCESS (0.104s)
[162.794211] [038/133] (862s left) syncobj_wait (multi-wait-submitted)
[162.853258] Starting subtest: multi-wait-submitted
[162.957284] Subtest multi-wait-submitted: SUCCESS (0.105s)
[163.030025] [039/133] (862s left) gem_ccs (large-ctrl-surf-copy)
[163.085859] Starting subtest: large-ctrl-surf-copy
[163.090535] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[163.181722] [040/133] (862s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[163.272387] Starting subtest: fbc-rgb565-draw-mmap-gtt
[163.276572] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[163.367368] [041/133] (862s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[163.424219] Starting subtest: multi-wait-available-submitted-signaled
[163.426471] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[163.514077] [042/133] (862s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[163.605028] Starting subtest: y-tiled-32bpp-rotate-90
[163.609325] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[163.700589] [043/133] (861s left) kms_psr (psr-cursor-mmap-cpu)
[163.807635] Starting subtest: psr-cursor-mmap-cpu
[163.810162] Starting dynamic subtest: eDP-1
[165.537887] Dynamic subtest eDP-1: SUCCESS (1.737s)
[165.540164] Subtest psr-cursor-mmap-cpu: SUCCESS (1.738s)
[166.372142] [044/133] (859s left) gem_lmem_swapping (random)
[166.417005] Subtest random: SKIP (0.000s)
[166.456977] [045/133] (859s left) kms_flip (flip-vs-expired-vblank)
[166.551076] Starting subtest: flip-vs-expired-vblank
[166.552957] Starting dynamic subtest: A-eDP1
[175.235049] Dynamic subtest A-eDP1: SUCCESS (8.706s)
[175.248566] Starting dynamic subtest: B-eDP1
[183.906117] Dynamic subtest B-eDP1: SUCCESS (8.678s)
[183.917937] Starting dynamic subtest: C-eDP1
[192.587772] Dynamic subtest C-eDP1: SUCCESS (8.675s)
[192.598891] Starting dynamic subtest: D-eDP1
[201.293895] Dynamic subtest D-eDP1: SUCCESS (8.703s)
[201.300445] Subtest flip-vs-expired-vblank: SUCCESS (34.775s)
[202.533380] [046/133] (823s left) i915_pm_rc6_residency (rc6-accuracy)
[202.592272] Starting subtest: rc6-accuracy
[202.594576] Starting dynamic subtest: gt0
[205.763403] Dynamic subtest gt0: SUCCESS (3.168s)
[205.772461] Starting dynamic subtest: gt1
[208.933099] Dynamic subtest gt1: SUCCESS (3.168s)
[208.935803] Subtest rc6-accuracy: SUCCESS (6.340s)
[209.035854] [047/133] (816s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[209.129942] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[209.134461] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[209.218628] [048/133] (816s left) kms_flip (2x-modeset-vs-vblank-race)
[209.319648] Starting subtest: 2x-modeset-vs-vblank-race
[209.330259] Subtest 2x-modeset-vs-vblank-race: SKIP (0.010s)
[209.435300] [049/133] (816s left) kms_vblank (wait-forked-busy)
[209.522758] Starting subtest: wait-forked-busy
[209.525139] Starting dynamic subtest: pipe-A-eDP-1
[211.404473] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.879s)
[211.418838] Starting dynamic subtest: pipe-D-eDP-1
[213.954238] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.546s)
[213.956764] Subtest wait-forked-busy: SUCCESS (4.432s)
[214.828197] [050/133] (811s left) kms_explicit_fence (multiplane-atomic-fence-wait)
[214.967910] Starting subtest: multiplane-atomic-fence-wait
[215.289147] Subtest multiplane-atomic-fence-wait: SUCCESS (0.321s)
[216.417693] [051/133] (809s left) gem_pxp (regular-baseline-src-copy-readible)
[216.538388] Starting subtest: regular-baseline-src-copy-readible
[216.601945] Subtest regular-baseline-src-copy-readible: SUCCESS (0.066s)
[216.721321] [052/133] (809s left) kms_pm_rpm (basic-pci-d3-state)
[218.235773] Starting subtest: basic-pci-d3-state
[219.734941] Subtest basic-pci-d3-state: SUCCESS (1.499s)
[219.858300] [053/133] (806s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[219.953742] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[220.577435] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.626s)
[220.724817] [054/133] (805s left) kms_universal_plane (disable-primary-vs-flip)
[220.820656] Starting subtest: disable-primary-vs-flip
[220.822986] Starting dynamic subtest: pipe-A-eDP-1
[222.362219] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.538s)
[222.372223] Starting dynamic subtest: pipe-B-eDP-1
[224.578696] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.216s)
[224.588837] Starting dynamic subtest: pipe-C-eDP-1
[226.756216] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.177s)
[226.767264] Starting dynamic subtest: pipe-D-eDP-1
[228.949385] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.193s)
[228.951657] Subtest disable-primary-vs-flip: SUCCESS (8.130s)
[229.820145] [055/133] (796s left) gem_userptr_blits (stress-mm)
[229.890701] Starting subtest: stress-mm
[235.259705] Subtest stress-mm: SUCCESS (5.369s)
[235.388551] [056/133] (790s left) kms_busy (extended-modeset-hang-newfb)
[235.487087] Starting subtest: extended-modeset-hang-newfb
[235.489314] Starting dynamic subtest: pipe-A
[270.795091] Dynamic subtest pipe-A: SUCCESS (35.303s)
[270.805661] Starting dynamic subtest: pipe-D
[293.762802] Dynamic subtest pipe-D: SUCCESS (22.964s)
[293.765205] Subtest extended-modeset-hang-newfb: SUCCESS (58.272s)
[294.610381] [057/133] (731s left) prime_vgem (basic-fence-flip)
[294.681580] Starting subtest: basic-fence-flip
[294.986796] Subtest basic-fence-flip: SUCCESS (0.308s)
[295.089745] [058/133] (731s left) syncobj_wait (invalid-wait-bad-flags)
[295.146162] Starting subtest: invalid-wait-bad-flags
[295.148230] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[295.241901] [059/133] (731s left) kms_addfb_basic (master-rmfb)
[295.289251] Starting subtest: master-rmfb
[295.291185] Subtest master-rmfb: SUCCESS (0.001s)
[295.429799] [060/133] (730s left) gem_lmem_swapping (heavy-verify-multi)
[295.474752] Subtest heavy-verify-multi: SKIP (0.000s)
[295.526736] [061/133] (730s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[295.623898] Starting subtest: fbc-pr-cursor-mmap-cpu
[295.628551] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[295.708133] [062/133] (730s left) gem_exec_params (no-vebox)
[295.772819] Starting subtest: no-vebox
[295.777764] Subtest no-vebox: SUCCESS (0.003s)
[296.959947] [063/133] (729s left) gem_exec_params (invalid-fence-in)
[297.028554] Starting subtest: invalid-fence-in
[297.030923] Subtest invalid-fence-in: SUCCESS (0.000s)
[297.142863] [064/133] (729s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[297.252804] Starting subtest: flip-vs-cursor-atomic-transitions
[305.152706] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (7.899s)
[306.022248] [065/133] (720s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[306.080653] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[306.084933] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[306.263294] [066/133] (720s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[306.363429] Starting subtest: pr-plane-move-sf-dmg-area
[306.367528] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[306.430764] [067/133] (720s left) kms_plane (plane-position-hole)
[306.534515] Starting subtest: plane-position-hole
[307.085128] Starting dynamic subtest: pipe-A-plane-1
[308.382692] Dynamic subtest pipe-A-plane-1: SUCCESS (1.297s)
[308.392285] Starting dynamic subtest: pipe-A-plane-2
[309.640187] Dynamic subtest pipe-A-plane-2: SUCCESS (1.258s)
[309.650210] Starting dynamic subtest: pipe-A-plane-3
[310.981479] Dynamic subtest pipe-A-plane-3: SUCCESS (1.339s)
[310.991542] Starting dynamic subtest: pipe-A-plane-4
[312.253411] Dynamic subtest pipe-A-plane-4: SUCCESS (1.273s)
[312.262659] Starting dynamic subtest: pipe-A-plane-5
[312.278773] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s)
[313.490187] Starting dynamic subtest: pipe-B-plane-1
[314.743516] Dynamic subtest pipe-B-plane-1: SUCCESS (1.263s)
[314.753964] Starting dynamic subtest: pipe-B-plane-2
[316.064528] Dynamic subtest pipe-B-plane-2: SUCCESS (1.319s)
[316.074044] Starting dynamic subtest: pipe-B-plane-3
[317.343542] Dynamic subtest pipe-B-plane-3: SUCCESS (1.280s)
[317.353508] Starting dynamic subtest: pipe-B-plane-4
[318.681290] Dynamic subtest pipe-B-plane-4: SUCCESS (1.334s)
[318.691123] Starting dynamic subtest: pipe-B-plane-5
[318.704401] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[318.706769] Subtest plane-position-hole: SUCCESS (12.172s)
[319.506308] [068/133] (707s left) syncobj_timeline (etime-multi-wait-all-submitted)
[319.562435] Starting subtest: etime-multi-wait-all-submitted
[319.670392] Subtest etime-multi-wait-all-submitted: SUCCESS (0.106s)
[319.745258] [069/133] (706s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[319.855990] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[319.860132] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[319.937509] [070/133] (706s left) syncobj_timeline (invalid-transfer-illegal-handle)
[319.986065] Starting subtest: invalid-transfer-illegal-handle
[319.987964] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[320.080053] [071/133] (706s left) kms_flip (2x-blocking-absolute-wf_vblank)
[320.165722] Starting subtest: 2x-blocking-absolute-wf_vblank
[320.173930] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.010s)
[320.255197] [072/133] (706s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[320.349735] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[320.353939] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[320.442551] [073/133] (706s left) gem_ccs (block-multicopy-inplace)
[320.506422] Starting subtest: block-multicopy-inplace
[320.510832] Subtest block-multicopy-inplace: SKIP (0.000s)
[320.600823] [074/133] (706s left) i915_module_load (reload-no-display)
[320.645537] Starting subtest: reload-no-display
[327.792022] Subtest reload-no-display: SUCCESS (7.153s)
[327.820750] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[327.821793] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[327.825054] [075/133] (699s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[329.339545] Starting subtest: etime-multi-wait-all-available-unsubmitted
[329.444230] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.105s)
[329.486456] [FACT syncobj_timeline (etime-multi-wait-all-available-unsubmitted)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[329.487787] [FACT syncobj_timeline (etime-multi-wait-all-available-unsubmitted)] new: kernel.kmod_is_loaded.i915: true
[329.491965] [076/133] (697s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[329.640307] Starting subtest: dp-mst-type-1-suspend-resume
[329.644788] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[329.754701] [077/133] (697s left) kms_async_flips (test-cursor)
[329.838842] Starting subtest: test-cursor
[330.232318] Starting dynamic subtest: pipe-A-eDP-1
[331.006542] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.775s)
[331.398914] Starting dynamic subtest: pipe-B-eDP-1
[332.152221] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.759s)
[332.540107] Starting dynamic subtest: pipe-C-eDP-1
[333.372566] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.840s)
[333.738296] Starting dynamic subtest: pipe-D-eDP-1
[334.503737] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.772s)
[334.506095] Subtest test-cursor: SUCCESS (4.668s)
[335.672299] [078/133] (691s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[335.775395] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[335.777688] Starting dynamic subtest: pipe-A-eDP-1
[335.834151] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[335.844157] Starting dynamic subtest: pipe-B-eDP-1
[335.900010] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[335.912794] Starting dynamic subtest: pipe-C-eDP-1
[335.971005] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[335.979385] Starting dynamic subtest: pipe-D-eDP-1
[336.033071] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[336.037218] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[338.031171] [079/133] (689s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[338.149138] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[338.153640] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[338.259414] [080/133] (688s left) kms_getfb (getfb-handle-zero)
[338.357990] Starting subtest: getfb-handle-zero
[338.360229] Subtest getfb-handle-zero: SUCCESS (0.000s)
[338.455417] [081/133] (688s left) gem_exec_reloc (basic-wc-cpu-active)
[338.525035] Subtest basic-wc-cpu-active: SKIP (0.000s)
[338.601472] [082/133] (688s left) gem_ctx_persistence (legacy-engines-cleanup)
[338.664724] Starting subtest: legacy-engines-cleanup
[338.667086] Starting dynamic subtest: render
[338.731167] Dynamic subtest render: SUCCESS (0.066s)
[338.742405] Starting dynamic subtest: bsd1
[338.770930] Dynamic subtest bsd1: SUCCESS (0.037s)
[338.783206] Starting dynamic subtest: bsd2
[338.810854] Dynamic subtest bsd2: SUCCESS (0.037s)
[338.819804] Starting dynamic subtest: blt
[338.848793] Dynamic subtest blt: SUCCESS (0.036s)
[338.860703] Starting dynamic subtest: vebox
[338.882745] Dynamic subtest vebox: SUCCESS (0.032s)
[338.885071] Subtest legacy-engines-cleanup: SUCCESS (0.219s)
[338.974359] [083/133] (688s left) prime_vgem (sync)
[339.058598] Starting subtest: sync
[339.060548] Starting dynamic subtest: rcs0
[339.099385] Dynamic subtest rcs0: SUCCESS (0.040s)
[339.107445] Starting dynamic subtest: bcs0
[339.161445] Dynamic subtest bcs0: SUCCESS (0.061s)
[339.169949] Starting dynamic subtest: vcs0
[339.208074] Dynamic subtest vcs0: SUCCESS (0.045s)
[339.215914] Starting dynamic subtest: vcs1
[339.253291] Dynamic subtest vcs1: SUCCESS (0.044s)
[339.261325] Starting dynamic subtest: vecs0
[339.299287] Dynamic subtest vecs0: SUCCESS (0.045s)
[339.307183] Starting dynamic subtest: ccs0
[339.344643] Dynamic subtest ccs0: SUCCESS (0.044s)
[339.346617] Subtest sync: SUCCESS (0.287s)
[339.466856] [084/133] (687s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[339.570407] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[340.383922] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.814s)
[340.564931] [085/133] (686s left) gem_exec_reloc (basic-cpu-noreloc)
[340.629704] Subtest basic-cpu-noreloc: SKIP (0.000s)
[340.721065] [086/133] (686s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[340.817466] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[347.680715] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.866s)
[347.796874] [087/133] (679s left) gem_exec_schedule (semaphore-user)
[347.866527] Starting subtest: semaphore-user
[347.919256] Subtest semaphore-user: SUCCESS (0.057s)
[349.934285] [088/133] (677s left) kms_cursor_crc (cursor-offscreen-128x128)
[350.135239] Starting subtest: cursor-offscreen-128x128
[350.140272] Starting dynamic subtest: pipe-A-eDP-1
[352.069188] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.930s)
[352.097079] Starting dynamic subtest: pipe-D-eDP-1
[354.720378] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.629s)
[354.722735] Subtest cursor-offscreen-128x128: SUCCESS (4.587s)
[355.558406] [089/133] (671s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[355.607394] Starting subtest: multi-wait-all-for-submit-unsubmitted
[355.709817] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[355.796726] [090/133] (671s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[355.904071] Starting subtest: crc-primary-suspend-y-tiled-ccs
[355.906275] Starting dynamic subtest: pipe-A-eDP-1
[355.915288] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[355.924233] Starting dynamic subtest: pipe-B-eDP-1
[355.933442] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[355.941634] Starting dynamic subtest: pipe-C-eDP-1
[355.949869] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[355.958265] Starting dynamic subtest: pipe-D-eDP-1
[355.966815] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[355.970906] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[358.137671] [091/133] (669s left) gem_exec_fence (syncobj-timeline-repeat)
[358.672904] Starting subtest: syncobj-timeline-repeat
[358.912182] Subtest syncobj-timeline-repeat: SUCCESS (0.239s)
[359.995076] [092/133] (667s left) i915_drm_fdinfo (busy-idle)
[360.034921] Starting subtest: busy-idle
[360.036968] Starting dynamic subtest: rcs0
[360.068114] Dynamic subtest rcs0: SKIP (0.033s)
[360.079550] Starting dynamic subtest: bcs0
[360.083545] Dynamic subtest bcs0: SKIP (0.000s)
[360.094677] Starting dynamic subtest: vcs0
[360.099136] Dynamic subtest vcs0: SKIP (0.000s)
[360.107532] Starting dynamic subtest: vcs1
[360.111569] Dynamic subtest vcs1: SKIP (0.000s)
[360.119765] Starting dynamic subtest: vecs0
[360.123772] Dynamic subtest vecs0: SKIP (0.000s)
[360.131930] Starting dynamic subtest: ccs0
[360.136040] Dynamic subtest ccs0: SKIP (0.000s)
[360.140147] Subtest busy-idle: SKIP (0.039s)
[360.176455] [093/133] (667s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[360.273928] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[367.112687] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.843s)
[367.250234] [094/133] (660s left) gem_ctx_shared (q-independent)
[367.317567] Starting subtest: Q-independent
[367.320049] Starting dynamic subtest: rcs0
[367.388523] Dynamic subtest rcs0: SUCCESS (0.070s)
[367.404596] Starting dynamic subtest: vcs0
[367.439367] Dynamic subtest vcs0: SUCCESS (0.051s)
[367.451130] Starting dynamic subtest: vcs1
[367.477602] Dynamic subtest vcs1: SUCCESS (0.038s)
[367.489767] Starting dynamic subtest: vecs0
[367.519443] Dynamic subtest vecs0: SUCCESS (0.041s)
[367.531623] Starting dynamic subtest: ccs0
[367.565297] Dynamic subtest ccs0: SUCCESS (0.045s)
[367.567599] Subtest Q-independent: SUCCESS (0.248s)
[368.985087] [095/133] (658s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[369.079555] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[369.081963] Starting dynamic subtest: pipe-A-default-mode
[370.244169] Dynamic subtest pipe-A-default-mode: SKIP (1.162s)
[370.248587] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.163s)
[370.394952] [096/133] (657s left) kms_addfb_basic (too-high)
[370.494255] Starting subtest: too-high
[370.496505] Subtest too-high: SUCCESS (0.000s)
[370.597221] [097/133] (657s left) kms_scaling_modes (scaling-mode-full)
[370.686684] Starting subtest: scaling-mode-full
[370.688661] Starting dynamic subtest: pipe-A-eDP-1
[370.780366] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.095s)
[370.789472] Starting dynamic subtest: pipe-B-eDP-1
[371.930440] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.149s)
[371.946567] Starting dynamic subtest: pipe-C-eDP-1
[373.036986] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.102s)
[373.046731] Starting dynamic subtest: pipe-D-eDP-1
[374.158386] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[374.160681] Subtest scaling-mode-full: SUCCESS (3.475s)
[375.316466] [098/133] (652s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[375.714511] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[376.533255] [099/133] (651s left) gem_exec_whisper (basic-fds)
[376.592846] Starting subtest: basic-fds
[383.826611] Subtest basic-fds: SUCCESS (7.226s)
[384.020720] [100/133] (643s left) gem_mmap_wc (read)
[384.085291] Subtest read: SKIP (0.000s)
[384.151762] [101/133] (643s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[384.265717] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[392.840429] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.574s)
[392.946964] [102/133] (635s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[393.044775] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[393.047123] Starting dynamic subtest: pipe-A-eDP-1
[393.069935] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[393.082571] Starting dynamic subtest: pipe-B-eDP-1
[393.103393] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[393.114808] Starting dynamic subtest: pipe-C-eDP-1
[393.136120] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[393.148301] Starting dynamic subtest: pipe-D-eDP-1
[393.171113] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[393.175632] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[395.230724] [103/133] (632s left) kms_cursor_legacy (forked-bo)
[395.346640] Starting subtest: forked-bo
[395.348865] Starting dynamic subtest: pipe-A
[400.801579] Dynamic subtest pipe-A: SUCCESS (5.450s)
[400.814015] Starting dynamic subtest: pipe-B
[406.272474] Dynamic subtest pipe-B: SUCCESS (5.468s)
[406.283881] Starting dynamic subtest: pipe-C
[411.743111] Dynamic subtest pipe-C: SUCCESS (5.467s)
[411.757762] Starting dynamic subtest: pipe-D
[417.209635] Dynamic subtest pipe-D: SUCCESS (5.463s)
[417.224638] Starting dynamic subtest: all-pipes
[422.717244] Dynamic subtest all-pipes: SUCCESS (5.503s)
[422.719884] Subtest forked-bo: SUCCESS (27.363s)
[422.880795] [104/133] (605s left) kms_flip (flip-vs-absolute-wf_vblank)
[422.973948] Starting subtest: flip-vs-absolute-wf_vblank
[422.976213] Starting dynamic subtest: A-eDP1
[431.868754] Dynamic subtest A-eDP1: SUCCESS (8.894s)
[431.882635] Starting dynamic subtest: B-eDP1
[440.801543] Dynamic subtest B-eDP1: SUCCESS (8.931s)
[440.814110] Starting dynamic subtest: C-eDP1
[449.729980] Dynamic subtest C-eDP1: SUCCESS (8.925s)
[449.742979] Starting dynamic subtest: D-eDP1
[458.669551] Dynamic subtest D-eDP1: SUCCESS (8.936s)
[458.671929] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.698s)
[459.898907] [105/133] (568s left) api_intel_bb (purge-bb)
[459.947246] Starting subtest: purge-bb
[459.949700] Subtest purge-bb: SUCCESS (0.004s)
[460.080842] [106/133] (568s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[460.170245] Starting subtest: read-crc-frame-sequence
[460.172585] Starting dynamic subtest: pipe-A-eDP-1
[460.875984] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.703s)
[460.886467] Starting dynamic subtest: pipe-B-eDP-1
[462.272781] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.399s)
[462.283586] Starting dynamic subtest: pipe-C-eDP-1
[463.575802] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.301s)
[463.587016] Starting dynamic subtest: pipe-D-eDP-1
[464.885056] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.307s)
[464.887396] Subtest read-crc-frame-sequence: SUCCESS (4.715s)
[465.755331] [107/133] (562s left) gem_exec_parallel (contexts)
[465.819069] Starting subtest: contexts
[465.821418] Starting dynamic subtest: rcs0
[468.824174] Dynamic subtest rcs0: SUCCESS (3.002s)
[468.833816] Starting dynamic subtest: bcs0
[472.468221] Dynamic subtest bcs0: SUCCESS (3.643s)
[472.480913] Starting dynamic subtest: vcs0
[476.423329] Dynamic subtest vcs0: SUCCESS (3.954s)
[476.435852] Starting dynamic subtest: vcs1
[480.430238] Dynamic subtest vcs1: SUCCESS (4.004s)
[480.440891] Starting dynamic subtest: vecs0
[484.470607] Dynamic subtest vecs0: SUCCESS (4.039s)
[484.483175] Starting dynamic subtest: ccs0
[488.229794] Dynamic subtest ccs0: SUCCESS (3.756s)
[488.232569] Subtest contexts: SUCCESS (22.412s)
[488.419565] [108/133] (539s left) kms_color (ctm-negative)
[488.520236] Starting subtest: ctm-negative
[488.522419] Starting dynamic subtest: pipe-A-eDP-1
[489.228079] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[489.238987] Starting dynamic subtest: pipe-B-eDP-1
[490.665826] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.435s)
[490.676271] Starting dynamic subtest: pipe-C-eDP-1
[492.031137] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.365s)
[492.041978] Starting dynamic subtest: pipe-D-eDP-1
[493.352782] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[493.355136] Subtest ctm-negative: SUCCESS (4.835s)
[494.165095] [109/133] (534s left) kms_psr (fbc-psr2-suspend)
[494.266633] Starting subtest: fbc-psr2-suspend
[494.268587] Starting dynamic subtest: eDP-1
[494.272766] Dynamic subtest eDP-1: SKIP (0.000s)
[494.277030] Subtest fbc-psr2-suspend: SKIP (0.001s)
[494.340199] [110/133] (534s left) perf_pmu (event-wait)
[494.378283] Starting subtest: event-wait
[494.380618] Starting dynamic subtest: rcs0
[494.385019] Dynamic subtest rcs0: SKIP (0.000s)
[494.389314] Subtest event-wait: SKIP (0.001s)
[494.477527] [111/133] (533s left) kms_addfb_basic (bad-pitch-0)
[494.529388] Starting subtest: bad-pitch-0
[494.531285] Subtest bad-pitch-0: SUCCESS (0.000s)
[494.660075] [112/133] (533s left) kms_psr (pr-primary-page-flip)
[494.748320] Starting subtest: pr-primary-page-flip
[494.752756] Subtest pr-primary-page-flip: SKIP (0.000s)
[494.822987] [113/133] (533s left) kms_chamelium_frames (dp-crc-fast)
[495.210956] Subtest dp-crc-fast: SKIP (0.000s)
[496.053449] [114/133] (532s left) gem_exec_flush (basic-wb-pro-default)
[496.125045] Starting subtest: basic-wb-pro-default
[501.586191] Subtest basic-wb-pro-default: SUCCESS (5.462s)
[501.774881] [115/133] (526s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[501.875235] Starting subtest: crc-primary-basic-y-tiled-ccs
[501.877731] Starting dynamic subtest: pipe-A-eDP-1
[501.900436] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[501.913021] Starting dynamic subtest: pipe-B-eDP-1
[501.934213] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[501.943587] Starting dynamic subtest: pipe-C-eDP-1
[501.965367] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[501.974328] Starting dynamic subtest: pipe-D-eDP-1
[501.996115] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[502.000473] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.009s)
[504.132767] [116/133] (524s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[504.244480] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[508.684534] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.444s)
[509.829183] [117/133] (518s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[509.921188] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[509.925624] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[510.031972] [118/133] (518s left) prime_vgem (coherency-gtt)
[510.107216] Starting subtest: coherency-gtt
[510.111830] Subtest coherency-gtt: SKIP (0.000s)
[510.199057] [119/133] (518s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[510.305089] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[510.309075] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[510.429147] [120/133] (518s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[510.529317] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[510.533653] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[510.628505] [121/133] (518s left) gem_exec_balancer (bonded-chain)
[510.681273] Starting subtest: bonded-chain
[517.674480] Subtest bonded-chain: SUCCESS (6.992s)
[518.024990] [122/133] (510s left) gem_exec_params (invalid-batch-start-offset)
[518.091678] Starting subtest: invalid-batch-start-offset
[518.094478] Subtest invalid-batch-start-offset: SUCCESS (0.002s)
[518.209905] [123/133] (510s left) kms_pipe_crc_basic (bad-source)
[518.305299] Starting subtest: bad-source
[518.307562] Subtest bad-source: SUCCESS (0.000s)
[518.377389] [124/133] (510s left) perf (global-sseu-config-invalid)
[518.511048] Starting subtest: global-sseu-config-invalid
[518.515498] Subtest global-sseu-config-invalid: SKIP (0.000s)
[518.552073] [125/133] (510s left) gem_mmap_wc (write-wc-read-gtt)
[518.619180] Subtest write-wc-read-gtt: SKIP (0.000s)
[518.685365] [126/133] (510s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[518.786838] Starting subtest: x-tiled-16bpp-rotate-180
[520.020145] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.234s)
[520.167783] [127/133] (508s left) gem_spin_batch (legacy-resubmit)
[520.217385] Starting subtest: legacy-resubmit
[520.219672] Starting dynamic subtest: default
[520.225982] Dynamic subtest default: SUCCESS (0.008s)
[520.238380] Starting dynamic subtest: render
[520.240414] Dynamic subtest render: SUCCESS (0.002s)
[520.250025] Starting dynamic subtest: bsd
[520.251647] Dynamic subtest bsd: SUCCESS (0.002s)
[520.260249] Starting dynamic subtest: bsd1
[520.261860] Dynamic subtest bsd1: SUCCESS (0.001s)
[520.270591] Starting dynamic subtest: bsd2
[520.272135] Dynamic subtest bsd2: SUCCESS (0.003s)
[520.280785] Starting dynamic subtest: blt
[520.282410] Dynamic subtest blt: SUCCESS (0.002s)
[520.291128] Starting dynamic subtest: vebox
[520.292742] Dynamic subtest vebox: SUCCESS (0.003s)
[520.294334] Subtest legacy-resubmit: SUCCESS (0.027s)
[520.505416] [128/133] (508s left) gem_close_race (basic-process)
[520.576591] Starting subtest: basic-process
[520.636979] Subtest basic-process: SUCCESS (0.060s)
[520.819181] [129/133] (508s left) kms_rotation_crc (primary-rotation-270)
[520.910296] Starting subtest: primary-rotation-270
[520.914701] Subtest primary-rotation-270: SKIP (0.001s)
[520.999987] [130/133] (508s left) kms_psr (fbc-psr2-cursor-blt)
[521.086072] Starting subtest: fbc-psr2-cursor-blt
[521.088391] Starting dynamic subtest: eDP-1
[521.092502] Dynamic subtest eDP-1: SKIP (0.000s)
[521.096561] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[521.161943] [131/133] (508s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[521.254071] Starting subtest: psr-1p-pri-indfb-multidraw
[543.573052] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.326s)
[543.696500] [132/133] (485s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[543.758396] Starting subtest: multi-wait-all-for-submit-signaled
[543.760056] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[543.846621] [133/133] (485s left) gem_exec_fence (expired-history)
[544.313096] Starting subtest: expired-history
[551.626237] Subtest expired-history: SUCCESS (7.315s)
[553.881966] Closing watchdogs
results: parsing output: 0/ for test: kms_flip
results: parsing output: 1/ for test: kms_invalid_mode
results: parsing output: 2/ for test: kms_plane_lowres
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: gem_readwrite
results: parsing output: 8/ for test: api_intel_allocator
results: parsing output: 9/ for test: gem_wait
results: parsing output: 10/ for test: kms_plane_scaling
results: parsing output: 11/ for test: kms_prop_blob
results: parsing output: 12/ for test: kms_big_fb
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: gem_mmap_gtt
results: parsing output: 15/ for test: gem_mmap_offset
results: parsing output: 16/ for test: gen9_exec_parse
results: parsing output: 17/ for test: kms_lease
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_plane
results: parsing output: 20/ for test: gem_workarounds
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: i915_suspend
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_cursor_crc
results: parsing output: 25/ for test: kms_force_connector_basic
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: kms_cursor_crc
results: parsing output: 28/ for test: kms_flip_scaled_crc
results: parsing output: 29/ for test: gem_unref_active_buffers
results: parsing output: 30/ for test: gem_ctx_param
results: parsing output: 31/ for test: gem_exec_schedule
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: kms_rotation_crc
results: parsing output: 34/ for test: gem_ctx_param
results: parsing output: 35/ for test: core_setmaster
results: parsing output: 36/ for test: syncobj_timeline
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: gem_ccs
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_psr
results: parsing output: 43/ for test: gem_lmem_swapping
results: parsing output: 44/ for test: kms_flip
results: parsing output: 45/ for test: i915_pm_rc6_residency
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_vblank
results: parsing output: 49/ for test: kms_explicit_fence
results: parsing output: 50/ for test: gem_pxp
results: parsing output: 51/ for test: kms_pm_rpm
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_universal_plane
results: parsing output: 54/ for test: gem_userptr_blits
results: parsing output: 55/ for test: kms_busy
results: parsing output: 56/ for test: prime_vgem
results: parsing output: 57/ for test: syncobj_wait
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: gem_lmem_swapping
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: gem_exec_params
results: parsing output: 62/ for test: gem_exec_params
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: gem_render_copy
results: parsing output: 65/ for test: kms_psr2_sf
results: parsing output: 66/ for test: kms_plane
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_ccs
results: parsing output: 73/ for test: i915_module_load
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: kms_content_protection
results: parsing output: 76/ for test: kms_async_flips
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_getfb
results: parsing output: 80/ for test: gem_exec_reloc
results: parsing output: 81/ for test: gem_ctx_persistence
results: parsing output: 82/ for test: prime_vgem
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_exec_schedule
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: syncobj_wait
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: gem_exec_fence
results: parsing output: 91/ for test: i915_drm_fdinfo
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_ctx_shared
results: parsing output: 94/ for test: kms_flip_scaled_crc
results: parsing output: 95/ for test: kms_addfb_basic
results: parsing output: 96/ for test: kms_scaling_modes
results: parsing output: 97/ for test: kms_chamelium_hpd
results: parsing output: 98/ for test: gem_exec_whisper
results: parsing output: 99/ for test: gem_mmap_wc
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: kms_cursor_legacy
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: api_intel_bb
results: parsing output: 105/ for test: kms_pipe_crc_basic
results: parsing output: 106/ for test: gem_exec_parallel
results: parsing output: 107/ for test: kms_color
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: perf_pmu
results: parsing output: 110/ for test: kms_addfb_basic
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: kms_chamelium_frames
results: parsing output: 113/ for test: gem_exec_flush
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: prime_vgem
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_exec_balancer
results: parsing output: 121/ for test: gem_exec_params
results: parsing output: 122/ for test: kms_pipe_crc_basic
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: gem_mmap_wc
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: gem_spin_batch
results: parsing output: 127/ for test: gem_close_race
results: parsing output: 128/ for test: kms_rotation_crc
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: syncobj_wait
results: parsing output: 132/ for test: gem_exec_fence
Done.