[54.946975] Initializing watchdogs
[54.947492]   /dev/watchdog0
[54.979738] [001/142] (960s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[55.433321] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[55.571660] [002/142] (959s left) gem_exec_fence (basic-wait-all)
[55.984364] Starting subtest: basic-wait-all
[56.110342] Subtest basic-wait-all: SUCCESS (0.125s)
[58.682063] [003/142] (956s left) kms_lease (lease_unleased_crtc)
[59.107409] Starting subtest: lease_unleased_crtc
[59.110526] Starting dynamic subtest: pipe-A-eDP-1
[59.481708] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.371s)
[59.510895] Starting dynamic subtest: pipe-B-eDP-1
[60.280093] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.794s)
[60.294650] Starting dynamic subtest: pipe-C-eDP-1
[60.302751] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.016s)
[60.319378] Starting dynamic subtest: pipe-D-eDP-1
[60.322517] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.017s)
[60.325568] Subtest lease_unleased_crtc: SUCCESS (1.215s)
[60.844557] [004/142] (954s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc)
[62.467781] Starting subtest: pipe-D-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc
[62.472511] Subtest pipe-D-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[63.084466] [005/142] (952s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[63.587847] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[63.594682] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[63.744493] [006/142] (952s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[64.105746] Starting subtest: multi-wait-for-submit-available-signaled
[64.109330] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.003s)
[64.234328] [007/142] (951s left) gem_exec_store (pages)
[64.643392] Starting subtest: pages
[64.646295] Starting dynamic subtest: rcs0
[64.881411] Dynamic subtest rcs0: SUCCESS (0.238s)
[64.913145] Starting dynamic subtest: bcs0
[65.118099] Dynamic subtest bcs0: SUCCESS (0.235s)
[65.154525] Starting dynamic subtest: vcs0
[66.525721] Dynamic subtest vcs0: SUCCESS (1.406s)
[66.563928] Starting dynamic subtest: vcs1
[68.508661] Dynamic subtest vcs1: SUCCESS (1.982s)
[68.539049] Starting dynamic subtest: vecs0
[70.493434] Dynamic subtest vecs0: SUCCESS (1.984s)
[70.523779] Starting dynamic subtest: ccs0
[70.712722] Dynamic subtest ccs0: SUCCESS (0.221s)
[70.716260] Subtest pages: SUCCESS (6.070s)
[70.997614] [008/142] (944s left) gem_exec_balancer (bonded-false-hang)
[72.236847] Starting subtest: bonded-false-hang
[72.243943] Subtest bonded-false-hang: SKIP (0.000s)
[72.420825] [009/142] (943s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[72.794715] Starting subtest: invalid-multi-wait-unsubmitted
[72.798271] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[72.955704] [010/142] (943s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[73.445399] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt
[73.451585] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[73.598860] [011/142] (942s left) kms_vblank (pipe-c-ts-continuation-dpms-suspend)
[74.059875] Starting subtest: pipe-C-ts-continuation-dpms-suspend
[93.178412] Subtest pipe-C-ts-continuation-dpms-suspend: SUCCESS (19.118s)
[94.129334] [012/142] (922s left) gem_exec_suspend (basic)
[94.530052] Starting subtest: basic
[94.533463] Starting dynamic subtest: smem
[96.474813] Dynamic subtest smem: SUCCESS (1.944s)
[96.477939] Subtest basic: SUCCESS (1.944s)
[96.660307] [013/142] (919s left) gem_mmap_gtt (flink-race)
[96.987600] Subtest flink-race: SKIP (0.000s)
[97.093171] [014/142] (919s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[97.623369] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[97.629605] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[97.793518] [015/142] (918s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[100.686435] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[100.834969] [016/142] (915s left) vgem_basic (dmabuf-export)
[101.209424] Starting subtest: dmabuf-export
[101.228875] Subtest dmabuf-export: SUCCESS (0.024s)
[101.381088] [017/142] (915s left) kms_vblank (pipe-c-wait-busy-hang)
[101.871315] Starting subtest: pipe-C-wait-busy-hang
[117.492056] Subtest pipe-C-wait-busy-hang: SUCCESS (15.616s)
[118.413905] [018/142] (898s left) kms_vblank (pipe-d-ts-continuation-dpms-suspend)
[118.897478] Starting subtest: pipe-D-ts-continuation-dpms-suspend
[139.222379] Subtest pipe-D-ts-continuation-dpms-suspend: SUCCESS (20.328s)
[140.167444] [019/142] (876s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_ccs)
[141.598219] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_ccs
[141.603924] Subtest pipe-C-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[142.429534] [020/142] (874s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-factor-0-25)
[142.905952] Starting subtest: planes-downscale-factor-0-25-upscale-factor-0-25
[142.913347] Starting dynamic subtest: pipe-A-eDP-1
[143.014625] Dynamic subtest pipe-A-eDP-1: SKIP (0.097s)
[143.056464] Starting dynamic subtest: pipe-B-eDP-1
[143.223756] Dynamic subtest pipe-B-eDP-1: SKIP (0.198s)
[143.263048] Starting dynamic subtest: pipe-C-eDP-1
[143.420271] Dynamic subtest pipe-C-eDP-1: SKIP (0.190s)
[143.458056] Starting dynamic subtest: pipe-D-eDP-1
[143.617924] Dynamic subtest pipe-D-eDP-1: SKIP (0.189s)
[143.624261] Subtest planes-downscale-factor-0-25-upscale-factor-0-25: SKIP (0.707s)
[143.776136] [021/142] (873s left) kms_big_fb (linear-64bpp-rotate-90)
[144.305323] Starting subtest: linear-64bpp-rotate-90
[144.364189] Subtest linear-64bpp-rotate-90: SKIP (0.057s)
[144.547506] [022/142] (872s left) kms_flip (2x-absolute-wf_vblank)
[144.936257] Starting subtest: 2x-absolute-wf_vblank
[145.093568] Subtest 2x-absolute-wf_vblank: SKIP (0.158s)
[145.282816] [023/142] (871s left) kms_ccs (pipe-b-bad-pixel-format-yf_tiled_ccs)
[146.386809] Starting subtest: pipe-B-bad-pixel-format-yf_tiled_ccs
[146.393111] Subtest pipe-B-bad-pixel-format-yf_tiled_ccs: SKIP (0.000s)
[147.678305] [024/142] (869s left) i915_pm_rpm (dpms-lpsp)
[154.143351] Starting subtest: dpms-lpsp
[154.874947] Subtest dpms-lpsp: SUCCESS (0.731s)
[155.065992] [025/142] (862s left) i915_pm_backlight (fade-with-dpms)
[155.529491] Starting subtest: fade-with-dpms
[158.089487] Starting dynamic subtest: eDP-1
[161.010170] Dynamic subtest eDP-1: SUCCESS (2.918s)
[161.013390] Subtest fade-with-dpms: SUCCESS (5.483s)
[162.162985] [026/142] (855s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[162.676696] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[176.501135] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (13.827s)
[176.762733] [027/142] (840s left) gem_mmap_offset (oob-read)
[177.128202] Starting subtest: oob-read
[177.132247] Subtest oob-read: SUCCESS (0.003s)
[177.293976] [028/142] (840s left) kms_flip (2x-flip-vs-dpms)
[177.672558] Starting subtest: 2x-flip-vs-dpms
[177.834123] Subtest 2x-flip-vs-dpms: SKIP (0.159s)
[178.029073] [029/142] (839s left) prime_busy (hang)
[178.410282] Starting subtest: hang
[178.413874] Starting dynamic subtest: rcs0
[192.378218] Dynamic subtest rcs0: SUCCESS (13.961s)
[192.418845] Starting dynamic subtest: bcs0
[199.500859] Dynamic subtest bcs0: SUCCESS (7.120s)
[199.540887] Starting dynamic subtest: vcs0
[206.569756] Dynamic subtest vcs0: SUCCESS (7.068s)
[206.608882] Starting dynamic subtest: vcs1
[213.553318] Dynamic subtest vcs1: SUCCESS (6.981s)
[213.595891] Starting dynamic subtest: vecs0
[220.482115] Dynamic subtest vecs0: SUCCESS (6.929s)
[220.524077] Starting dynamic subtest: ccs0
[234.364200] Dynamic subtest ccs0: SUCCESS (13.882s)
[234.367911] Subtest hang: SUCCESS (55.951s)
[234.524440] [030/142] (783s left) gem_close_race (multigpu-basic-process)
[234.963634] Starting subtest: multigpu-basic-process
[234.969291] Subtest multigpu-basic-process: SKIP (0.000s)
[235.266738] [031/142] (782s left) prime_mmap (test_correct_cpu_write)
[235.639455] Starting subtest: test_correct_cpu_write
[235.643605] Starting dynamic subtest: test_correct_cpu_write-smem
[235.647158] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.002s)
[235.650573] Subtest test_correct_cpu_write: SUCCESS (0.004s)
[235.814937] [032/142] (781s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[236.375743] Starting subtest: 4-tiled-16bpp-rotate-180
[240.284825] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (3.906s)
[240.696185] [033/142] (777s left) gem_linear_blits (normal)
[243.798529] Starting subtest: normal
[265.158869] Subtest normal: SUCCESS (21.360s)
[265.877475] [034/142] (752s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_gen12_rc_ccs_cc)
[266.950180] Starting subtest: pipe-A-bad-rotation-90-y_tiled_gen12_rc_ccs_cc
[266.956917] Subtest pipe-A-bad-rotation-90-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[268.078215] [035/142] (749s left) gen9_exec_parse (bb-start-out)
[268.470329] Subtest bb-start-out: SKIP (0.000s)
[268.585583] [036/142] (749s left) gem_reg_read (timestamp-monotonic)
[268.954484] Starting subtest: timestamp-monotonic
[273.543561] Subtest timestamp-monotonic: SUCCESS (4.589s)
[273.695432] [037/142] (744s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[274.191270] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[274.199690] Starting dynamic subtest: pipe-A-default-mode
[274.591462] Dynamic subtest pipe-A-default-mode: SKIP (0.384s)
[274.599562] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.394s)
[275.529567] [038/142] (742s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[276.032636] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[284.942439] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (8.915s)
[285.131128] [039/142] (733s left) gem_ctx_engines (none)
[285.385478] Starting subtest: none
[285.392676] Subtest none: SUCCESS (0.005s)
[285.587851] [040/142] (732s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_mtl_rc_ccs)
[286.825340] Starting subtest: pipe-D-bad-pixel-format-4_tiled_mtl_rc_ccs
[286.829217] Subtest pipe-D-bad-pixel-format-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[287.616284] [041/142] (730s left) perf_pmu (event-wait)
[288.006454] Starting subtest: event-wait
[288.010216] Starting dynamic subtest: rcs0
[288.017239] Dynamic subtest rcs0: SKIP (0.000s)
[288.023880] Subtest event-wait: SKIP (0.003s)
[288.185929] [042/142] (730s left) gem_exec_schedule (preempt-hang)
[288.766538] Starting subtest: preempt-hang
[288.769431] Starting dynamic subtest: rcs0
[289.703757] Dynamic subtest rcs0: SUCCESS (0.937s)
[289.752887] Starting dynamic subtest: bcs0
[290.610737] Dynamic subtest bcs0: SUCCESS (0.905s)
[290.652063] Starting dynamic subtest: vcs0
[291.274356] Dynamic subtest vcs0: SUCCESS (0.661s)
[291.319243] Starting dynamic subtest: vcs1
[291.925971] Dynamic subtest vcs1: SUCCESS (0.650s)
[291.969920] Starting dynamic subtest: vecs0
[292.717045] Dynamic subtest vecs0: SUCCESS (0.787s)
[292.762161] Starting dynamic subtest: ccs0
[293.607328] Dynamic subtest ccs0: SUCCESS (0.887s)
[293.610248] Subtest preempt-hang: SUCCESS (4.842s)
[294.004152] [043/142] (724s left) gem_exec_balancer (invalid-balancer)
[294.425937] Starting subtest: invalid-balancer
[298.888808] Subtest invalid-balancer: SUCCESS (4.463s)
[299.918895] [044/142] (718s left) syncobj_timeline (invalid-query-zero-handles)
[300.290519] Starting subtest: invalid-query-zero-handles
[300.294053] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[300.471490] [045/142] (718s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[300.852735] Starting subtest: invalid-single-wait-all-unsubmitted
[300.856311] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[301.028354] [046/142] (717s left) perf_pmu (all-busy-check-all)
[301.430294] Starting subtest: all-busy-check-all
[302.491338] Subtest all-busy-check-all: SUCCESS (1.065s)
[302.675397] [047/142] (716s left) gem_userptr_blits (process-exit-busy)
[303.127851] Starting subtest: process-exit-busy
[303.222700] Subtest process-exit-busy: SUCCESS (0.095s)
[303.480617] [048/142] (715s left) gem_exec_fence (syncobj-timeline-unused-fence)
[305.592784] Starting subtest: syncobj-timeline-unused-fence
[305.611175] Subtest syncobj-timeline-unused-fence: SUCCESS (0.020s)
[305.939360] [049/142] (713s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[306.386553] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[306.393452] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[306.557160] [050/142] (712s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[307.107569] Starting subtest: flip-vs-cursor-busy-crc-legacy
[309.127108] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (2.019s)
[310.083886] [051/142] (709s left) kms_cursor_crc (cursor-offscreen-64x64)
[310.849300] Starting subtest: cursor-offscreen-64x64
[310.883890] Starting dynamic subtest: pipe-A-eDP-1
[313.631885] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.748s)
[313.827394] Starting dynamic subtest: pipe-D-eDP-1
[317.111097] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.328s)
[317.114999] Subtest cursor-offscreen-64x64: SUCCESS (6.262s)
[318.365588] [052/142] (701s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[319.263608] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[320.139778] [053/142] (699s left) gem_exec_reloc (basic-range)
[320.533945] Subtest basic-range: SKIP (0.000s)
[320.685613] [054/142] (698s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[321.165318] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[321.172442] Starting dynamic subtest: pipe-A-default-mode
[321.583535] Dynamic subtest pipe-A-default-mode: SKIP (0.406s)
[321.590333] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.419s)
[322.518449] [055/142] (697s left) i915_pm_rps (reset)
[322.927929] Starting subtest: reset
[323.036100] Subtest reset: FAIL (0.067s)
[323.125500] [056/142] (696s left) perf_pmu (gt-awake)
[323.515431] Starting subtest: gt-awake
[332.510165] Subtest gt-awake: SUCCESS (8.994s)
[332.715746] [057/142] (687s left) gem_exec_schedule (smoketest)
[333.316214] Starting subtest: smoketest
[333.320107] Starting dynamic subtest: rcs0
[339.323127] Dynamic subtest rcs0: SUCCESS (5.930s)
[339.364313] Starting dynamic subtest: bcs0
[345.236571] Dynamic subtest bcs0: SUCCESS (5.915s)
[345.281423] Starting dynamic subtest: vcs0
[351.199596] Dynamic subtest vcs0: SUCCESS (5.950s)
[351.241683] Starting dynamic subtest: vcs1
[357.142257] Dynamic subtest vcs1: SUCCESS (5.944s)
[357.179313] Starting dynamic subtest: vecs0
[362.995752] Dynamic subtest vecs0: SUCCESS (5.846s)
[363.038120] Starting dynamic subtest: ccs0
[368.796342] Dynamic subtest ccs0: SUCCESS (5.804s)
[368.799209] Subtest smoketest: SUCCESS (35.411s)
[369.100396] [058/142] (650s left) v3d/v3d_submit_cl (bad-multisync-extension)
[369.629288] Subtest bad-multisync-extension: SKIP (0.000s)
[369.776402] [059/142] (650s left) gem_exec_params (cliprects_ptr-dirt)
[370.178772] Starting subtest: cliprects_ptr-dirt
[370.182391] Subtest cliprects_ptr-dirt: SUCCESS (0.001s)
[370.379667] [060/142] (649s left) kms_vblank (pipe-a-query-busy)
[370.869348] Starting subtest: pipe-A-query-busy
[372.930148] Subtest pipe-A-query-busy: SUCCESS (2.060s)
[373.884250] [061/142] (646s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[374.425141] Starting subtest: 4-tiled-8bpp-rotate-0
[377.936486] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (3.510s)
[378.252549] [062/142] (642s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[378.755810] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[378.762567] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[378.893443] [063/142] (641s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[379.804359] Starting subtest: pipe-A-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[379.811524] Subtest pipe-A-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[381.044996] [064/142] (639s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[381.523645] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[381.532582] Starting dynamic subtest: pipe-A-eDP-1
[383.065333] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.534s)
[383.119522] Starting dynamic subtest: pipe-B-eDP-1
[385.883323] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.807s)
[385.936474] Starting dynamic subtest: pipe-C-eDP-1
[388.675832] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.783s)
[388.734808] Starting dynamic subtest: pipe-D-eDP-1
[391.561705] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.874s)
[391.564864] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (10.040s)
[393.051740] [065/142] (627s left) gen7_exec_parse (basic-rejected)
[393.457556] Subtest basic-rejected: SKIP (0.000s)
[393.588671] [066/142] (627s left) sysfs_heartbeat_interval (invalid)
[393.986370] Starting subtest: invalid
[393.997646] Starting dynamic subtest: vecs0
[394.001798] Dynamic subtest vecs0: SUCCESS (0.001s)
[394.055499] Starting dynamic subtest: ccs0
[394.058628] Dynamic subtest ccs0: SUCCESS (0.001s)
[394.107085] Starting dynamic subtest: vcs0
[394.110635] Dynamic subtest vcs0: SUCCESS (0.001s)
[394.158316] Starting dynamic subtest: bcs0
[394.161862] Dynamic subtest bcs0: SUCCESS (0.001s)
[394.208997] Starting dynamic subtest: rcs0
[394.212583] Dynamic subtest rcs0: SUCCESS (0.001s)
[394.260251] Starting dynamic subtest: vcs1
[394.263858] Dynamic subtest vcs1: SUCCESS (0.001s)
[394.267642] Subtest invalid: SUCCESS (0.041s)
[394.336222] [067/142] (626s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[394.857575] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[394.864105] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[395.025691] [068/142] (625s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_ccs)
[396.359565] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_ccs
[396.375802] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.013s)
[397.456284] [069/142] (623s left) kms_scaling_modes (scaling-mode-full)
[397.931327] Starting subtest: scaling-mode-full
[397.934812] Starting dynamic subtest: eDP-1-pipe-A
[398.320521] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.387s)
[398.389789] Starting dynamic subtest: eDP-1-pipe-B
[399.959068] Dynamic subtest eDP-1-pipe-B: SUCCESS (1.626s)
[400.020475] Starting dynamic subtest: eDP-1-pipe-C
[401.613911] Dynamic subtest eDP-1-pipe-C: SUCCESS (1.644s)
[401.669273] Starting dynamic subtest: eDP-1-pipe-D
[403.246154] Dynamic subtest eDP-1-pipe-D: SUCCESS (1.623s)
[403.250085] Subtest scaling-mode-full: SUCCESS (5.320s)
[404.772380] [070/142] (616s left) kms_busy (extended-modeset-hang-oldfb)
[405.286347] Starting subtest: extended-modeset-hang-oldfb
[405.294772] Starting dynamic subtest: pipe-A
[421.577760] Dynamic subtest pipe-A: SUCCESS (16.281s)
[421.644808] Starting dynamic subtest: pipe-D
[438.000382] Dynamic subtest pipe-D: SUCCESS (16.397s)
[438.003943] Subtest extended-modeset-hang-oldfb: SUCCESS (32.715s)
[438.957967] [071/142] (582s left) gem_exec_flush (basic-uc-set-default)
[439.379743] Starting subtest: basic-uc-set-default
[445.266808] Subtest basic-uc-set-default: SUCCESS (5.891s)
[445.585808] [072/142] (575s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[445.994331] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[446.000792] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[446.310808] [073/142] (575s left) kms_flip (dpms-vs-vblank-race-interruptible)
[446.747997] Starting subtest: dpms-vs-vblank-race-interruptible
[446.781337] Starting dynamic subtest: A-eDP1
[451.554474] Dynamic subtest A-eDP1: SUCCESS (4.769s)
[451.596846] Starting dynamic subtest: B-eDP1
[455.518093] Dynamic subtest B-eDP1: SUCCESS (3.958s)
[455.562239] Starting dynamic subtest: C-eDP1
[459.502787] Dynamic subtest C-eDP1: SUCCESS (3.979s)
[459.544567] Starting dynamic subtest: D-eDP1
[463.459820] Dynamic subtest D-eDP1: SUCCESS (3.950s)
[463.478250] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (16.730s)
[464.407454] [074/142] (557s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[464.914641] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[464.921359] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[465.094314] [075/142] (556s left) vc4/vc4_purgeable_bo (free-purged-bo)
[465.570777] Subtest free-purged-bo: SKIP (0.000s)
[465.707792] [076/142] (555s left) kms_cursor_crc (cursor-random-64x64)
[466.444157] Starting subtest: cursor-random-64x64
[466.479291] Starting dynamic subtest: pipe-A-eDP-1
[472.644152] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.165s)
[472.844733] Starting dynamic subtest: pipe-D-eDP-1
[480.091124] Dynamic subtest pipe-D-eDP-1: SUCCESS (7.294s)
[480.094579] Subtest cursor-random-64x64: SUCCESS (13.648s)
[481.328486] [077/142] (540s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[481.861377] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[481.867347] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[482.032599] [078/142] (539s left) gem_ctx_shared (disjoint-timelines)
[482.457491] Starting subtest: disjoint-timelines
[482.503554] Subtest disjoint-timelines: SUCCESS (0.046s)
[482.829554] [079/142] (539s left) gen3_render_mixed_blits
[483.321905] [080/142] (538s left) prime_vgem (basic-read)
[483.734058] Starting subtest: basic-read
[483.762917] Subtest basic-read: SKIP (0.027s)
[483.927410] [081/142] (538s left) kms_flip (flip-vs-absolute-wf_vblank)
[484.320998] Starting subtest: flip-vs-absolute-wf_vblank
[484.348496] Starting dynamic subtest: A-eDP1
[493.829670] Dynamic subtest A-eDP1: SUCCESS (9.481s)
[493.895971] Starting dynamic subtest: B-eDP1
[503.156826] Dynamic subtest B-eDP1: SUCCESS (9.321s)
[503.214093] Starting dynamic subtest: C-eDP1
[512.474800] Dynamic subtest C-eDP1: SUCCESS (9.309s)
[512.531905] Starting dynamic subtest: D-eDP1
[521.804401] Dynamic subtest D-eDP1: SUCCESS (9.324s)
[521.831313] Subtest flip-vs-absolute-wf_vblank: SUCCESS (37.514s)
[523.429277] [082/142] (498s left) kms_psr2_sf (overlay-plane-move-continuous-exceed-fully-sf)
[530.774143] Starting subtest: overlay-plane-move-continuous-exceed-fully-sf
[530.786548] Starting dynamic subtest: pipe-A-eDP-1
[548.983826] Dynamic subtest pipe-A-eDP-1: SUCCESS (18.199s)
[549.035859] Starting dynamic subtest: pipe-B-eDP-1
[567.199920] Dynamic subtest pipe-B-eDP-1: SUCCESS (18.206s)
[567.203604] Subtest overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (36.425s)
[568.158315] [083/142] (454s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[568.723238] Starting subtest: cursorB-vs-flipA-varying-size
[568.739773] Subtest cursorB-vs-flipA-varying-size: SKIP (0.016s)
[569.089735] [084/142] (453s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[569.591292] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[575.017511] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (5.435s)
[575.306277] [085/142] (447s left) v3d/v3d_get_param (get-bad-param)
[575.774649] Subtest get-bad-param: SKIP (0.000s)
[575.908959] [086/142] (446s left) gem_ctx_persistence (heartbeat-many)
[576.312273] Starting subtest: heartbeat-many
[576.318527] Subtest heartbeat-many: SKIP (0.001s)
[576.493657] [087/142] (446s left) gem_exec_reloc (basic-scanout)
[576.880700] Subtest basic-scanout: SKIP (0.000s)
[577.021133] [088/142] (445s left) kms_vblank (pipe-a-accuracy-idle)
[577.491194] Starting subtest: pipe-A-accuracy-idle
[579.429533] Subtest pipe-A-accuracy-idle: SUCCESS (1.940s)
[580.386701] [089/142] (442s left) device_reset (cold-reset-bound)
[581.202704] Subtest cold-reset-bound: SKIP (0.000s)
[581.343456] [090/142] (441s left) perf_pmu (cpu-hotplug)
[581.729619] Starting subtest: cpu-hotplug
[581.744856] Subtest cpu-hotplug: SKIP (0.012s)
[581.924166] [091/142] (441s left) drm_fdinfo (busy-idle)
[582.324370] Starting subtest: busy-idle
[582.327814] Starting dynamic subtest: rcs0
[582.334567] Dynamic subtest rcs0: SKIP (0.001s)
[582.388536] Starting dynamic subtest: bcs0
[582.394925] Dynamic subtest bcs0: SKIP (0.001s)
[582.444067] Starting dynamic subtest: vcs0
[582.450257] Dynamic subtest vcs0: SKIP (0.001s)
[582.497803] Starting dynamic subtest: vcs1
[582.504144] Dynamic subtest vcs1: SKIP (0.001s)
[582.551177] Starting dynamic subtest: vecs0
[582.557126] Dynamic subtest vecs0: SKIP (0.001s)
[582.603819] Starting dynamic subtest: ccs0
[582.609969] Dynamic subtest ccs0: SKIP (0.001s)
[582.616154] Subtest busy-idle: SKIP (0.030s)
[582.683606] [092/142] (440s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs)
[584.475191] Starting subtest: pipe-D-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs
[605.348365] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SUCCESS (20.873s)
[606.778941] [093/142] (416s left) gem_userptr_blits (coherency-sync)
[607.229968] Starting subtest: coherency-sync
[608.679478] Subtest coherency-sync: SKIP (1.416s)
[608.909141] [094/142] (414s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_rc_ccs)
[610.243980] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs
[611.845699] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs: SUCCESS (1.607s)
[613.415711] [095/142] (409s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[613.836388] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[613.842732] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[614.114312] [096/142] (409s left) gem_softpin (noreloc)
[614.522285] Starting subtest: noreloc
[620.306656] Subtest noreloc: SUCCESS (5.787s)
[620.506298] [097/142] (402s left) gem_exec_fence (concurrent)
[621.091451] Starting subtest: concurrent
[621.098171] Subtest concurrent: SKIP (0.000s)
[622.976143] [098/142] (400s left) kms_color (degamma)
[623.475248] Starting subtest: degamma
[623.478963] Starting dynamic subtest: pipe-A
[625.011812] Dynamic subtest pipe-A: SUCCESS (1.535s)
[625.058938] Starting dynamic subtest: pipe-B
[627.210652] Dynamic subtest pipe-B: SUCCESS (2.193s)
[627.256229] Starting dynamic subtest: pipe-C
[629.733631] Dynamic subtest pipe-C: SUCCESS (2.521s)
[629.779384] Starting dynamic subtest: pipe-D
[632.471338] Dynamic subtest pipe-D: SUCCESS (2.731s)
[632.474455] Subtest degamma: SUCCESS (8.996s)
[633.417162] [099/142] (390s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[633.977315] Starting subtest: y-tiled-8bpp-rotate-0
[633.984292] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[634.148666] [100/142] (389s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[634.539912] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[634.642150] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.102s)
[634.794601] [101/142] (389s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[635.310758] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[644.427549] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (9.121s)
[644.719832] [102/142] (379s left) kms_vblank (pipe-d-query-idle)
[645.203920] Starting subtest: pipe-D-query-idle
[649.104498] Subtest pipe-D-query-idle: SUCCESS (3.904s)
[650.068692] [103/142] (373s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[650.445525] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[650.561216] [104/142] (373s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[651.066197] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[651.073350] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[651.242368] [105/142] (372s left) kms_cursor_edge_walk (64x64-right-edge)
[651.727382] Starting subtest: 64x64-right-edge
[651.731676] Starting dynamic subtest: pipe-A-eDP-1
[655.646597] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.912s)
[655.702185] Starting dynamic subtest: pipe-D-eDP-1
[659.096306] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.441s)
[659.100095] Subtest 64x64-right-edge: SUCCESS (7.375s)
[660.045226] [106/142] (364s left) kms_ccs (pipe-d-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[661.817367] Starting subtest: pipe-D-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[661.821053] Subtest pipe-D-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SUCCESS (0.003s)
[662.474200] [107/142] (361s left) kms_chamelium_hpd (dp-hpd-storm)
[663.369513] Subtest dp-hpd-storm: SKIP (0.000s)
[664.246757] [108/142] (360s left) vc4/vc4_label_bo (set-bad-name)
[664.728352] Subtest set-bad-name: SKIP (0.000s)
[664.866520] [109/142] (359s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[665.371365] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[665.378394] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[665.540620] [110/142] (359s left) gem_ppgtt (flink-and-close-vma-leak)
[665.938447] Starting subtest: flink-and-close-vma-leak
[665.995563] Subtest flink-and-close-vma-leak: SUCCESS (0.057s)
[666.187494] [111/142] (358s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[666.696742] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[679.585732] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (12.891s)
[679.890725] [112/142] (344s left) kms_getfb (getfb-handle-closed)
[680.264974] Starting subtest: getfb-handle-closed
[680.268865] Subtest getfb-handle-closed: SUCCESS (0.000s)
[680.435202] [113/142] (344s left) kms_psr (sprite_plane_onoff)
[680.934507] Starting subtest: sprite_plane_onoff
[684.165175] Subtest sprite_plane_onoff: SUCCESS (3.230s)
[685.103158] [114/142] (339s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[685.628869] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[701.971973] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (16.351s)
[702.259411] [115/142] (322s left) kms_invalid_mode (int-max-clock)
[702.746987] Starting subtest: int-max-clock
[702.750740] Starting dynamic subtest: eDP-1-pipe-A
[703.179890] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.429s)
[703.237345] Starting dynamic subtest: eDP-1-pipe-B
[703.241265] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.041s)
[703.290306] Starting dynamic subtest: eDP-1-pipe-C
[703.294145] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.041s)
[703.342327] Starting dynamic subtest: eDP-1-pipe-D
[703.346142] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.041s)
[703.349620] Subtest int-max-clock: SUCCESS (0.590s)
[704.105988] [116/142] (321s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[704.602759] Starting subtest: psr-1p-primscrn-cur-indfb-move
[716.259961] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (11.663s)
[716.543564] [117/142] (308s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[717.029332] Starting subtest: primary-yf-tiled-reflect-x-180
[717.186812] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.158s)
[717.445763] [118/142] (307s left) kms_addfb_basic (bad-pitch-0)
[717.824994] Starting subtest: bad-pitch-0
[717.828492] Subtest bad-pitch-0: SUCCESS (0.000s)
[718.111162] [119/142] (307s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[718.491399] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[718.762211] [120/142] (306s left) kms_3d
[728.007644] [121/142] (297s left) i915_pm_rpm (gem-execbuf)
[730.744319] Starting subtest: gem-execbuf
[730.747992] Starting dynamic subtest: smem0
[736.576814] Dynamic subtest smem0: SUCCESS (5.830s)
[736.579657] Subtest gem-execbuf: SUCCESS (5.833s)
[736.780983] [122/142] (288s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[737.293452] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[737.300622] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[737.466931] [123/142] (288s left) gem_spin_batch (resubmit-new-all)
[737.887403] Starting subtest: resubmit-new-all
[737.890542] Starting dynamic subtest: rcs0
[737.924817] Dynamic subtest rcs0: SUCCESS (0.035s)
[737.983376] Starting dynamic subtest: bcs0
[737.987602] Dynamic subtest bcs0: SUCCESS (0.023s)
[738.038324] Starting dynamic subtest: vcs0
[738.042303] Dynamic subtest vcs0: SUCCESS (0.023s)
[738.092635] Starting dynamic subtest: vcs1
[738.096438] Dynamic subtest vcs1: SUCCESS (0.023s)
[738.145671] Starting dynamic subtest: vecs0
[738.149413] Dynamic subtest vecs0: SUCCESS (0.025s)
[738.198756] Starting dynamic subtest: ccs0
[738.202815] Dynamic subtest ccs0: SUCCESS (0.023s)
[738.206606] Subtest resubmit-new-all: SUCCESS (0.179s)
[739.630637] [124/142] (286s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[740.163293] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[745.500529] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: FAIL (4.463s)
[745.581101] [125/142] (280s left) api_intel_bb (object-noreloc-keep-cache-simple)
[745.951644] Starting subtest: object-noreloc-keep-cache-simple
[745.968347] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.019s)
[746.157048] [126/142] (279s left) gem_exec_reloc (basic-write-cpu)
[746.560805] Subtest basic-write-cpu: SKIP (0.000s)
[746.700495] [127/142] (279s left) kms_big_fb (x-tiled-addfb)
[747.245994] Starting subtest: x-tiled-addfb
[747.249728] Subtest x-tiled-addfb: SUCCESS (0.002s)
[747.419397] [128/142] (278s left) syncobj_timeline (single-wait-all-available-signaled)
[747.795900] Starting subtest: single-wait-all-available-signaled
[747.799794] Subtest single-wait-all-available-signaled: SUCCESS (0.001s)
[747.953600] [129/142] (278s left) kms_ccs (pipe-c-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[749.492594] Starting subtest: pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[749.496313] Subtest pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[750.390774] [130/142] (276s left) gem_lmem_swapping (random)
[750.791691] Subtest random: SKIP (0.000s)
[750.912622] [131/142] (275s left) kms_addfb_basic (bad-pitch-1024)
[751.285625] Starting subtest: bad-pitch-1024
[751.289242] Subtest bad-pitch-1024: SUCCESS (0.000s)
[751.571090] [132/142] (275s left) kms_psr (psr2_dpms)
[752.066805] Starting subtest: psr2_dpms
[756.661419] Subtest psr2_dpms: SUCCESS (4.593s)
[757.618398] [133/142] (269s left) feature_discovery (psr2)
[758.116575] Starting subtest: psr2
[758.120341] Subtest psr2: SUCCESS (0.001s)
[758.270216] [134/142] (268s left) kms_plane_alpha_blend (alpha-opaque-fb)
[758.765112] Starting subtest: alpha-opaque-fb
[760.535280] Starting dynamic subtest: pipe-A-eDP-1
[761.880195] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.342s)
[761.940194] Starting dynamic subtest: pipe-D-eDP-1
[763.843896] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.955s)
[763.847522] Subtest alpha-opaque-fb: SUCCESS (5.083s)
[764.805831] [135/142] (262s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc)
[766.044431] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc
[766.048239] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.002s)
[767.198498] [136/142] (259s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_mtl_rc_ccs)
[768.154350] Starting subtest: pipe-A-random-ccs-data-4_tiled_mtl_rc_ccs
[769.379514] Subtest pipe-A-random-ccs-data-4_tiled_mtl_rc_ccs: SUCCESS (1.225s)
[771.227711] [137/142] (255s left) kms_chamelium_audio (dp-audio)
[772.120212] Subtest dp-audio: SKIP (0.000s)
[772.996460] [138/142] (254s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[774.384319] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs_cc
[774.855227] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.465s)
[776.210607] [139/142] (251s left) gem_exec_reloc (basic-wc-noreloc)
[776.604042] Subtest basic-wc-noreloc: SKIP (0.000s)
[776.745794] [140/142] (250s left) kms_dsc (dsc-with-formats)
[777.230278] Starting subtest: dsc-with-formats
[777.237045] Subtest dsc-with-formats: SKIP (0.007s)
[777.386271] [141/142] (250s left) vc4/vc4_tiling (set-bad-flags)
[777.862830] Subtest set-bad-flags: SKIP (0.000s)
[777.997641] [142/142] (249s left) perf (missing-sample-flags)
[778.911149] Starting subtest: missing-sample-flags
[778.914288] Subtest missing-sample-flags: SUCCESS (0.003s)
[779.013120] Closing watchdogs
Done.