[56.548314] Initializing watchdogs
[56.548451]   /dev/watchdog0
[56.562099] [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]
[56.565875] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.568162] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.571999] [001/128] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[56.742497] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[61.833613] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (5.096s)
[61.970035] [002/128] (955s left) kms_pm_lpsp (screens-disabled)
[63.077425] Starting subtest: screens-disabled
[63.081790] Subtest screens-disabled: SKIP (0.000s)
[63.139173] [003/128] (953s left) vgem_basic (debugfs)
[63.201707] Starting subtest: debugfs
[63.203947] Subtest debugfs: SUCCESS (0.000s)
[63.248153] [FACT vgem_basic (debugfs)] new: kernel.kmod_is_loaded.vgem: true
[63.251549] [004/128] (953s left) prime_vgem (basic-blt)
[63.330526] Starting subtest: basic-blt
[63.356083] Subtest basic-blt: SUCCESS (0.025s)
[63.482808] [005/128] (953s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[63.602212] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[93.987881] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (30.383s)
[94.834267] [006/128] (922s left) kms_chamelium_frames (dp-crc-fast)
[95.209215] Subtest dp-crc-fast: SKIP (0.000s)
[96.037188] [007/128] (921s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs)
[96.139045] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs
[96.141491] Starting dynamic subtest: pipe-A-eDP-1
[96.165420] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[96.171825] Starting dynamic subtest: pipe-B-eDP-1
[96.193936] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[96.200657] Starting dynamic subtest: pipe-C-eDP-1
[96.222594] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[96.229459] Starting dynamic subtest: pipe-D-eDP-1
[96.252042] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[96.256208] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.002s)
[98.313524] [008/128] (918s left) kms_plane_scaling (invalid-parameters)
[98.416732] Starting subtest: invalid-parameters
[98.419047] Starting dynamic subtest: less-than-1-height-src
[98.424115] Dynamic subtest less-than-1-height-src: SUCCESS (0.010s)
[98.432480] Starting dynamic subtest: less-than-1-width-src
[98.437665] Dynamic subtest less-than-1-width-src: SUCCESS (0.011s)
[98.439610] Subtest invalid-parameters: SUCCESS (0.021s)
[98.522616] [009/128] (918s left) kms_dp_link_training (uhbr-mst)
[98.620860] Starting subtest: uhbr-mst
[98.629769] Subtest uhbr-mst: SKIP (0.000s)
[98.683697] [010/128] (918s left) kms_flip (2x-flip-vs-panning)
[98.783239] Starting subtest: 2x-flip-vs-panning
[98.793723] Subtest 2x-flip-vs-panning: SKIP (0.010s)
[98.903896] [011/128] (918s left) gem_gpgpu_fill (offset-16x16)
[98.967148] Starting subtest: offset-16x16
[98.972070] Subtest offset-16x16: SUCCESS (0.007s)
[99.106290] [012/128] (918s left) gem_busy (close-race)
[99.172418] Starting subtest: close-race
[122.921528] Subtest close-race: SUCCESS (23.748s)
[123.297415] [013/128] (894s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[123.448714] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[123.453165] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[123.558837] [014/128] (893s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[123.648784] Starting subtest: plane-upscale-factor-0-25-with-rotation
[123.650938] Starting dynamic subtest: pipe-A
[123.876110] Dynamic subtest pipe-A: SUCCESS (0.227s)
[123.884583] Starting dynamic subtest: pipe-B
[125.193895] Dynamic subtest pipe-B: SUCCESS (1.317s)
[125.202473] Starting dynamic subtest: pipe-C
[126.519948] Dynamic subtest pipe-C: SUCCESS (1.326s)
[126.529154] Starting dynamic subtest: pipe-D
[127.839809] Dynamic subtest pipe-D: SUCCESS (1.320s)
[127.841912] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.191s)
[129.012834] [015/128] (888s left) gem_mmap_gtt (basic-small-bo)
[129.082230] Subtest basic-small-bo: SKIP (0.000s)
[129.144982] [016/128] (888s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[129.259993] Starting subtest: short-flip-before-cursor-toggle
[130.186876] Subtest short-flip-before-cursor-toggle: SUCCESS (0.927s)
[131.037827] [017/128] (886s left) gem_exec_reloc (basic-cpu-wc-active)
[131.106401] Subtest basic-cpu-wc-active: SKIP (0.000s)
[131.190840] [018/128] (886s left) gem_tiled_fence_blits (basic)
[131.256783] Subtest basic: SKIP (0.000s)
[131.343297] [019/128] (886s left) api_intel_bb (intel-bb-blit-y)
[131.410071] Starting subtest: intel-bb-blit-y
[135.185371] Subtest intel-bb-blit-y: SUCCESS (3.774s)
[135.317626] [020/128] (882s left) kms_invalid_mode (bad-vsync-end)
[135.425288] Starting subtest: bad-vsync-end
[135.427693] Starting dynamic subtest: pipe-A-eDP-1
[135.707298] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.282s)
[135.716075] Starting dynamic subtest: pipe-B-eDP-1
[135.718184] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[135.724994] Starting dynamic subtest: pipe-C-eDP-1
[135.727218] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[135.734083] Starting dynamic subtest: pipe-D-eDP-1
[135.736346] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[135.738658] Subtest bad-vsync-end: SUCCESS (0.288s)
[136.561898] [021/128] (881s left) syncobj_wait (single-wait-all-for-submit-signaled)
[136.619565] Starting subtest: single-wait-all-for-submit-signaled
[136.622127] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[136.727034] [022/128] (881s left) kms_async_flips (async-flip-suspend-resume)
[136.826108] Starting subtest: async-flip-suspend-resume
[137.186720] Starting dynamic subtest: pipe-A-eDP-1
[155.163325] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.929s)
[155.530267] Starting dynamic subtest: pipe-B-eDP-1
[158.277206] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.753s)
[158.637034] Starting dynamic subtest: pipe-C-eDP-1
[161.402783] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.771s)
[161.763560] Starting dynamic subtest: pipe-D-eDP-1
[164.536176] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.779s)
[164.538808] Subtest async-flip-suspend-resume: SUCCESS (12.656s)
[165.713056] [023/128] (867s left) kms_rotation_crc (multiplane-rotation)
[165.825708] Starting subtest: multiplane-rotation
[172.974419] Subtest multiplane-rotation: SUCCESS (7.148s)
[173.093126] [024/128] (859s left) kms_psr (psr-sprite-mmap-gtt)
[173.188296] Starting subtest: psr-sprite-mmap-gtt
[173.190064] Starting dynamic subtest: eDP-1
[174.639595] Dynamic subtest eDP-1: SKIP (1.445s)
[174.644702] Subtest psr-sprite-mmap-gtt: SKIP (1.446s)
[174.814685] [025/128] (858s left) kms_lease (lease-unleased-crtc)
[174.911730] Starting subtest: lease-unleased-crtc
[174.914263] Starting dynamic subtest: pipe-A-eDP-1
[175.199465] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[175.208949] Starting dynamic subtest: pipe-B-eDP-1
[175.991613] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.789s)
[176.001390] Starting dynamic subtest: pipe-C-eDP-1
[176.003770] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[176.012529] Starting dynamic subtest: pipe-D-eDP-1
[176.014432] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[176.016333] Subtest lease-unleased-crtc: SUCCESS (1.094s)
[176.356133] [026/128] (856s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[176.421843] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[176.478100] [027/128] (856s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[176.533578] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[176.593063] [028/128] (856s left) perf (blocking-parameterized)
[176.706473] Starting subtest: blocking-parameterized
[196.747539] Subtest blocking-parameterized: SUCCESS (20.037s)
[196.790073] [029/128] (836s left) gem_exec_capture (capture-invisible)
[196.857326] Starting subtest: capture-invisible
[196.859213] Starting dynamic subtest: smem0
[196.863228] Dynamic subtest smem0: SKIP (0.000s)
[196.867213] Subtest capture-invisible: SKIP (0.000s)
[196.975834] [030/128] (836s left) gem_mmap_wc (close)
[197.041657] Subtest close: SKIP (0.000s)
[197.107833] [031/128] (836s left) kms_cursor_crc (cursor-onscreen-512x170)
[197.385064] Starting subtest: cursor-onscreen-512x170
[197.389488] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[197.525265] [032/128] (835s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[202.530940] Starting subtest: psr2-cursor-plane-update-sf
[202.533401] Starting dynamic subtest: pipe-A-eDP-1
[203.824873] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.292s)
[203.835050] Starting dynamic subtest: pipe-B-eDP-1
[205.150319] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.323s)
[205.152747] Subtest psr2-cursor-plane-update-sf: SUCCESS (2.620s)
[206.019893] [033/128] (827s left) dumb_buffer (invalid-bpp)
[206.083685] Starting subtest: invalid-bpp
[206.085862] Subtest invalid-bpp: SUCCESS (0.000s)
[206.183220] [034/128] (827s left) gem_ctx_param (non-root-set)
[206.234708] Starting subtest: non-root-set
[206.268893] Subtest non-root-set: SUCCESS (0.034s)
[206.349197] [035/128] (826s left) i915_fb_tiling (basic-x-tiling)
[206.411591] Subtest basic-x-tiling: SKIP (0.000s)
[206.472539] [036/128] (826s left) gem_mmap_gtt (cpuset-basic-small-copy)
[206.534701] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[206.600416] [037/128] (826s left) gem_ctx_isolation (preservation-s3)
[206.665803] Starting subtest: preservation-S3
[206.668175] Starting dynamic subtest: rcs0
[223.442325] Dynamic subtest rcs0: SUCCESS (1.693s)
[223.453755] Starting dynamic subtest: bcs0
[240.171272] Dynamic subtest bcs0: SUCCESS (1.414s)
[240.181729] Starting dynamic subtest: vcs0
[256.157807] Dynamic subtest vcs0: SUCCESS (2.249s)
[256.166485] Starting dynamic subtest: vcs1
[274.245601] Dynamic subtest vcs1: SUCCESS (5.096s)
[274.255284] Starting dynamic subtest: vecs0
[292.236701] Dynamic subtest vecs0: SUCCESS (4.896s)
[292.244746] Starting dynamic subtest: ccs0
[310.179035] Dynamic subtest ccs0: SUCCESS (3.222s)
[310.182250] Subtest preservation-S3: SUCCESS (18.574s)
[310.437755] [038/128] (807s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[310.537886] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[310.540736] Starting dynamic subtest: pipe-A-default-mode
[313.207218] Dynamic subtest pipe-A-default-mode: SUCCESS (2.662s)
[313.210055] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.663s)
[313.990409] [039/128] (804s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[314.110631] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[315.348872] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (1.237s)
[315.557969] [040/128] (802s left) gem_eio (in-flight-external)
[315.782846] Starting subtest: in-flight-external
[316.975205] Subtest in-flight-external: SUCCESS (1.190s)
[317.123715] [041/128] (801s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[317.278911] Starting subtest: cursor-rapid-movement-32x10
[317.316895] Subtest cursor-rapid-movement-32x10: SKIP (0.031s)
[317.619406] [042/128] (800s left) gem_set_tiling_vs_pwrite
[317.743471] [043/128] (800s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[317.850643] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[317.852923] Starting dynamic subtest: pipe-A
[317.923726] Dynamic subtest pipe-A: SUCCESS (0.073s)
[317.932741] Starting dynamic subtest: pipe-B
[319.029394] Dynamic subtest pipe-B: SUCCESS (1.104s)
[319.040012] Starting dynamic subtest: pipe-C
[320.129362] Dynamic subtest pipe-C: SUCCESS (1.098s)
[320.138187] Starting dynamic subtest: pipe-D
[321.253614] Dynamic subtest pipe-D: SUCCESS (1.123s)
[321.255995] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.400s)
[322.399658] [044/128] (796s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[322.499077] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[322.501992] Starting dynamic subtest: pipe-A-default-mode
[322.782261] Dynamic subtest pipe-A-default-mode: SKIP (0.280s)
[322.788069] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.281s)
[323.624667] [045/128] (794s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[323.752067] Starting subtest: flip-vs-cursor-atomic-transitions
[331.006673] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (7.250s)
[331.858935] [046/128] (786s left) kms_psr (fbc-pr-primary-mmap-gtt)
[331.956783] Starting subtest: fbc-pr-primary-mmap-gtt
[331.962030] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[332.024573] [047/128] (786s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[332.124734] Starting subtest: modeset-transition-nonblocking-fencing
[332.127040] Starting dynamic subtest: 1x-outputs
[342.697837] Dynamic subtest 1x-outputs: SUCCESS (10.565s)
[342.961970] Subtest modeset-transition-nonblocking-fencing: SUCCESS (10.829s)
[343.812920] [048/128] (774s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[343.921491] Starting subtest: yf-tiled-8bpp-rotate-270
[343.926868] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[344.031554] [049/128] (774s left) gem_pxp (verify-pxp-stale-buf-execution)
[344.161199] Starting subtest: verify-pxp-stale-buf-execution
[345.220082] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.061s)
[345.343180] [050/128] (773s left) gem_userptr_blits (sync-unmap-after-close)
[345.423825] Starting subtest: sync-unmap-after-close
[345.436798] Subtest sync-unmap-after-close: SUCCESS (0.013s)
[345.572586] [051/128] (773s left) intel_hwmon (hwmon-read)
[345.637592] Subtest hwmon-read: SKIP (0.000s)
[345.699236] [052/128] (773s left) gem_exec_schedule (semaphore-noskip)
[345.781091] Starting subtest: semaphore-noskip
[346.215243] Subtest semaphore-noskip: SUCCESS (0.445s)
[346.544829] [053/128] (772s left) perf (gen12-unprivileged-single-ctx-counters)
[346.630463] Starting subtest: gen12-unprivileged-single-ctx-counters
[346.633107] Starting dynamic subtest: rcs0
[347.140380] Dynamic subtest rcs0: SUCCESS (0.509s)
[347.143535] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.510s)
[347.185578] [054/128] (771s left) gem_exec_reloc (basic-gtt-noreloc)
[347.295106] Subtest basic-gtt-noreloc: SKIP (0.000s)
[347.387372] [055/128] (771s left) gem_exec_schedule (u-semaphore-codependency)
[347.470020] Starting subtest: u-semaphore-codependency
[347.485952] Subtest u-semaphore-codependency: SUCCESS (0.019s)
[347.854491] [056/128] (771s left) gem_exec_fence (syncobj-timeline-signal)
[348.427218] Starting subtest: syncobj-timeline-signal
[348.439857] Subtest syncobj-timeline-signal: SUCCESS (0.014s)
[350.002901] [057/128] (769s left) kms_flip (2x-flip-vs-suspend)
[350.106926] Starting subtest: 2x-flip-vs-suspend
[350.118198] Subtest 2x-flip-vs-suspend: SKIP (0.011s)
[350.230758] [058/128] (768s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[350.330829] Starting subtest: bad-aux-stride-y-tiled-ccs
[350.333219] Starting dynamic subtest: pipe-A-eDP-1
[350.361029] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[350.371803] Starting dynamic subtest: pipe-B-eDP-1
[350.398248] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[350.407192] Starting dynamic subtest: pipe-C-eDP-1
[350.433533] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[350.442036] Starting dynamic subtest: pipe-D-eDP-1
[350.468692] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[350.473531] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[352.464707] [059/128] (766s left) gem_ccs (suspend-resume)
[352.533944] Starting subtest: suspend-resume
[352.539844] Subtest suspend-resume: SKIP (0.000s)
[352.640666] [060/128] (766s left) kms_hdr (bpc-switch-dpms)
[352.733287] Starting subtest: bpc-switch-dpms
[353.835904] Starting dynamic subtest: pipe-A-eDP-1
[358.248905] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.411s)
[358.251468] Subtest bpc-switch-dpms: SUCCESS (5.512s)
[358.377008] [061/128] (760s left) i915_query (query-topology-matches-eu-total)
[358.438131] Starting subtest: query-topology-matches-eu-total
[358.440873] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[358.541852] [062/128] (760s left) gem_exec_whisper (basic-contexts-priority)
[358.617690] Starting subtest: basic-contexts-priority
[382.836445] Subtest basic-contexts-priority: SUCCESS (24.206s)
[383.023553] [063/128] (736s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[383.137088] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[383.142277] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[383.249118] [064/128] (736s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[383.356923] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[383.362004] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[383.456553] [065/128] (735s left) kms_psr (fbc-psr-cursor-plane-move)
[383.553998] Starting subtest: fbc-psr-cursor-plane-move
[383.556787] Starting dynamic subtest: eDP-1
[383.561505] Dynamic subtest eDP-1: SKIP (0.000s)
[383.566229] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[383.623604] [066/128] (735s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[383.739065] Starting subtest: y-tiled-16bpp-rotate-270
[383.743954] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[383.833458] [067/128] (735s left) syncobj_timeline (single-wait-all-available-signaled)
[383.888252] Starting subtest: single-wait-all-available-signaled
[383.890598] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[383.993601] [068/128] (735s left) gem_ctx_isolation (dirty-create)
[384.054017] Starting subtest: dirty-create
[384.056552] Starting dynamic subtest: rcs0
[384.500072] Dynamic subtest rcs0: SUCCESS (0.445s)
[384.510001] Starting dynamic subtest: bcs0
[384.956520] Dynamic subtest bcs0: SUCCESS (0.456s)
[384.964349] Starting dynamic subtest: vcs0
[385.456281] Dynamic subtest vcs0: SUCCESS (0.499s)
[385.465894] Starting dynamic subtest: vcs1
[387.367729] Dynamic subtest vcs1: SUCCESS (1.909s)
[387.378322] Starting dynamic subtest: vecs0
[389.352515] Dynamic subtest vecs0: SUCCESS (1.982s)
[389.365068] Starting dynamic subtest: ccs0
[391.355833] Dynamic subtest ccs0: SUCCESS (2.002s)
[391.358937] Subtest dirty-create: SUCCESS (7.297s)
[391.622608] [069/128] (727s left) drm_fdinfo (idle)
[391.675669] Starting subtest: idle
[391.677767] Starting dynamic subtest: rcs0
[392.237869] Dynamic subtest rcs0: SUCCESS (0.562s)
[392.250260] Starting dynamic subtest: bcs0
[392.783238] Dynamic subtest bcs0: SUCCESS (0.542s)
[392.793363] Starting dynamic subtest: vcs0
[393.288586] Dynamic subtest vcs0: SUCCESS (0.503s)
[393.299656] Starting dynamic subtest: vcs1
[393.798202] Dynamic subtest vcs1: SUCCESS (0.503s)
[393.809070] Starting dynamic subtest: vecs0
[394.299869] Dynamic subtest vecs0: SUCCESS (0.503s)
[394.311070] Starting dynamic subtest: ccs0
[394.804990] Dynamic subtest ccs0: SUCCESS (0.503s)
[394.808052] Subtest idle: SUCCESS (3.129s)
[394.876493] [070/128] (724s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[394.987059] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[394.992864] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[395.093011] [071/128] (724s left) kms_flip (plain-flip-interruptible)
[395.194362] Starting subtest: plain-flip-interruptible
[395.196826] Starting dynamic subtest: A-eDP1
[396.913955] Dynamic subtest A-eDP1: SUCCESS (1.717s)
[396.924592] Starting dynamic subtest: B-eDP1
[398.540250] Dynamic subtest B-eDP1: SUCCESS (1.624s)
[398.549724] Starting dynamic subtest: C-eDP1
[400.157731] Dynamic subtest C-eDP1: SUCCESS (1.617s)
[400.167607] Starting dynamic subtest: D-eDP1
[401.779219] Dynamic subtest D-eDP1: SUCCESS (1.619s)
[401.782101] Subtest plain-flip-interruptible: SUCCESS (6.584s)
[402.942478] [072/128] (716s left) gem_softpin (noreloc-interruptible)
[403.017953] Starting subtest: noreloc-interruptible
[408.440136] Subtest noreloc-interruptible: SUCCESS (5.423s)
[408.626760] [073/128] (710s left) gem_exec_params (cliprects-invalid)
[408.699241] Starting subtest: cliprects-invalid
[408.702071] Subtest cliprects-invalid: SUCCESS (0.000s)
[408.782546] [074/128] (710s left) gem_fence_thrash (bo-write-verify-y)
[408.820987] Starting subtest: bo-write-verify-y
[408.836927] Subtest bo-write-verify-y: SKIP (0.014s)
[408.888100] [075/128] (710s left) syncobj_timeline (invalid-signal-illegal-handle)
[408.949024] Starting subtest: invalid-signal-illegal-handle
[408.951715] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[409.061434] [076/128] (710s left) kms_plane_lowres (tiling-4)
[409.167698] Starting subtest: tiling-4
[409.170186] Starting dynamic subtest: pipe-A-eDP-1
[409.175170] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[409.466389] Starting dynamic subtest: pipe-B-eDP-1
[409.472679] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[409.483236] Starting dynamic subtest: pipe-C-eDP-1
[409.489066] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[409.497760] Starting dynamic subtest: pipe-D-eDP-1
[409.502239] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[409.506807] Subtest tiling-4: SKIP (0.290s)
[410.267556] [077/128] (709s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[410.375991] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[413.698674] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (3.330s)
[413.833564] [078/128] (705s left) kms_color (gamma)
[413.933409] Starting subtest: gamma
[413.935926] Starting dynamic subtest: pipe-A-eDP-1
[414.664686] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.728s)
[414.673809] Starting dynamic subtest: pipe-B-eDP-1
[416.066458] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.404s)
[416.076180] Starting dynamic subtest: pipe-C-eDP-1
[417.352245] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.284s)
[417.361764] Starting dynamic subtest: pipe-D-eDP-1
[418.673054] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[418.675502] Subtest gamma: SUCCESS (4.741s)
[419.503593] [079/128] (700s left) i915_hangman (engine-error-state-capture)
[419.569766] Starting subtest: engine-error-state-capture
[419.572700] Starting dynamic subtest: rcs0
[422.852538] Dynamic subtest rcs0: SUCCESS (3.283s)
[422.863922] Starting dynamic subtest: bcs0
[428.868120] Dynamic subtest bcs0: SUCCESS (6.016s)
[428.880442] Starting dynamic subtest: vcs0
[434.818301] Dynamic subtest vcs0: SUCCESS (5.949s)
[434.830204] Starting dynamic subtest: vcs1
[439.874983] Dynamic subtest vcs1: SUCCESS (5.055s)
[439.887083] Starting dynamic subtest: vecs0
[445.827742] Dynamic subtest vecs0: SUCCESS (5.950s)
[445.839036] Starting dynamic subtest: ccs0
[451.843289] Dynamic subtest ccs0: SUCCESS (6.013s)
[451.846261] Subtest engine-error-state-capture: SUCCESS (32.282s)
[451.919190] [080/128] (667s left) syncobj_timeline (etime-multi-wait-submitted)
[451.969200] Starting subtest: etime-multi-wait-submitted
[452.073612] Subtest etime-multi-wait-submitted: SUCCESS (0.104s)
[452.145735] [081/128] (667s left) gem_exec_reloc (basic-gtt-read-noreloc)
[452.208485] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[452.267458] [082/128] (667s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[452.374649] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[455.023862] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (2.654s)
[455.156256] [083/128] (664s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[455.254075] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[455.256241] Starting dynamic subtest: pipe-A-eDP-1
[457.405818] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.149s)
[457.416248] Starting dynamic subtest: pipe-B-eDP-1
[459.437896] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.031s)
[459.447209] Starting dynamic subtest: pipe-C-eDP-1
[461.444230] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.006s)
[461.452886] Starting dynamic subtest: pipe-D-eDP-1
[463.478996] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.034s)
[463.481833] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.224s)
[464.620044] [084/128] (655s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[464.720993] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[464.723759] Starting dynamic subtest: pipe-A-eDP-1
[464.753060] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[464.762802] Starting dynamic subtest: pipe-B-eDP-1
[464.790317] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[464.799462] Starting dynamic subtest: pipe-C-eDP-1
[464.830276] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[464.840141] Starting dynamic subtest: pipe-D-eDP-1
[464.870791] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[464.875686] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[466.967677] [085/128] (653s left) perf_pmu (pmu-read)
[467.018061] Starting subtest: pmu-read
[467.021220] Subtest pmu-read: SUCCESS (0.002s)
[467.119039] [086/128] (652s left) gem_pwrite (basic-self)
[467.178385] Subtest basic-self: SKIP (0.000s)
[467.240925] [087/128] (652s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[467.346426] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[467.351058] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[467.447790] [088/128] (652s left) syncobj_wait (wait-delayed-signal)
[467.508294] Starting subtest: wait-delayed-signal
[467.611857] Subtest wait-delayed-signal: SUCCESS (0.103s)
[467.684019] [089/128] (652s left) gem_mmap_wc (bad-object)
[467.758056] Subtest bad-object: SKIP (0.000s)
[467.806945] [090/128] (652s left) gem_exec_reloc (basic-write-wc)
[467.865672] Subtest basic-write-wc: SKIP (0.000s)
[467.927193] [091/128] (652s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[468.047907] Starting subtest: 2x-long-cursor-vs-flip-atomic
[468.052941] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[468.140573] [092/128] (652s left) gem_request_retire (retire-vma-not-inactive)
[468.198177] Starting subtest: retire-vma-not-inactive
[468.260227] Subtest retire-vma-not-inactive: SUCCESS (0.058s)
[468.381749] [093/128] (651s left) i915_pm_rps (fence-order)
[468.437097] Starting subtest: fence-order
[470.931126] Subtest fence-order: SUCCESS (2.492s)
[471.012522] [094/128] (649s left) gem_exec_flush (basic-wb-prw-default)
[471.088019] Starting subtest: basic-wb-prw-default
[476.574511] Subtest basic-wb-prw-default: SUCCESS (5.486s)
[476.756719] [095/128] (643s left) gem_ctx_persistence (legacy-engines-hostile)
[476.817283] Starting subtest: legacy-engines-hostile
[476.820219] Starting dynamic subtest: render
[476.893501] Dynamic subtest render: SUCCESS (0.076s)
[476.905499] Starting dynamic subtest: bsd1
[476.991534] Dynamic subtest bsd1: SUCCESS (0.096s)
[477.003373] Starting dynamic subtest: bsd2
[477.096754] Dynamic subtest bsd2: SUCCESS (0.103s)
[477.109320] Starting dynamic subtest: blt
[477.188466] Dynamic subtest blt: SUCCESS (0.090s)
[477.200858] Starting dynamic subtest: vebox
[477.300097] Dynamic subtest vebox: SUCCESS (0.109s)
[477.303181] Subtest legacy-engines-hostile: SUCCESS (0.484s)
[477.376480] [096/128] (643s left) gem_tiled_pread_basic
[477.499429] [097/128] (642s left) kms_content_protection (mei-interface)
[477.682230] Starting subtest: mei-interface
[477.687573] Subtest mei-interface: SKIP (0.001s)
[477.830453] [098/128] (642s left) gem_mmap_wc (write-gtt-read-wc)
[477.893018] Subtest write-gtt-read-wc: SKIP (0.000s)
[477.953445] [099/128] (642s left) syncobj_timeline (wait-delayed-signal)
[478.008560] Starting subtest: wait-delayed-signal
[478.107798] Subtest wait-delayed-signal: SUCCESS (0.102s)
[478.189646] [100/128] (642s left) perf (i915-ref-count)
[478.264767] Starting subtest: i915-ref-count
[478.299032] Subtest i915-ref-count: SUCCESS (0.031s)
[478.368884] [101/128] (642s left) kms_cursor_edge_walk (256x256-left-edge)
[478.465636] Starting subtest: 256x256-left-edge
[478.467819] Starting dynamic subtest: pipe-A-eDP-1
[481.939203] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.472s)
[481.948459] Starting dynamic subtest: pipe-D-eDP-1
[485.036112] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.095s)
[485.039228] Subtest 256x256-left-edge: SUCCESS (6.569s)
[485.851452] [102/128] (634s left) kms_psr (fbc-psr-primary-mmap-gtt)
[485.961199] Starting subtest: fbc-psr-primary-mmap-gtt
[485.963384] Starting dynamic subtest: eDP-1
[485.967707] Dynamic subtest eDP-1: SKIP (0.000s)
[485.972120] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[486.061259] [103/128] (634s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[486.127598] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[486.233729] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[486.311191] [104/128] (634s left) gem_render_copy_redux (flink)
[486.370816] Starting subtest: flink
[487.308625] Subtest flink: SUCCESS (0.937s)
[487.437471] [105/128] (633s left) core_getversion (all-cards)
[487.475304] Starting subtest: all-cards
[489.669216] Subtest all-cards: SUCCESS (2.198s)
[489.709706] [FACT core_getversion (all-cards)] new: kernel.kmod_is_loaded.amdgpu: true
[489.710363] [FACT core_getversion (all-cards)] new: kernel.kmod_is_loaded.xe: true
[489.713994] [106/128] (631s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[489.820245] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[489.825095] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[489.912495] [107/128] (630s left) gem_ctx_shared (q-smoketest-all)
[489.981407] Starting subtest: Q-smoketest-all
[522.444358] Subtest Q-smoketest-all: SUCCESS (32.392s)
[523.971798] [108/128] (596s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[524.099237] Starting subtest: cursor-vs-flip-varying-size
[554.451840] Subtest cursor-vs-flip-varying-size: SUCCESS (30.351s)
[555.280496] [109/128] (565s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[555.388221] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[555.393343] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[555.491690] [110/128] (565s left) gem_ringfill (basic-all)
[560.873442] Starting subtest: basic-all
[562.053881] Subtest basic-all: SUCCESS (1.180s)
[562.172499] [111/128] (558s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[562.282967] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[562.288090] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[562.389548] [112/128] (558s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[562.780198] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[563.583858] [113/128] (557s left) kms_busy (extended-modeset-hang-newfb)
[563.677814] Starting subtest: extended-modeset-hang-newfb
[563.680420] Starting dynamic subtest: pipe-A
[598.740965] Dynamic subtest pipe-A: SUCCESS (35.059s)
[598.750939] Starting dynamic subtest: pipe-D
[621.764044] Dynamic subtest pipe-D: SUCCESS (23.019s)
[621.767193] Subtest extended-modeset-hang-newfb: SUCCESS (58.082s)
[622.548330] [114/128] (498s left) gem_exec_params (readonly)
[622.607972] Starting subtest: readonly
[622.610683] Subtest readonly: SUCCESS (0.003s)
[622.727609] [115/128] (498s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[622.828188] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[627.313022] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (4.487s)
[627.430649] [116/128] (493s left) gem_wait (busy)
[627.494636] Starting subtest: busy
[627.497188] Starting dynamic subtest: all-engines
[628.901619] Dynamic subtest all-engines: SUCCESS (1.405s)
[628.911227] Starting dynamic subtest: rcs0
[629.443098] Dynamic subtest rcs0: SUCCESS (0.541s)
[629.453540] Starting dynamic subtest: bcs0
[629.984197] Dynamic subtest bcs0: SUCCESS (0.540s)
[629.994618] Starting dynamic subtest: vcs0
[631.909212] Dynamic subtest vcs0: SUCCESS (1.923s)
[631.920494] Starting dynamic subtest: vcs1
[633.892977] Dynamic subtest vcs1: SUCCESS (1.981s)
[633.904891] Starting dynamic subtest: vecs0
[635.877250] Dynamic subtest vecs0: SUCCESS (1.981s)
[635.888027] Starting dynamic subtest: ccs0
[636.420101] Dynamic subtest ccs0: SUCCESS (0.542s)
[636.423027] Subtest busy: SUCCESS (8.925s)
[636.618842] [117/128] (484s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[636.724921] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[636.727471] Starting dynamic subtest: pipe-A-eDP-1
[636.775701] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.050s)
[636.785810] Starting dynamic subtest: pipe-B-eDP-1
[636.830035] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.053s)
[636.839834] Starting dynamic subtest: pipe-C-eDP-1
[636.882232] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.052s)
[636.891741] Starting dynamic subtest: pipe-D-eDP-1
[636.937168] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.054s)
[636.939952] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.213s)
[639.059705] [118/128] (482s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[639.168396] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[643.808532] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (4.642s)
[643.951233] [119/128] (477s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[644.085635] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[644.123448] [120/128] (477s left) gen3_render_linear_blits
[644.227344] [121/128] (477s left) gem_userptr_blits (huge-split)
[644.314097] Starting subtest: huge-split
[644.322995] Subtest huge-split: SUCCESS (0.008s)
[644.467978] [122/128] (476s left) gem_exec_balancer (hog)
[644.540994] Starting subtest: hog
[644.546278] Subtest hog: SKIP (0.000s)
[644.821300] [123/128] (476s left) kms_psr (psr2-cursor-render)
[644.918043] Starting subtest: psr2-cursor-render
[644.920838] Starting dynamic subtest: eDP-1
[646.570504] Dynamic subtest eDP-1: SUCCESS (1.650s)
[646.572789] Subtest psr2-cursor-render: SUCCESS (1.650s)
[647.351792] [124/128] (474s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[647.457057] Starting subtest: x-tiled-32bpp-rotate-180
[649.267148] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.813s)
[649.428858] [125/128] (472s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[654.414066] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[654.419611] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s)
[655.261608] [126/128] (466s left) core_setmaster_vs_auth
[655.419566] [127/128] (466s left) gem_mmap_wc (fault-concurrent)
[655.480234] Subtest fault-concurrent: SKIP (0.000s)
[655.551824] [128/128] (466s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[655.659958] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[656.961521] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (1.303s)
[657.158396] Closing watchdogs
Done.