[56.962058] Initializing watchdogs
[56.962198]   /dev/watchdog0
[56.979335] [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.984314] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.986785] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.990164] [001/133] (960s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[57.123351] Starting subtest: yf-tiled-64bpp-rotate-90
[57.127703] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.001s)
[57.209345] [002/133] (960s left) i915_pm_rps (waitboost)
[57.281453] Starting subtest: waitboost
[63.374806] Subtest waitboost: SUCCESS (6.095s)
[63.502840] [003/133] (954s left) gem_exec_parallel (userptr)
[63.562891] Starting subtest: userptr
[63.565360] Starting dynamic subtest: rcs0
[65.078112] Dynamic subtest rcs0: SUCCESS (1.516s)
[65.086820] Starting dynamic subtest: bcs0
[66.562370] Dynamic subtest bcs0: SUCCESS (1.484s)
[66.570651] Starting dynamic subtest: vcs0
[69.849434] Dynamic subtest vcs0: SUCCESS (3.285s)
[69.859483] Starting dynamic subtest: vcs1
[71.895692] Dynamic subtest vcs1: SUCCESS (2.046s)
[71.905976] Starting dynamic subtest: vecs0
[73.880171] Dynamic subtest vecs0: SUCCESS (1.983s)
[73.888927] Starting dynamic subtest: ccs0
[75.355700] Dynamic subtest ccs0: SUCCESS (1.476s)
[75.358056] Subtest userptr: SUCCESS (11.797s)
[75.537646] [004/133] (942s left) gem_workarounds (suspend-resume-context)
[75.625656] Starting subtest: suspend-resume-context
[92.264927] Subtest suspend-resume-context: SUCCESS (1.276s)
[92.414912] [005/133] (925s left) i915_drm_fdinfo (virtual-busy)
[92.472414] Starting subtest: virtual-busy
[92.477996] Subtest virtual-busy: SKIP (0.000s)
[92.566573] [006/133] (925s left) gem_lmem_swapping (verify-random-ccs)
[92.608938] Subtest verify-random-ccs: SKIP (0.000s)
[92.650911] [007/133] (925s left) syncobj_timeline (invalid-transfer-bad-pad)
[92.703093] Starting subtest: invalid-transfer-bad-pad
[92.705703] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[92.782538] [008/133] (924s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[92.870337] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[92.875023] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[93.950670] [009/133] (923s left) kms_pm_rpm (cursor)
[95.520688] Starting subtest: cursor
[95.525927] Subtest cursor: SKIP (0.000s)
[96.322945] [010/133] (921s left) gem_exec_reloc (basic-write-cpu-active)
[96.386168] Subtest basic-write-cpu-active: SKIP (0.000s)
[96.450908] [011/133] (921s left) gem_render_linear_blits (basic)
[96.512656] Starting subtest: basic
[96.537443] Subtest basic: SUCCESS (0.025s)
[96.622321] [012/133] (921s left) gem_softpin (allocator-nopin-reserve)
[96.682059] Starting subtest: allocator-nopin-reserve
[96.778595] Subtest allocator-nopin-reserve: SUCCESS (0.096s)
[96.891686] [013/133] (921s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[96.990832] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[96.995912] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[97.058304] [014/133] (920s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[97.156168] Starting subtest: psr-modesetfrombusy
[103.354827] Subtest psr-modesetfrombusy: SUCCESS (6.209s)
[103.480209] [015/133] (914s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[103.580810] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[103.584915] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[103.660281] [016/133] (914s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[103.715923] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[103.826387] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.110s)
[103.901782] [017/133] (914s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[103.961172] Starting subtest: multi-wait-all-for-submit-submitted
[104.062652] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[104.152973] [018/133] (914s left) syncobj_timeline (host-signal-ordered)
[104.210406] Starting subtest: host-signal-ordered
[104.212994] Subtest host-signal-ordered: SUCCESS (0.000s)
[104.301918] [019/133] (913s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[104.403052] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[104.408013] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[104.505346] [020/133] (913s left) api_intel_allocator (fork-simple-stress-signal)
[104.563057] Starting subtest: fork-simple-stress-signal
[109.960972] Subtest fork-simple-stress-signal: SUCCESS (5.403s)
[110.070693] [021/133] (908s left) gem_exec_balancer (bonded-sync)
[110.136308] Starting subtest: bonded-sync
[110.141959] Subtest bonded-sync: SKIP (0.000s)
[110.462017] [022/133] (907s left) kms_psr (psr2-suspend)
[110.568076] Starting subtest: psr2-suspend
[110.571023] Starting dynamic subtest: eDP-1
[128.673679] Dynamic subtest eDP-1: SUCCESS (7.777s)
[128.676006] Subtest psr2-suspend: SUCCESS (7.777s)
[129.480152] [023/133] (888s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[129.588818] Starting subtest: y-tiled-64bpp-rotate-90
[129.593212] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[129.696474] [024/133] (888s left) gem_userptr_blits (readonly-pwrite-unsync)
[129.762213] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[129.891188] [025/133] (888s left) gem_exec_schedule (u-independent)
[129.976974] Starting subtest: u-independent
[129.979335] Starting dynamic subtest: rcs0
[131.578637] Dynamic subtest rcs0: SUCCESS (1.609s)
[131.594101] Starting dynamic subtest: vcs0
[133.633984] Dynamic subtest vcs0: SUCCESS (2.055s)
[133.649402] Starting dynamic subtest: vcs1
[135.617938] Dynamic subtest vcs1: SUCCESS (1.984s)
[135.627088] Starting dynamic subtest: vecs0
[137.603364] Dynamic subtest vecs0: SUCCESS (1.985s)
[137.614983] Starting dynamic subtest: ccs0
[139.642914] Dynamic subtest ccs0: SUCCESS (2.038s)
[139.645454] Subtest u-independent: SUCCESS (9.676s)
[139.947675] [026/133] (878s left) kms_big_fb (x-tiled-addfb-size-overflow)
[140.062158] Starting subtest: x-tiled-addfb-size-overflow
[140.064420] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[140.163657] [027/133] (878s left) kms_cursor_edge_walk (128x128-top-edge)
[140.262622] Starting subtest: 128x128-top-edge
[140.265046] Starting dynamic subtest: pipe-A-eDP-1
[143.732936] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.471s)
[143.743821] Starting dynamic subtest: pipe-D-eDP-1
[146.854311] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.123s)
[146.856695] Subtest 128x128-top-edge: SUCCESS (6.596s)
[147.659482] [028/133] (870s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[147.750699] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[147.753170] Starting dynamic subtest: pipe-A-eDP-1
[147.777073] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[147.788704] Starting dynamic subtest: pipe-B-eDP-1
[147.810314] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[147.819149] Starting dynamic subtest: pipe-C-eDP-1
[147.840934] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[147.849090] Starting dynamic subtest: pipe-D-eDP-1
[147.871382] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[147.875763] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[149.934913] [029/133] (868s left) kms_addfb_basic (invalid-get-prop-any)
[149.996773] Starting subtest: invalid-get-prop-any
[149.999129] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[150.116388] [030/133] (868s left) gem_pipe_control_store_loop (fresh-buffer)
[150.179948] Starting subtest: fresh-buffer
[152.332441] Subtest fresh-buffer: SUCCESS (2.152s)
[152.477217] [031/133] (866s left) perf (gen12-mi-rpc)
[152.603070] Starting subtest: gen12-mi-rpc
[152.605375] Starting dynamic subtest: rcs0
[152.621631] Dynamic subtest rcs0: SUCCESS (0.017s)
[152.623992] Subtest gen12-mi-rpc: SUCCESS (0.017s)
[152.662787] [032/133] (866s left) gem_mmap_wc (bad-size)
[152.733327] Subtest bad-size: SKIP (0.000s)
[152.787475] [033/133] (865s left) gem_exec_reloc (basic-range-active)
[152.844622] Subtest basic-range-active: SKIP (0.000s)
[152.902675] [034/133] (865s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[152.963177] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[152.967479] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[153.169019] [035/133] (865s left) gen7_exec_parse (bitmasks)
[153.220955] Subtest bitmasks: SKIP (0.000s)
[153.278438] [036/133] (865s left) prime_busy (after-wait)
[153.344742] Starting subtest: after-wait
[153.347170] Starting dynamic subtest: rcs0
[153.378595] Dynamic subtest rcs0: SUCCESS (0.033s)
[153.389593] Starting dynamic subtest: bcs0
[153.433462] Dynamic subtest bcs0: SUCCESS (0.054s)
[153.444396] Starting dynamic subtest: vcs0
[153.476535] Dynamic subtest vcs0: SUCCESS (0.043s)
[153.486057] Starting dynamic subtest: vcs1
[153.519295] Dynamic subtest vcs1: SUCCESS (0.043s)
[153.528858] Starting dynamic subtest: vecs0
[153.561389] Dynamic subtest vecs0: SUCCESS (0.042s)
[153.570247] Starting dynamic subtest: ccs0
[153.604857] Dynamic subtest ccs0: SUCCESS (0.043s)
[153.713473] Subtest after-wait: SUCCESS (0.369s)
[153.786022] [037/133] (865s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[153.897946] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[154.521357] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.625s)
[154.679825] [038/133] (864s left) kms_big_fb (linear-8bpp-rotate-0)
[154.793695] Starting subtest: linear-8bpp-rotate-0
[156.041851] Subtest linear-8bpp-rotate-0: SUCCESS (1.249s)
[156.174662] [039/133] (862s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[156.271370] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[156.274772] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[156.343136] [040/133] (862s left) gem_ctx_persistence (engines-hang)
[156.411531] Starting subtest: engines-hang
[156.413711] Starting dynamic subtest: rcs0
[156.487439] Dynamic subtest rcs0: SUCCESS (0.075s)
[156.499083] Starting dynamic subtest: bcs0
[156.578712] Dynamic subtest bcs0: SUCCESS (0.090s)
[156.589382] Starting dynamic subtest: vcs0
[156.671721] Dynamic subtest vcs0: SUCCESS (0.091s)
[156.683247] Starting dynamic subtest: vcs1
[156.763357] Dynamic subtest vcs1: SUCCESS (0.090s)
[156.774740] Starting dynamic subtest: vecs0
[156.875484] Dynamic subtest vecs0: SUCCESS (0.110s)
[156.886059] Starting dynamic subtest: ccs0
[156.981134] Dynamic subtest ccs0: SUCCESS (0.105s)
[156.983209] Subtest engines-hang: SUCCESS (0.570s)
[157.072562] [041/133] (862s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[157.169121] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[164.476846] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.315s)
[164.600354] [042/133] (854s left) gen7_exec_parse (basic-allocation)
[164.658083] Subtest basic-allocation: SKIP (0.000s)
[164.721588] [043/133] (854s left) kms_cursor_crc (cursor-offscreen-64x64)
[164.886446] Starting subtest: cursor-offscreen-64x64
[164.891271] Starting dynamic subtest: pipe-A-eDP-1
[166.823792] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.933s)
[166.853553] Starting dynamic subtest: pipe-D-eDP-1
[169.472243] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.627s)
[169.474556] Subtest cursor-offscreen-64x64: SUCCESS (4.586s)
[170.321969] [044/133] (848s left) syncobj_wait (wait-for-submit-snapshot)
[170.376940] Starting subtest: wait-for-submit-snapshot
[170.457519] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[170.531279] [045/133] (848s left) gem_unref_active_buffers
[170.712406] [046/133] (848s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[170.804802] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[178.320431] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.523s)
[178.452167] [047/133] (840s left) kms_cursor_crc (cursor-random-128x42)
[178.688234] Starting subtest: cursor-random-128x42
[178.737091] Subtest cursor-random-128x42: SKIP (0.043s)
[178.937088] [048/133] (840s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[179.523532] Starting subtest: syncobj-timeline-invalid-flags
[179.526408] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[180.671377] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[180.675502] [049/133] (838s left) drm_read (short-buffer-nonblock)
[180.827013] Starting subtest: short-buffer-nonblock
[180.829471] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[180.960156] [050/133] (838s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[181.080669] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[185.056831] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.982s)
[186.238423] [051/133] (833s left) gem_exec_params (no-blt)
[186.304637] Starting subtest: no-blt
[186.307128] Subtest no-blt: SUCCESS (0.002s)
[186.442232] [052/133] (833s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[186.540297] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[186.544380] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[186.642707] [053/133] (832s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[186.743645] Starting subtest: psr-2p-primscrn-spr-indfb-move
[186.747892] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[186.836464] [054/133] (832s left) kms_pm_rpm (pm-tiling)
[188.767902] Starting subtest: pm-tiling
[188.773081] Subtest pm-tiling: SKIP (0.000s)
[189.143762] [055/133] (830s left) sysfs_heartbeat_interval (mixed)
[189.204712] Starting subtest: mixed
[189.207249] Starting dynamic subtest: vecs0
[194.441611] Dynamic subtest vecs0: SUCCESS (5.235s)
[194.454902] Starting dynamic subtest: ccs0
[199.584096] Dynamic subtest ccs0: SUCCESS (5.139s)
[199.595547] Starting dynamic subtest: vcs0
[204.782855] Dynamic subtest vcs0: SUCCESS (5.196s)
[204.794345] Starting dynamic subtest: bcs0
[209.989618] Dynamic subtest bcs0: SUCCESS (5.206s)
[210.001275] Starting dynamic subtest: rcs0
[215.196043] Dynamic subtest rcs0: SUCCESS (5.206s)
[215.207890] Starting dynamic subtest: vcs1
[220.399586] Dynamic subtest vcs1: SUCCESS (5.202s)
[220.402635] Subtest mixed: SUCCESS (31.193s)
[220.515748] [056/133] (799s left) gem_create (create-clear)
[220.569903] Starting subtest: create-clear
[220.572646] Starting dynamic subtest: smem0
[253.332309] Dynamic subtest smem0: SUCCESS (32.769s)
[253.334697] Subtest create-clear: SUCCESS (32.771s)
[253.537934] [057/133] (766s left) i915_hangman (detector)
[253.605860] Starting subtest: detector
[253.609179] Starting dynamic subtest: rcs0
[265.559370] Dynamic subtest rcs0: SUCCESS (11.948s)
[265.571934] Starting dynamic subtest: bcs0
[277.592244] Dynamic subtest bcs0: SUCCESS (12.028s)
[277.604681] Starting dynamic subtest: vcs0
[289.495302] Dynamic subtest vcs0: SUCCESS (11.899s)
[289.508571] Starting dynamic subtest: vcs1
[299.607246] Dynamic subtest vcs1: SUCCESS (10.109s)
[299.619300] Starting dynamic subtest: vecs0
[311.511190] Dynamic subtest vecs0: SUCCESS (11.902s)
[311.528304] Starting dynamic subtest: ccs0
[323.543218] Dynamic subtest ccs0: SUCCESS (12.030s)
[323.546149] Subtest detector: SUCCESS (69.933s)
[323.663007] [058/133] (696s left) kms_addfb_basic (basic)
[323.716651] Starting subtest: basic
[323.719060] Subtest basic: SUCCESS (0.000s)
[323.856223] [059/133] (695s left) kms_addfb_basic (bad-pitch-1024)
[323.912636] Starting subtest: bad-pitch-1024
[323.915185] Subtest bad-pitch-1024: SUCCESS (0.000s)
[324.075501] [060/133] (695s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[324.470148] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[325.240992] [061/133] (694s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[325.365167] Starting subtest: basic-flip-after-cursor-atomic
[326.284835] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.919s)
[327.111125] [062/133] (692s left) i915_pm_rpm (gem-execbuf-stress)
[328.840785] Starting subtest: gem-execbuf-stress
[328.843147] Starting dynamic subtest: smem0
[341.728936] Dynamic subtest smem0: SUCCESS (12.893s)
[341.742220] Starting dynamic subtest: extra-wait-smem0
[403.684160] Dynamic subtest extra-wait-smem0: SUCCESS (61.953s)
[403.687173] Subtest gem-execbuf-stress: SUCCESS (74.851s)
[404.059182] [063/133] (615s left) kms_draw_crc (draw-method-pwrite)
[404.160257] Starting subtest: draw-method-pwrite
[404.162191] Starting dynamic subtest: xrgb8888-untiled
[404.746357] Dynamic subtest xrgb8888-untiled: SUCCESS (0.588s)
[404.756082] Starting dynamic subtest: rgb565-untiled
[405.213065] Dynamic subtest rgb565-untiled: SUCCESS (0.465s)
[405.225564] Starting dynamic subtest: xrgb2101010-untiled
[405.779692] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.566s)
[405.789774] Starting dynamic subtest: xbgr16161616f-untiled
[406.557968] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.777s)
[406.567931] Starting dynamic subtest: xrgb8888-xtiled
[408.380291] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.820s)
[408.393175] Starting dynamic subtest: rgb565-xtiled
[409.513948] Dynamic subtest rgb565-xtiled: SUCCESS (1.132s)
[409.523561] Starting dynamic subtest: xrgb2101010-xtiled
[411.403173] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.888s)
[411.413050] Starting dynamic subtest: xbgr16161616f-xtiled
[414.770473] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.364s)
[414.780173] Starting dynamic subtest: xrgb8888-4tiled
[415.892414] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.122s)
[415.904313] Starting dynamic subtest: rgb565-4tiled
[416.470487] Dynamic subtest rgb565-4tiled: SUCCESS (0.577s)
[416.480935] Starting dynamic subtest: xrgb2101010-4tiled
[417.581776] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.110s)
[417.594660] Starting dynamic subtest: xbgr16161616f-4tiled
[420.648993] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.066s)
[420.651321] Subtest draw-method-pwrite: SUCCESS (16.489s)
[420.743922] [064/133] (599s left) kms_joiner (invalid-modeset-force-big-joiner)
[420.849492] Starting subtest: invalid-modeset-force-big-joiner
[420.853943] Subtest invalid-modeset-force-big-joiner: SKIP (0.000s)
[420.918055] [065/133] (599s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[421.022219] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[421.024561] Starting dynamic subtest: pipe-A-default-mode
[423.808209] Dynamic subtest pipe-A-default-mode: SUCCESS (2.784s)
[423.810160] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.786s)
[424.649811] [066/133] (595s left) gem_partial_pwrite_pread (reads-display)
[424.705319] Subtest reads-display: SKIP (0.000s)
[424.772579] [067/133] (595s left) perf_pmu (interrupts-sync)
[424.810483] Starting subtest: interrupts-sync
[425.877741] Subtest interrupts-sync: SUCCESS (1.066s)
[425.984784] [068/133] (594s left) kms_debugfs (display-on-read-all)
[426.072922] Starting subtest: display-on-read-all
[426.239856] Subtest display-on-read-all: SUCCESS (0.167s)
[426.377993] [069/133] (593s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[426.476352] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[433.025053] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.551s)
[433.129055] [070/133] (587s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[433.229647] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[433.233547] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[433.325138] [071/133] (587s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[433.447351] Starting subtest: short-flip-before-cursor-toggle
[434.360723] Subtest short-flip-before-cursor-toggle: SUCCESS (0.914s)
[435.214147] [072/133] (585s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[435.309938] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[435.314038] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[435.430360] [073/133] (585s left) kms_chamelium_edid (dp-edid-read)
[435.823672] Subtest dp-edid-read: SKIP (0.000s)
[436.630043] [074/133] (583s left) syncobj_wait (single-wait-all-submitted)
[436.692706] Starting subtest: single-wait-all-submitted
[436.797139] Subtest single-wait-all-submitted: SUCCESS (0.102s)
[436.882431] [075/133] (583s left) gem_exec_fence (expired-history)
[437.313238] Starting subtest: expired-history
[444.241897] Subtest expired-history: SUCCESS (6.929s)
[444.879493] [076/133] (575s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[444.981749] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[444.986352] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[445.088205] [077/133] (575s left) gem_exec_schedule (implicit-read-write)
[445.166401] Starting subtest: implicit-read-write
[445.168714] Starting dynamic subtest: rcs0
[445.270100] Dynamic subtest rcs0: SUCCESS (0.115s)
[445.283522] Starting dynamic subtest: vcs0
[445.352262] Dynamic subtest vcs0: SUCCESS (0.081s)
[445.365198] Starting dynamic subtest: vcs1
[445.436452] Dynamic subtest vcs1: SUCCESS (0.083s)
[445.455465] Starting dynamic subtest: vecs0
[445.514474] Dynamic subtest vecs0: SUCCESS (0.077s)
[445.527896] Starting dynamic subtest: ccs0
[445.612498] Dynamic subtest ccs0: SUCCESS (0.098s)
[445.615209] Subtest implicit-read-write: SUCCESS (0.459s)
[446.667694] [078/133] (574s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[446.774751] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[446.779394] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[446.863627] [079/133] (573s left) kms_psr (pr-cursor-plane-onoff)
[446.981387] Starting subtest: pr-cursor-plane-onoff
[446.986028] Subtest pr-cursor-plane-onoff: SKIP (0.000s)
[447.058310] [080/133] (573s left) kms_psr (fbc-psr-cursor-render)
[447.161571] Starting subtest: fbc-psr-cursor-render
[447.163764] Starting dynamic subtest: eDP-1
[447.167599] Dynamic subtest eDP-1: SKIP (0.000s)
[447.171444] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[447.236981] [081/133] (573s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[447.336630] Starting subtest: fbcpsr-shrfb-scaledprimary
[453.161358] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.828s)
[453.288004] [082/133] (567s left) kms_flip (2x-busy-flip)
[453.372273] Starting subtest: 2x-busy-flip
[453.381254] Subtest 2x-busy-flip: SKIP (0.011s)
[453.480884] [083/133] (567s left) gem_ctx_persistence (legacy-engines-persistence)
[453.533710] Starting subtest: legacy-engines-persistence
[453.536069] Starting dynamic subtest: render
[454.330089] Dynamic subtest render: SUCCESS (0.795s)
[454.350754] Starting dynamic subtest: bsd1
[455.093164] Dynamic subtest bsd1: SUCCESS (0.760s)
[455.106948] Starting dynamic subtest: bsd2
[455.861423] Dynamic subtest bsd2: SUCCESS (0.765s)
[455.875280] Starting dynamic subtest: blt
[456.608146] Dynamic subtest blt: SUCCESS (0.744s)
[456.620707] Starting dynamic subtest: vebox
[457.403220] Dynamic subtest vebox: SUCCESS (0.792s)
[457.406222] Subtest legacy-engines-persistence: SUCCESS (3.870s)
[457.487965] [084/133] (563s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[457.559261] Starting subtest: mixed-tiled-to-y-tiled-ccs
[457.563622] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[457.746692] [085/133] (563s left) gem_exec_params (invalid-ring)
[457.811765] Starting subtest: invalid-ring
[457.814303] Subtest invalid-ring: SUCCESS (0.000s)
[457.929756] [086/133] (563s left) gem_close (basic)
[457.974470] Starting subtest: basic
[457.976772] Subtest basic: SUCCESS (0.000s)
[458.085366] [087/133] (562s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[458.200319] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[458.208512] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[458.312967] [088/133] (562s left) kms_lease (lease-get)
[458.400858] Starting subtest: lease-get
[458.403277] Starting dynamic subtest: pipe-A-eDP-1
[458.405488] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[458.416960] Starting dynamic subtest: pipe-B-eDP-1
[458.419104] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[458.429062] Starting dynamic subtest: pipe-C-eDP-1
[458.431143] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[458.440783] Starting dynamic subtest: pipe-D-eDP-1
[458.442932] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[458.445022] Subtest lease-get: SUCCESS (0.009s)
[458.508766] [089/133] (562s left) gem_mmap_offset (partial-mmap)
[458.564373] Starting subtest: partial-mmap
[458.566695] Starting dynamic subtest: smem0
[458.572415] Dynamic subtest smem0: SUCCESS (0.008s)
[458.574764] Subtest partial-mmap: SUCCESS (0.010s)
[458.716243] [090/133] (562s left) gem_tiled_partial_pwrite_pread (reads)
[458.772794] Subtest reads: SKIP (0.000s)
[458.842305] [091/133] (562s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[458.936744] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[458.940139] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[459.035035] [092/133] (562s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[459.124750] Starting subtest: fbc-psr2-cursor-plane-update-sf
[459.126548] Starting dynamic subtest: pipe-A-eDP-1
[459.199939] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[459.212485] Starting dynamic subtest: pipe-B-eDP-1
[460.361121] Dynamic subtest pipe-B-eDP-1: SKIP (1.158s)
[460.365453] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.238s)
[461.551213] [093/133] (559s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[461.657366] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[461.661620] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[461.741295] [094/133] (559s left) gem_exec_reloc (basic-cpu-wc)
[461.799968] Subtest basic-cpu-wc: SKIP (0.000s)
[461.870648] [095/133] (559s left) kms_content_protection (lic-type-1)
[462.006562] Starting subtest: lic-type-1
[462.011120] Subtest lic-type-1: SKIP (0.000s)
[462.125890] [096/133] (559s left) kms_atomic (plane-invalid-params-fence)
[462.222042] Starting subtest: plane-invalid-params-fence
[462.224296] Starting dynamic subtest: pipe-A-eDP-1
[462.578946] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.355s)
[462.581181] Subtest plane-invalid-params-fence: SUCCESS (0.357s)
[463.366702] [097/133] (558s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[463.460300] Starting subtest: bad-rotation-90-yf-tiled-ccs
[463.462649] Starting dynamic subtest: pipe-A-eDP-1
[463.485292] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[463.499129] Starting dynamic subtest: pipe-B-eDP-1
[463.520772] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[463.530583] Starting dynamic subtest: pipe-C-eDP-1
[463.553380] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[463.563200] Starting dynamic subtest: pipe-D-eDP-1
[463.586065] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[463.590092] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.005s)
[465.757021] [098/133] (555s left) gem_lmem_swapping (massive-random)
[465.803519] Subtest massive-random: SKIP (0.000s)
[465.849849] [099/133] (555s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[465.938145] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[466.310293] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[469.096140] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.784s)
[469.107095] Starting dynamic subtest: pipe-A-eDP-1-4
[471.281415] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.185s)
[471.292278] Starting dynamic subtest: pipe-A-eDP-1-4
[473.292696] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[473.303125] Starting dynamic subtest: pipe-A-eDP-1-x
[475.470531] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.177s)
[475.838157] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[478.613752] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.783s)
[478.626792] Starting dynamic subtest: pipe-B-eDP-1-4
[480.821662] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.207s)
[480.837060] Starting dynamic subtest: pipe-B-eDP-1-4
[482.831162] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[482.844654] Starting dynamic subtest: pipe-B-eDP-1-x
[485.031957] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.200s)
[485.416980] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[488.139660] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.736s)
[488.155709] Starting dynamic subtest: pipe-C-eDP-1-4
[490.325088] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.184s)
[490.337586] Starting dynamic subtest: pipe-C-eDP-1-4
[492.334972] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[492.345587] Starting dynamic subtest: pipe-C-eDP-1-x
[494.514189] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[494.880560] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[497.666750] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.794s)
[497.680028] Starting dynamic subtest: pipe-D-eDP-1-4
[499.829910] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.162s)
[499.843292] Starting dynamic subtest: pipe-D-eDP-1-4
[501.839904] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[501.852759] Starting dynamic subtest: pipe-D-eDP-1-x
[504.022343] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[504.024849] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.083s)
[505.257468] [100/133] (516s left) kms_color (ctm-negative)
[505.357374] Starting subtest: ctm-negative
[505.359370] Starting dynamic subtest: pipe-A-eDP-1
[506.056730] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.698s)
[506.067305] Starting dynamic subtest: pipe-B-eDP-1
[507.466970] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.412s)
[507.478790] Starting dynamic subtest: pipe-C-eDP-1
[508.865541] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.398s)
[508.876460] Starting dynamic subtest: pipe-D-eDP-1
[510.305120] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.437s)
[510.307530] Subtest ctm-negative: SUCCESS (4.949s)
[511.192195] [101/133] (510s left) gem_fence_thrash (bo-write-verify-y)
[511.232316] Starting subtest: bo-write-verify-y
[511.259916] Subtest bo-write-verify-y: SKIP (0.024s)
[511.324296] [102/133] (510s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[511.415652] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[511.420094] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[511.490332] [103/133] (510s left) kms_psr (fbc-pr-primary-mmap-gtt)
[511.574152] Starting subtest: fbc-pr-primary-mmap-gtt
[511.578835] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[511.646944] [104/133] (510s left) core_getversion (basic)
[511.686961] Starting subtest: basic
[511.691084] Subtest basic: SUCCESS (0.000s)
[511.757361] [105/133] (510s left) kms_atomic_transition (plane-all-transition-fencing)
[511.857296] Starting subtest: plane-all-transition-fencing
[511.859522] Starting dynamic subtest: pipe-A-eDP-1
[514.355803] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.500s)
[514.664956] Starting dynamic subtest: pipe-B-eDP-1
[517.900429] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.245s)
[518.199165] Subtest plane-all-transition-fencing: SUCCESS (6.344s)
[519.027803] [106/133] (502s left) gem_eio (in-flight-immediate)
[519.252098] Starting subtest: in-flight-immediate
[527.412925] Subtest in-flight-immediate: SUCCESS (8.161s)
[527.532213] [107/133] (494s left) kms_pm_backlight (bad-brightness)
[527.629833] Starting subtest: bad-brightness
[527.678721] Starting dynamic subtest: eDP-1
[527.989753] Dynamic subtest eDP-1: SUCCESS (0.308s)
[527.992251] Subtest bad-brightness: SUCCESS (0.359s)
[528.853573] [108/133] (493s left) kms_psr (psr-primary-page-flip)
[528.939841] Starting subtest: psr-primary-page-flip
[528.942109] Starting dynamic subtest: eDP-1
[530.664217] Dynamic subtest eDP-1: SUCCESS (1.725s)
[530.666594] Subtest psr-primary-page-flip: SUCCESS (1.726s)
[531.527620] [109/133] (490s left) gem_exec_store (pages)
[531.602571] Starting subtest: pages
[531.604941] Starting dynamic subtest: rcs0
[531.674240] Dynamic subtest rcs0: SUCCESS (0.072s)
[531.686683] Starting dynamic subtest: bcs0
[531.738504] Dynamic subtest bcs0: SUCCESS (0.063s)
[531.752943] Starting dynamic subtest: vcs0
[531.802562] Dynamic subtest vcs0: SUCCESS (0.063s)
[531.815558] Starting dynamic subtest: vcs1
[531.875745] Dynamic subtest vcs1: SUCCESS (0.072s)
[531.888321] Starting dynamic subtest: vecs0
[531.943972] Dynamic subtest vecs0: SUCCESS (0.067s)
[531.955534] Starting dynamic subtest: ccs0
[532.012194] Dynamic subtest ccs0: SUCCESS (0.066s)
[532.014577] Subtest pages: SUCCESS (0.410s)
[532.200038] [110/133] (489s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[532.291186] Starting subtest: extended-pageflip-modeset-hang-oldfb
[532.293495] Starting dynamic subtest: pipe-A
[540.193328] Dynamic subtest pipe-A: SUCCESS (7.902s)
[540.204053] Starting dynamic subtest: pipe-D
[548.822444] Dynamic subtest pipe-D: SUCCESS (8.629s)
[548.824760] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.536s)
[549.681852] [111/133] (472s left) perf_pmu (busy-start)
[549.732338] Starting subtest: busy-start
[549.734708] Starting dynamic subtest: rcs0
[552.283160] Dynamic subtest rcs0: SUCCESS (2.552s)
[552.295413] Starting dynamic subtest: bcs0
[554.833220] Dynamic subtest bcs0: SUCCESS (2.548s)
[554.845480] Starting dynamic subtest: vcs0
[558.585275] Dynamic subtest vcs0: SUCCESS (3.748s)
[558.597134] Starting dynamic subtest: vcs1
[562.616135] Dynamic subtest vcs1: SUCCESS (4.028s)
[562.629100] Starting dynamic subtest: vecs0
[566.585437] Dynamic subtest vecs0: SUCCESS (3.967s)
[566.597549] Starting dynamic subtest: ccs0
[569.135612] Dynamic subtest ccs0: SUCCESS (2.549s)
[569.138291] Subtest busy-start: SUCCESS (19.408s)
[569.241815] [112/133] (452s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[569.337653] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[569.339531] Starting dynamic subtest: pipe-A-eDP-1
[569.361771] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s)
[569.372067] Starting dynamic subtest: pipe-B-eDP-1
[569.391337] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[569.401119] Starting dynamic subtest: pipe-C-eDP-1
[569.420369] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s)
[569.430384] Starting dynamic subtest: pipe-D-eDP-1
[569.449939] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[569.452176] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.092s)
[571.744555] [113/133] (450s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[571.799852] Starting subtest: single-wait-all-for-submit-signaled
[571.802168] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[571.902718] [114/133] (450s left) gem_exec_schedule (smoketest-all)
[571.973370] Starting subtest: smoketest-all
[604.338278] Subtest smoketest-all: SUCCESS (32.328s)
[605.611188] [115/133] (416s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[605.714288] Starting subtest: primary-yf-tiled-reflect-x-180
[605.785558] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.071s)
[605.890711] [116/133] (416s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[606.001381] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[606.003679] Starting dynamic subtest: pipe-A-eDP-1
[611.884904] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.887s)
[611.895950] Starting dynamic subtest: pipe-B-eDP-1
[618.538754] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.651s)
[618.540934] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.541s)
[619.420935] [117/133] (402s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[619.523112] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[626.110518] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.591s)
[626.240252] [118/133] (396s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[626.341954] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[626.344087] Starting dynamic subtest: pipe-A
[626.361141] Dynamic subtest pipe-A: SKIP (0.016s)
[626.371715] Starting dynamic subtest: pipe-B
[626.394093] Dynamic subtest pipe-B: SKIP (0.030s)
[626.404324] Starting dynamic subtest: pipe-C
[626.424019] Dynamic subtest pipe-C: SKIP (0.030s)
[626.434156] Starting dynamic subtest: pipe-D
[626.455994] Dynamic subtest pipe-D: SKIP (0.031s)
[626.459988] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.110s)
[626.552514] [119/133] (395s left) kms_universal_plane (cursor-fb-leak)
[626.643615] Starting subtest: cursor-fb-leak
[626.645789] Starting dynamic subtest: pipe-A-eDP-1
[627.009143] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.367s)
[627.021339] Starting dynamic subtest: pipe-B-eDP-1
[628.357516] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.346s)
[628.370715] Starting dynamic subtest: pipe-C-eDP-1
[629.674861] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.315s)
[629.689839] Starting dynamic subtest: pipe-D-eDP-1
[631.001971] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.324s)
[631.004425] Subtest cursor-fb-leak: SUCCESS (4.361s)
[632.181751] [120/133] (390s left) gem_exec_async (concurrent-writes)
[632.252403] Starting subtest: concurrent-writes
[632.254632] Starting dynamic subtest: rcs0
[632.265290] Dynamic subtest rcs0: SUCCESS (0.016s)
[632.276840] Starting dynamic subtest: bcs0
[632.278675] Dynamic subtest bcs0: SUCCESS (0.006s)
[632.288756] Starting dynamic subtest: vcs0
[632.291024] Dynamic subtest vcs0: SUCCESS (0.006s)
[632.300668] Starting dynamic subtest: vcs1
[632.302951] Dynamic subtest vcs1: SUCCESS (0.006s)
[632.312383] Starting dynamic subtest: vecs0
[632.314517] Dynamic subtest vecs0: SUCCESS (0.006s)
[632.324059] Starting dynamic subtest: ccs0
[632.326185] Dynamic subtest ccs0: SUCCESS (0.006s)
[632.328310] Subtest concurrent-writes: SUCCESS (0.050s)
[632.528054] [121/133] (390s left) syncobj_timeline (reset-signaled)
[632.581624] Starting subtest: reset-signaled
[632.583744] Subtest reset-signaled: SUCCESS (0.000s)
[632.671943] [122/133] (389s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[632.787038] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[632.791395] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[632.936850] [123/133] (389s left) kms_getfb (getfb-handle-protection)
[633.031947] Starting subtest: getfb-handle-protection
[633.051832] Subtest getfb-handle-protection: SUCCESS (0.021s)
[633.159084] [124/133] (389s left) kms_addfb_basic (bad-pitch-32)
[633.213819] Starting subtest: bad-pitch-32
[633.215918] Subtest bad-pitch-32: SUCCESS (0.000s)
[633.357825] [125/133] (389s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[633.448454] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[634.439157] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (0.990s)
[634.553456] [126/133] (388s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[634.654907] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[634.657159] Starting dynamic subtest: pipe-A-eDP-1
[634.676448] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[634.687502] Starting dynamic subtest: pipe-B-eDP-1
[634.707911] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[634.720694] Starting dynamic subtest: pipe-C-eDP-1
[634.739926] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[634.749147] Starting dynamic subtest: pipe-D-eDP-1
[634.767700] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[634.769548] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[636.929905] [127/133] (385s left) kms_vblank (query-forked-hang)
[637.025910] Starting subtest: query-forked-hang
[637.028049] Starting dynamic subtest: pipe-A-eDP-1
[664.388570] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.356s)
[664.404035] Starting dynamic subtest: pipe-D-eDP-1
[679.342676] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.951s)
[679.345244] Subtest query-forked-hang: SUCCESS (42.311s)
[680.197486] [128/133] (342s left) kms_flip (flip-vs-absolute-wf_vblank)
[680.301266] Starting subtest: flip-vs-absolute-wf_vblank
[680.303222] Starting dynamic subtest: A-eDP1
[689.241435] Dynamic subtest A-eDP1: SUCCESS (8.941s)
[689.253492] Starting dynamic subtest: B-eDP1
[698.163740] Dynamic subtest B-eDP1: SUCCESS (8.920s)
[698.175858] Starting dynamic subtest: C-eDP1
[707.077376] Dynamic subtest C-eDP1: SUCCESS (8.910s)
[707.090162] Starting dynamic subtest: D-eDP1
[716.018238] Dynamic subtest D-eDP1: SUCCESS (8.937s)
[716.020595] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.719s)
[717.253183] [129/133] (305s left) kms_pipe_crc_basic (hang-read-crc)
[717.355319] Starting subtest: hang-read-crc
[717.357374] Starting dynamic subtest: pipe-A-eDP-1
[719.431816] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.074s)
[719.444118] Starting dynamic subtest: pipe-B-eDP-1
[722.213304] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.779s)
[722.224069] Starting dynamic subtest: pipe-C-eDP-1
[724.929206] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.717s)
[724.940078] Starting dynamic subtest: pipe-D-eDP-1
[727.611287] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.680s)
[727.613621] Subtest hang-read-crc: SUCCESS (10.257s)
[728.413559] [130/133] (294s left) core_debugfs (read-all-entries)
[728.473944] Starting subtest: read-all-entries
[728.566831] Subtest read-all-entries: SUCCESS (0.094s)
[728.637309] [131/133] (294s left) gem_ccs (block-multicopy-compressed)
[728.697204] Starting subtest: block-multicopy-compressed
[728.701903] Subtest block-multicopy-compressed: SKIP (0.000s)
[728.802037] [132/133] (294s left) kms_plane_multiple (tiling-4)
[728.886123] Starting subtest: tiling-4
[728.888361] Starting dynamic subtest: pipe-A-eDP-1
[732.648524] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.764s)
[732.661601] Starting dynamic subtest: pipe-B-eDP-1
[737.080693] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.430s)
[737.091779] Starting dynamic subtest: pipe-C-eDP-1
[741.440940] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.356s)
[741.454266] Starting dynamic subtest: pipe-D-eDP-1
[745.787669] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.347s)
[745.790047] Subtest tiling-4: SUCCESS (16.902s)
[746.596207] [133/133] (276s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[746.697626] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[750.267888] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.574s)
[751.421108] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: i915_pm_rps
results: parsing output: 2/ for test: gem_exec_parallel
results: parsing output: 3/ for test: gem_workarounds
results: parsing output: 4/ for test: i915_drm_fdinfo
results: parsing output: 5/ for test: gem_lmem_swapping
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: kms_pm_rpm
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: gem_render_linear_blits
results: parsing output: 11/ for test: gem_softpin
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: syncobj_wait
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: api_intel_allocator
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: gem_userptr_blits
results: parsing output: 24/ for test: gem_exec_schedule
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: kms_cursor_edge_walk
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_addfb_basic
results: parsing output: 29/ for test: gem_pipe_control_store_loop
results: parsing output: 30/ for test: perf
results: parsing output: 31/ for test: gem_mmap_wc
results: parsing output: 32/ for test: gem_exec_reloc
results: parsing output: 33/ for test: gem_render_copy
results: parsing output: 34/ for test: gen7_exec_parse
results: parsing output: 35/ for test: prime_busy
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_plane
results: parsing output: 39/ for test: gem_ctx_persistence
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gen7_exec_parse
results: parsing output: 42/ for test: kms_cursor_crc
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: gem_unref_active_buffers
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_cursor_crc
results: parsing output: 47/ for test: gem_exec_fence
results: parsing output: 48/ for test: drm_read
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_params
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: sysfs_heartbeat_interval
results: parsing output: 55/ for test: gem_create
results: parsing output: 56/ for test: i915_hangman
results: parsing output: 57/ for test: kms_addfb_basic
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: kms_chamelium_hpd
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: i915_pm_rpm
results: parsing output: 62/ for test: kms_draw_crc
results: parsing output: 63/ for test: kms_joiner
results: parsing output: 64/ for test: kms_flip_scaled_crc
results: parsing output: 65/ for test: gem_partial_pwrite_pread
results: parsing output: 66/ for test: perf_pmu
results: parsing output: 67/ for test: kms_debugfs
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_cursor_legacy
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_chamelium_edid
results: parsing output: 73/ for test: syncobj_wait
results: parsing output: 74/ for test: gem_exec_fence
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_exec_schedule
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: kms_psr
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: gem_ctx_persistence
results: parsing output: 83/ for test: gem_render_copy
results: parsing output: 84/ for test: gem_exec_params
results: parsing output: 85/ for test: gem_close
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_lease
results: parsing output: 88/ for test: gem_mmap_offset
results: parsing output: 89/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_psr2_sf
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_exec_reloc
results: parsing output: 94/ for test: kms_content_protection
results: parsing output: 95/ for test: kms_atomic
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: gem_lmem_swapping
results: parsing output: 98/ for test: kms_async_flips
results: parsing output: 99/ for test: kms_color
results: parsing output: 100/ for test: gem_fence_thrash
results: parsing output: 101/ for test: kms_flip_scaled_crc
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: core_getversion
results: parsing output: 104/ for test: kms_atomic_transition
results: parsing output: 105/ for test: gem_eio
results: parsing output: 106/ for test: kms_pm_backlight
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: gem_exec_store
results: parsing output: 109/ for test: kms_busy
results: parsing output: 110/ for test: perf_pmu
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: syncobj_timeline
results: parsing output: 113/ for test: gem_exec_schedule
results: parsing output: 114/ for test: kms_rotation_crc
results: parsing output: 115/ for test: kms_psr2_sf
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_plane_scaling
results: parsing output: 118/ for test: kms_universal_plane
results: parsing output: 119/ for test: gem_exec_async
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: kms_getfb
results: parsing output: 123/ for test: kms_addfb_basic
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: kms_ccs
results: parsing output: 126/ for test: kms_vblank
results: parsing output: 127/ for test: kms_flip
results: parsing output: 128/ for test: kms_pipe_crc_basic
results: parsing output: 129/ for test: core_debugfs
results: parsing output: 130/ for test: gem_ccs
results: parsing output: 131/ for test: kms_plane_multiple
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.