[56.379163] Initializing watchdogs
[56.379281]   /dev/watchdog0
[56.395956] [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.400505] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.402978] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.406494] [001/133] (960s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[56.477851] Starting subtest: invalid-multi-wait-unsubmitted
[56.480148] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[56.594507] [002/133] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[56.690104] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[56.694323] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[56.775765] [003/133] (960s left) kms_ccs (bad-aux-stride-y-tiled-gen12-mc-ccs)
[56.865831] Starting subtest: bad-aux-stride-y-tiled-gen12-mc-ccs
[56.868219] Starting dynamic subtest: pipe-A-eDP-1
[56.891729] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[56.901764] Starting dynamic subtest: pipe-B-eDP-1
[56.923605] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[56.931095] Starting dynamic subtest: pipe-C-eDP-1
[56.952884] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[56.960051] Starting dynamic subtest: pipe-D-eDP-1
[56.982139] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[56.987070] Subtest bad-aux-stride-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[59.355874] [004/133] (957s left) gem_mmap_gtt (basic-small-copy-odd)
[59.413774] Subtest basic-small-copy-odd: SKIP (0.000s)
[59.466994] [005/133] (957s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[59.562088] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[59.566511] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[59.632202] [006/133] (957s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[59.729694] Starting subtest: yf-tiled-32bpp-rotate-180
[59.733808] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[59.822698] [007/133] (957s left) syncobj_timeline (single-wait-available-signaled)
[59.874267] Starting subtest: single-wait-available-signaled
[59.876693] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[59.962630] [008/133] (957s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[60.075436] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[69.622930] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.549s)
[70.484949] [009/133] (946s left) gem_softpin (softpin)
[70.555806] Starting subtest: softpin
[102.830476] Subtest softpin: SUCCESS (32.269s)
[102.944653] [010/133] (914s left) gem_mmap_wc (read)
[103.008811] Subtest read: SKIP (0.000s)
[103.067155] [011/133] (914s left) gem_flink_basic (basic)
[103.126771] Starting subtest: basic
[103.129070] Subtest basic: SUCCESS (0.000s)
[103.219565] [012/133] (914s left) gem_exec_params (invalid-bsd1-flag-on-render)
[103.274663] Starting subtest: invalid-bsd1-flag-on-render
[103.277077] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[103.392974] [013/133] (913s left) gem_mmap_gtt (cpuset-big-copy)
[103.450776] Subtest cpuset-big-copy: SKIP (0.000s)
[103.516654] [014/133] (913s left) perf_pmu (busy-idle-check-all)
[103.558632] Starting subtest: busy-idle-check-all
[103.560827] Starting dynamic subtest: rcs0
[104.220057] Dynamic subtest rcs0: SUCCESS (0.661s)
[104.234639] Starting dynamic subtest: bcs0
[104.877917] Dynamic subtest bcs0: SUCCESS (0.657s)
[104.890884] Starting dynamic subtest: vcs0
[106.302471] Dynamic subtest vcs0: SUCCESS (1.422s)
[106.313974] Starting dynamic subtest: vcs1
[108.286284] Dynamic subtest vcs1: SUCCESS (1.983s)
[108.298681] Starting dynamic subtest: vecs0
[110.271555] Dynamic subtest vecs0: SUCCESS (1.983s)
[110.283063] Starting dynamic subtest: ccs0
[110.926336] Dynamic subtest ccs0: SUCCESS (0.655s)
[110.928860] Subtest busy-idle-check-all: SUCCESS (7.368s)
[111.016846] [015/133] (906s left) gem_render_copy_redux (flink)
[111.083267] Starting subtest: flink
[112.177849] Subtest flink: SUCCESS (1.095s)
[112.293851] [016/133] (905s left) kms_psr (fbc-psr-primary-blt)
[112.386224] Starting subtest: fbc-psr-primary-blt
[112.388266] Starting dynamic subtest: eDP-1
[112.391986] Dynamic subtest eDP-1: SKIP (0.000s)
[112.395822] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[112.457215] [017/133] (905s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[112.564685] Starting subtest: fbc-rgb565-draw-mmap-cpu
[116.613410] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.051s)
[117.784997] [018/133] (899s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[117.882781] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[117.887193] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[117.981681] [019/133] (899s left) gem_ctx_param (invalid-size-get)
[118.040855] Starting subtest: invalid-size-get
[118.043241] Subtest invalid-size-get: SUCCESS (0.001s)
[118.139474] [020/133] (899s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[118.244264] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[125.115855] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.878s)
[125.252552] [021/133] (892s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[125.365607] Starting subtest: flip-vs-cursor-atomic
[132.892498] Subtest flip-vs-cursor-atomic: SUCCESS (7.526s)
[133.723600] [022/133] (883s left) kms_vblank (wait-busy-hang)
[133.812035] Starting subtest: wait-busy-hang
[133.814213] Starting dynamic subtest: pipe-A-eDP-1
[160.067121] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.252s)
[160.082328] Starting dynamic subtest: pipe-D-eDP-1
[175.050111] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.981s)
[175.052436] Subtest wait-busy-hang: SUCCESS (41.237s)
[175.889395] [023/133] (841s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[175.998791] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[176.003102] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[176.090514] [024/133] (841s left) i915_query (test-query-geometry-subslices)
[176.144814] Starting subtest: test-query-geometry-subslices
[176.147060] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[176.264783] [025/133] (841s left) kms_chamelium_frames (hdmi-frame-dump)
[176.646375] Subtest hdmi-frame-dump: SKIP (0.000s)
[177.510434] [026/133] (840s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[177.639097] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[179.033488] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.392s)
[179.912563] [027/133] (837s left) kms_psr (psr-dpms)
[180.007778] Starting subtest: psr-dpms
[180.010040] Starting dynamic subtest: eDP-1
[182.950542] Dynamic subtest eDP-1: SUCCESS (2.942s)
[182.952518] Subtest psr-dpms: SUCCESS (2.942s)
[183.814438] [028/133] (834s left) perf (buffer-fill)
[183.948070] Starting subtest: buffer-fill
[183.950397] Starting dynamic subtest: 0-rcs0
[186.203977] Dynamic subtest 0-rcs0: SUCCESS (2.254s)
[186.214055] Starting dynamic subtest: 1-vcs1
[190.650903] Dynamic subtest 1-vcs1: SUCCESS (4.446s)
[190.653323] Subtest buffer-fill: SUCCESS (6.702s)
[190.700052] [029/133] (827s left) kms_feature_discovery (display)
[190.791354] Starting subtest: display
[190.793269] Subtest display: SUCCESS (0.000s)
[190.860766] [030/133] (827s left) syncobj_wait (invalid-single-wait-unsubmitted)
[190.923853] Starting subtest: invalid-single-wait-unsubmitted
[190.926098] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[191.030655] [031/133] (827s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[191.076870] Starting subtest: multi-wait-for-submit-submitted-signaled
[191.079190] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[191.167207] [032/133] (826s left) gem_exec_reloc (basic-concurrent0)
[191.228393] Subtest basic-concurrent0: SKIP (0.000s)
[191.294982] [033/133] (826s left) core_getversion (all-cards)
[191.334228] Starting subtest: all-cards
[191.340738] Subtest all-cards: SUCCESS (0.009s)
[191.384654] [034/133] (826s left) gem_request_retire (retire-vma-not-inactive)
[191.447855] Starting subtest: retire-vma-not-inactive
[191.504514] Subtest retire-vma-not-inactive: SUCCESS (0.056s)
[191.636990] [035/133] (826s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[191.728915] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[191.732405] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[191.814880] [036/133] (826s left) gem_exec_schedule (timeslicing)
[191.878961] Starting subtest: timeslicing
[191.880784] Starting dynamic subtest: rcs0
[191.893268] Dynamic subtest rcs0: SUCCESS (0.021s)
[191.902233] Starting dynamic subtest: bcs0
[191.914129] Dynamic subtest bcs0: SUCCESS (0.020s)
[191.923199] Starting dynamic subtest: vcs0
[191.934126] Dynamic subtest vcs0: SUCCESS (0.020s)
[191.948293] Starting dynamic subtest: vcs1
[191.954060] Dynamic subtest vcs1: SUCCESS (0.020s)
[191.969673] Starting dynamic subtest: vecs0
[191.975598] Dynamic subtest vecs0: SUCCESS (0.021s)
[191.988061] Starting dynamic subtest: ccs0
[191.996088] Dynamic subtest ccs0: SUCCESS (0.020s)
[191.997979] Subtest timeslicing: SUCCESS (0.124s)
[193.315921] [037/133] (824s left) kms_psr (pr-sprite-render)
[193.411292] Starting subtest: pr-sprite-render
[193.415896] Subtest pr-sprite-render: SKIP (0.000s)
[193.492340] [038/133] (824s left) kms_frontbuffer_tracking (basic)
[193.583180] Starting subtest: basic
[197.048235] Subtest basic: SUCCESS (3.468s)
[197.160969] [039/133] (821s left) prime_vgem (sync)
[197.297616] Starting subtest: sync
[197.299353] Starting dynamic subtest: rcs0
[197.342221] Dynamic subtest rcs0: SUCCESS (0.047s)
[197.351094] Starting dynamic subtest: bcs0
[197.399401] Dynamic subtest bcs0: SUCCESS (0.056s)
[197.407849] Starting dynamic subtest: vcs0
[197.447822] Dynamic subtest vcs0: SUCCESS (0.048s)
[197.456495] Starting dynamic subtest: vcs1
[197.491870] Dynamic subtest vcs1: SUCCESS (0.043s)
[197.500179] Starting dynamic subtest: vecs0
[197.537793] Dynamic subtest vecs0: SUCCESS (0.045s)
[197.546556] Starting dynamic subtest: ccs0
[197.584884] Dynamic subtest ccs0: SUCCESS (0.046s)
[197.586707] Subtest sync: SUCCESS (0.290s)
[197.711221] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[197.714544] [040/133] (820s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[197.832406] Starting subtest: cursorA-vs-flipA-atomic
[208.965996] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.131s)
[209.863833] [041/133] (808s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[209.956793] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[217.064645] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.111s)
[217.181747] [042/133] (801s left) prime_vgem (basic-gtt)
[217.261282] Starting subtest: basic-gtt
[217.265832] Subtest basic-gtt: SKIP (0.000s)
[217.380642] [043/133] (801s left) gem_ctx_persistence (hostile)
[217.433165] Starting subtest: hostile
[227.584840] Subtest hostile: SUCCESS (10.146s)
[227.715845] [044/133] (790s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[228.100883] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[228.894155] [045/133] (789s left) kms_frontbuffer_tracking (psr-1p-rte)
[228.984075] Starting subtest: psr-1p-rte
[234.419047] Subtest psr-1p-rte: SUCCESS (5.439s)
[234.556818] [046/133] (784s left) kms_psr (fbc-pr-primary-mmap-gtt)
[234.649936] Starting subtest: fbc-pr-primary-mmap-gtt
[234.654116] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[234.725342] [047/133] (784s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[234.836115] Starting subtest: cursorB-vs-flipB-atomic
[234.840506] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[235.018660] [048/133] (783s left) gem_eio (in-flight-internal-10ms)
[235.208282] Starting subtest: in-flight-internal-10ms
[236.359393] Subtest in-flight-internal-10ms: SUCCESS (1.151s)
[236.498752] [049/133] (782s left) kms_cursor_crc (cursor-offscreen-64x64)
[236.668495] Starting subtest: cursor-offscreen-64x64
[236.673535] Starting dynamic subtest: pipe-A-eDP-1
[238.627674] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.954s)
[238.659747] Starting dynamic subtest: pipe-D-eDP-1
[241.257054] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.605s)
[241.259324] Subtest cursor-offscreen-64x64: SUCCESS (4.588s)
[242.111512] [050/133] (776s left) gen7_exec_parse (oacontrol-tracking)
[242.172265] Subtest oacontrol-tracking: SKIP (0.000s)
[242.260744] [051/133] (776s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[242.355164] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[242.357453] Starting dynamic subtest: pipe-A-default-mode
[243.565310] Dynamic subtest pipe-A-default-mode: SKIP (1.207s)
[243.569376] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.208s)
[243.731491] [052/133] (775s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[243.835384] Starting subtest: disable-crc-after-crtc
[243.837364] Starting dynamic subtest: pipe-A-eDP-1
[245.494275] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.659s)
[245.503855] Starting dynamic subtest: pipe-B-eDP-1
[247.862364] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.367s)
[247.872802] Starting dynamic subtest: pipe-C-eDP-1
[250.104997] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.239s)
[250.114848] Starting dynamic subtest: pipe-D-eDP-1
[252.383971] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.279s)
[252.386309] Subtest disable-crc-after-crtc: SUCCESS (8.549s)
[253.202638] [053/133] (765s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[253.304296] Starting subtest: primary-yf-tiled-reflect-x-0
[253.375365] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.071s)
[253.485063] [054/133] (765s left) gem_ctx_persistence (engines-hang)
[253.550405] Starting subtest: engines-hang
[253.552920] Starting dynamic subtest: rcs0
[253.671744] Dynamic subtest rcs0: SUCCESS (0.121s)
[253.682597] Starting dynamic subtest: bcs0
[253.764705] Dynamic subtest bcs0: SUCCESS (0.091s)
[253.775592] Starting dynamic subtest: vcs0
[253.866686] Dynamic subtest vcs0: SUCCESS (0.100s)
[253.879517] Starting dynamic subtest: vcs1
[253.966831] Dynamic subtest vcs1: SUCCESS (0.098s)
[253.977120] Starting dynamic subtest: vecs0
[254.061996] Dynamic subtest vecs0: SUCCESS (0.093s)
[254.074220] Starting dynamic subtest: ccs0
[254.148852] Dynamic subtest ccs0: SUCCESS (0.085s)
[254.151118] Subtest engines-hang: SUCCESS (0.599s)
[254.229446] [055/133] (764s left) gem_eio (in-flight-1us)
[254.463495] Starting subtest: in-flight-1us
[263.065900] Subtest in-flight-1us: SUCCESS (8.602s)
[263.198249] [056/133] (755s left) gem_mmap_wc (pf-nonblock)
[263.252923] Subtest pf-nonblock: SKIP (0.000s)
[263.311177] [057/133] (755s left) kms_flip (plain-flip-fb-recreate-interruptible)
[263.404772] Starting subtest: plain-flip-fb-recreate-interruptible
[263.407107] Starting dynamic subtest: A-eDP1
[272.293437] Dynamic subtest A-eDP1: SUCCESS (8.888s)
[272.306904] Starting dynamic subtest: B-eDP1
[281.121738] Dynamic subtest B-eDP1: SUCCESS (8.825s)
[281.134786] Starting dynamic subtest: C-eDP1
[289.939508] Dynamic subtest C-eDP1: SUCCESS (8.816s)
[289.949081] Starting dynamic subtest: D-eDP1
[298.773755] Dynamic subtest D-eDP1: SUCCESS (8.833s)
[298.778142] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.372s)
[299.980657] [058/133] (719s left) gem_exec_reloc (basic-wc-gtt-active)
[300.048299] Subtest basic-wc-gtt-active: SKIP (0.000s)
[300.128340] [059/133] (719s left) kms_async_flips (async-flip-hang)
[300.229221] Starting subtest: async-flip-hang
[300.595668] Starting dynamic subtest: pipe-A-eDP-1
[315.738314] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.139s)
[316.135873] Starting dynamic subtest: pipe-B-eDP-1
[331.737520] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.609s)
[332.133022] Starting dynamic subtest: pipe-C-eDP-1
[347.737215] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.608s)
[348.110440] Starting dynamic subtest: pipe-D-eDP-1
[363.737523] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.631s)
[363.739810] Subtest async-flip-hang: SUCCESS (63.506s)
[364.896134] [060/133] (654s left) gem_exec_flush (basic-wb-set-default)
[364.955273] Starting subtest: basic-wb-set-default
[370.418785] Subtest basic-wb-set-default: SUCCESS (5.464s)
[370.607053] [061/133] (648s left) kms_cursor_edge_walk (128x128-top-edge)
[370.704289] Starting subtest: 128x128-top-edge
[370.706614] Starting dynamic subtest: pipe-A-eDP-1
[374.161782] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.458s)
[374.174596] Starting dynamic subtest: pipe-D-eDP-1
[377.292071] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.130s)
[377.294544] Subtest 128x128-top-edge: SUCCESS (6.590s)
[378.088600] [062/133] (641s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[378.207933] Starting subtest: x-tiled-8bpp-rotate-180
[379.455631] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.249s)
[379.582192] [063/133] (639s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[379.673938] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[379.677467] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[379.782173] [064/133] (639s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[379.866306] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[379.868280] Starting dynamic subtest: pipe-A-eDP-1
[379.887537] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[379.898965] Starting dynamic subtest: pipe-B-eDP-1
[379.916967] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[379.925936] Starting dynamic subtest: pipe-C-eDP-1
[379.943798] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[379.952414] Starting dynamic subtest: pipe-D-eDP-1
[379.971021] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[379.974838] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[382.162087] [065/133] (637s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[382.273403] Starting subtest: 4-tiled-32bpp-rotate-180
[383.774524] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.501s)
[383.907517] [066/133] (635s left) gem_exec_fence (syncobj-channel)
[384.346938] Starting subtest: syncobj-channel
[385.368849] Subtest syncobj-channel: SUCCESS (1.019s)
[386.361136] [067/133] (633s left) kms_mmap_write_crc (main)
[386.459791] Starting subtest: main
[386.462063] Starting dynamic subtest: pipe-A-eDP-1
[390.051922] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.593s)
[390.054481] Subtest main: SUCCESS (3.594s)
[390.909755] [068/133] (628s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[391.006808] Starting subtest: y-tiled-32bpp-rotate-180
[391.011297] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[391.113948] [069/133] (628s left) kms_pm_rpm (fences-dpms)
[392.643455] Starting subtest: fences-dpms
[392.648697] Subtest fences-dpms: SKIP (0.000s)
[393.348959] [070/133] (626s left) gem_ctx_param (set-priority-range)
[393.409578] Starting subtest: set-priority-range
[393.424267] Subtest set-priority-range: SUCCESS (0.014s)
[393.522743] [071/133] (626s left) api_intel_allocator (alloc-simple)
[393.572767] Starting subtest: alloc-simple
[393.574740] Subtest alloc-simple: SUCCESS (0.003s)
[393.683623] [072/133] (626s left) gem_exec_schedule (preempt-other-chain)
[393.754426] Starting subtest: preempt-other-chain
[393.756281] Starting dynamic subtest: rcs0
[393.835826] Dynamic subtest rcs0: SUCCESS (0.086s)
[393.852169] Starting dynamic subtest: bcs0
[393.921987] Dynamic subtest bcs0: SUCCESS (0.085s)
[393.938818] Starting dynamic subtest: vcs0
[394.013679] Dynamic subtest vcs0: SUCCESS (0.091s)
[394.023754] Starting dynamic subtest: vcs1
[394.098341] Dynamic subtest vcs1: SUCCESS (0.083s)
[394.109349] Starting dynamic subtest: vecs0
[394.183890] Dynamic subtest vecs0: SUCCESS (0.084s)
[394.198176] Starting dynamic subtest: ccs0
[394.275962] Dynamic subtest ccs0: SUCCESS (0.091s)
[394.278684] Subtest preempt-other-chain: SUCCESS (0.527s)
[395.354888] [073/133] (624s left) kms_addfb_basic (too-wide)
[395.465943] Starting subtest: too-wide
[395.468293] Subtest too-wide: SUCCESS (0.000s)
[395.590240] [074/133] (624s left) gem_userptr_blits (set-cache-level)
[395.651480] Starting subtest: set-cache-level
[395.655491] Subtest set-cache-level: SKIP (0.000s)
[395.798626] [075/133] (624s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[395.894259] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[395.898685] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[396.008747] [076/133] (624s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[396.104091] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[396.106378] Starting dynamic subtest: pipe-A-eDP-1
[396.115446] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[396.125698] Starting dynamic subtest: pipe-B-eDP-1
[396.134834] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[396.144626] Starting dynamic subtest: pipe-C-eDP-1
[396.153367] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[396.162621] Starting dynamic subtest: pipe-D-eDP-1
[396.171297] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[396.175789] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[398.405156] [077/133] (621s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[398.504012] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[398.516616] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[398.593666] [078/133] (621s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[398.684053] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[398.710049] Starting dynamic subtest: pipe-A-plane-0
[399.376588] Dynamic subtest pipe-A-plane-0: SUCCESS (0.666s)
[399.385898] Starting dynamic subtest: pipe-A-plane-3
[399.831684] Dynamic subtest pipe-A-plane-3: SUCCESS (0.455s)
[400.895806] Starting dynamic subtest: pipe-B-plane-0
[401.587156] Dynamic subtest pipe-B-plane-0: SUCCESS (0.700s)
[401.599525] Starting dynamic subtest: pipe-B-plane-3
[402.030994] Dynamic subtest pipe-B-plane-3: SUCCESS (0.444s)
[402.339973] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.657s)
[403.176116] [079/133] (616s left) gem_exec_params (rsvd2-dirt)
[403.232646] Starting subtest: rsvd2-dirt
[403.236997] Subtest rsvd2-dirt: SKIP (0.000s)
[403.360940] [080/133] (616s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[403.450560] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[403.454963] Starting dynamic subtest: pipe-A-eDP-1
[403.999440] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.518s)
[404.010214] Starting dynamic subtest: pipe-B-eDP-1
[405.484943] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.483s)
[405.499601] Starting dynamic subtest: pipe-C-eDP-1
[406.932401] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.448s)
[406.945360] Starting dynamic subtest: pipe-D-eDP-1
[408.377739] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.446s)
[408.379728] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.901s)
[409.595870] [081/133] (610s left) kms_big_fb (linear-16bpp-rotate-90)
[409.687397] Starting subtest: linear-16bpp-rotate-90
[409.707267] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[409.821438] [082/133] (610s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[409.923922] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[417.316449] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.396s)
[417.478045] [083/133] (602s left) kms_chamelium_audio (dp-audio)
[417.863773] Subtest dp-audio: SKIP (0.000s)
[418.629138] [084/133] (601s left) gem_bad_reloc (negative-reloc-lut)
[418.675791] Subtest negative-reloc-lut: SKIP (0.000s)
[418.761867] [085/133] (601s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[418.866368] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[418.870421] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[418.944320] [086/133] (601s left) i915_hangman (detector)
[419.015248] Starting subtest: detector
[419.017097] Starting dynamic subtest: rcs0
[429.276092] Dynamic subtest rcs0: SUCCESS (10.260s)
[429.289093] Starting dynamic subtest: bcs0
[441.243729] Dynamic subtest bcs0: SUCCESS (11.965s)
[441.256486] Starting dynamic subtest: vcs0
[453.213395] Dynamic subtest vcs0: SUCCESS (11.966s)
[453.229116] Starting dynamic subtest: vcs1
[464.220069] Dynamic subtest vcs1: SUCCESS (11.003s)
[464.233263] Starting dynamic subtest: vecs0
[476.189342] Dynamic subtest vecs0: SUCCESS (11.966s)
[476.202719] Starting dynamic subtest: ccs0
[486.235340] Dynamic subtest ccs0: SUCCESS (10.043s)
[486.238400] Subtest detector: SUCCESS (67.220s)
[486.320288] [087/133] (534s left) sysfs_heartbeat_interval (mixed)
[486.380403] Starting subtest: mixed
[486.382755] Starting dynamic subtest: vecs0
[491.635621] Dynamic subtest vecs0: SUCCESS (5.254s)
[491.649097] Starting dynamic subtest: ccs0
[496.850221] Dynamic subtest ccs0: SUCCESS (5.213s)
[496.863079] Starting dynamic subtest: vcs0
[502.089199] Dynamic subtest vcs0: SUCCESS (5.237s)
[502.101866] Starting dynamic subtest: bcs0
[507.286379] Dynamic subtest bcs0: SUCCESS (5.195s)
[507.298976] Starting dynamic subtest: rcs0
[512.497301] Dynamic subtest rcs0: SUCCESS (5.209s)
[512.510795] Starting dynamic subtest: vcs1
[517.693722] Dynamic subtest vcs1: SUCCESS (5.195s)
[517.696246] Subtest mixed: SUCCESS (31.314s)
[517.772984] [088/133] (502s left) kms_universal_plane (universal-plane-pageflip-windowed)
[517.870967] Starting subtest: universal-plane-pageflip-windowed
[517.873252] Starting dynamic subtest: pipe-A-eDP-1
[518.466612] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.594s)
[518.476963] Starting dynamic subtest: pipe-B-eDP-1
[519.668352] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.200s)
[519.679037] Starting dynamic subtest: pipe-C-eDP-1
[520.878180] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.209s)
[520.891306] Starting dynamic subtest: pipe-D-eDP-1
[522.103461] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.225s)
[522.105845] Subtest universal-plane-pageflip-windowed: SUCCESS (4.234s)
[522.956341] [089/133] (497s left) kms_dp_link_training (uhbr-sst)
[523.059218] Starting subtest: uhbr-sst
[523.067304] Subtest uhbr-sst: SKIP (0.000s)
[523.144566] [090/133] (497s left) gem_exec_balancer (bonded-pair)
[523.210743] Starting subtest: bonded-pair
[523.215329] Subtest bonded-pair: SKIP (0.000s)
[523.541122] [091/133] (497s left) gem_exec_fence (invalid-fence-array)
[523.997833] Starting subtest: invalid-fence-array
[524.003630] Subtest invalid-fence-array: SUCCESS (0.002s)
[525.367139] [092/133] (495s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[525.464066] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[525.468154] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[525.575624] [093/133] (495s left) kms_psr (psr-cursor-plane-onoff)
[525.657065] Starting subtest: psr-cursor-plane-onoff
[525.659281] Starting dynamic subtest: eDP-1
[527.356679] Dynamic subtest eDP-1: SUCCESS (1.700s)
[527.359062] Subtest psr-cursor-plane-onoff: SUCCESS (1.701s)
[528.233093] [094/133] (492s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[528.334619] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[528.338774] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[528.440459] [095/133] (492s left) kms_busy (basic)
[528.533134] Starting subtest: basic
[528.535465] Starting dynamic subtest: flip
[529.127100] Dynamic subtest flip: SUCCESS (0.593s)
[529.138588] Starting dynamic subtest: modeset
[532.537652] Dynamic subtest modeset: SUCCESS (3.410s)
[532.540101] Subtest basic: SUCCESS (4.006s)
[533.336639] [096/133] (487s left) gem_exec_async (forked-writes)
[533.396862] Starting subtest: forked-writes
[533.399262] Starting dynamic subtest: rcs0
[533.425164] Dynamic subtest rcs0: SUCCESS (0.028s)
[533.436909] Starting dynamic subtest: bcs0
[533.447553] Dynamic subtest bcs0: SUCCESS (0.022s)
[533.459999] Starting dynamic subtest: vcs0
[533.470222] Dynamic subtest vcs0: SUCCESS (0.022s)
[533.480827] Starting dynamic subtest: vcs1
[533.494442] Dynamic subtest vcs1: SUCCESS (0.023s)
[533.505088] Starting dynamic subtest: vecs0
[533.522109] Dynamic subtest vecs0: SUCCESS (0.027s)
[533.534601] Starting dynamic subtest: ccs0
[533.549136] Dynamic subtest ccs0: SUCCESS (0.026s)
[533.551476] Subtest forked-writes: SUCCESS (0.153s)
[533.793280] [097/133] (487s left) prime_vgem (coherency-gtt)
[533.866281] Starting subtest: coherency-gtt
[533.870862] Subtest coherency-gtt: SKIP (0.000s)
[533.981303] [098/133] (487s left) kms_properties (colorop-properties-legacy)
[534.076225] Starting subtest: colorop-properties-legacy
[534.078536] Starting dynamic subtest: pipe-A-eDP-1
[534.446257] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.372s)
[534.456917] Starting dynamic subtest: pipe-B-eDP-1
[535.510778] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.060s)
[535.520935] Starting dynamic subtest: pipe-C-eDP-1
[536.545380] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.034s)
[536.555796] Starting dynamic subtest: pipe-D-eDP-1
[537.576131] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.029s)
[537.578336] Subtest colorop-properties-legacy: SUCCESS (3.501s)
[538.371623] [099/133] (482s left) gem_mmap_wc (set-cache-level)
[538.430596] Subtest set-cache-level: SKIP (0.000s)
[538.494186] [100/133] (482s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[538.581605] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[545.560361] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.979s)
[545.689435] [101/133] (475s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[545.793225] Starting subtest: fbc-psr2-primary-mmap-cpu
[545.795499] Starting dynamic subtest: eDP-1
[545.799576] Dynamic subtest eDP-1: SKIP (0.000s)
[545.803665] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[545.879228] [102/133] (475s left) syncobj_timeline (32bits-limit)
[545.931592] Starting subtest: 32bits-limit
[545.934019] Subtest 32bits-limit: SUCCESS (0.001s)
[546.053444] [103/133] (475s left) perf_pmu (busy-accuracy-98)
[546.093799] Starting subtest: busy-accuracy-98
[546.096090] Starting dynamic subtest: rcs0
[549.132292] Dynamic subtest rcs0: SUCCESS (3.035s)
[549.144992] Starting dynamic subtest: bcs0
[552.187098] Dynamic subtest bcs0: SUCCESS (3.052s)
[552.197362] Starting dynamic subtest: vcs0
[555.231050] Dynamic subtest vcs0: SUCCESS (3.041s)
[555.241110] Starting dynamic subtest: vcs1
[558.270662] Dynamic subtest vcs1: SUCCESS (3.037s)
[558.283223] Starting dynamic subtest: vecs0
[561.311381] Dynamic subtest vecs0: SUCCESS (3.039s)
[561.323629] Starting dynamic subtest: ccs0
[564.360699] Dynamic subtest ccs0: SUCCESS (3.048s)
[564.363247] Subtest busy-accuracy-98: SUCCESS (18.263s)
[564.475244] [104/133] (456s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[564.570873] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[564.573448] Starting dynamic subtest: pipe-A
[564.595193] Dynamic subtest pipe-A: SKIP (0.019s)
[564.605795] Starting dynamic subtest: pipe-B
[564.628925] Dynamic subtest pipe-B: SKIP (0.034s)
[564.639012] Starting dynamic subtest: pipe-C
[564.665452] Dynamic subtest pipe-C: SKIP (0.034s)
[564.675436] Starting dynamic subtest: pipe-D
[564.699139] Dynamic subtest pipe-D: SKIP (0.034s)
[564.703310] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.125s)
[564.798308] [105/133] (456s left) gem_exec_whisper (basic-normal-all)
[564.861757] Starting subtest: basic-normal-all
[568.317904] Subtest basic-normal-all: SUCCESS (3.454s)
[568.505671] [106/133] (452s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[568.617485] Starting subtest: verify-pxp-execution-after-suspend-resume
[575.793707] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.178s)
[575.934449] [107/133] (445s left) gem_exec_reloc (basic-range-active)
[576.004871] Subtest basic-range-active: SKIP (0.000s)
[576.090174] [108/133] (445s left) kms_content_protection (dp-mst-type-1)
[576.236994] Starting subtest: dp-mst-type-1
[576.241516] Subtest dp-mst-type-1: SKIP (0.000s)
[576.374228] [109/133] (445s left) kms_invalid_mode (bad-htotal)
[576.461253] Starting subtest: bad-htotal
[576.463590] Starting dynamic subtest: pipe-A-eDP-1
[576.744543] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[576.756924] Starting dynamic subtest: pipe-B-eDP-1
[576.758946] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[576.769850] Starting dynamic subtest: pipe-C-eDP-1
[576.772082] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[576.782617] Starting dynamic subtest: pipe-D-eDP-1
[576.785187] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[576.788034] Subtest bad-htotal: SUCCESS (0.291s)
[577.581896] [110/133] (443s left) gem_userptr_blits (unsync-unmap-after-close)
[577.645853] Subtest unsync-unmap-after-close: SKIP (0.000s)
[577.786625] [111/133] (443s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[577.884366] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[577.888548] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[578.002349] [112/133] (443s left) gem_exec_whisper (basic-queues-priority)
[578.061245] Starting subtest: basic-queues-priority
[602.251652] Subtest basic-queues-priority: SUCCESS (24.187s)
[602.440606] [113/133] (419s left) kms_flip (2x-flip-vs-suspend)
[602.552068] Starting subtest: 2x-flip-vs-suspend
[602.556475] Subtest 2x-flip-vs-suspend: SKIP (0.003s)
[602.637798] [114/133] (419s left) gem_ctx_engines (execute-one)
[602.694750] Starting subtest: execute-one
[605.096163] Subtest execute-one: SUCCESS (2.402s)
[605.352341] [115/133] (416s left) kms_color_pipeline (plane-ctm3x4)
[605.446756] Starting subtest: plane-ctm3x4
[605.604060] Starting dynamic subtest: pipe-A-plane-0
[605.749909] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[605.761400] Starting dynamic subtest: pipe-A-plane-1
[605.903820] Dynamic subtest pipe-A-plane-1: SUCCESS (0.154s)
[605.915200] Starting dynamic subtest: pipe-A-plane-2
[606.059067] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[607.282597] Starting dynamic subtest: pipe-B-plane-0
[607.439431] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[607.450568] Starting dynamic subtest: pipe-B-plane-1
[607.606372] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[607.617905] Starting dynamic subtest: pipe-B-plane-2
[607.761918] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[609.011356] Starting dynamic subtest: pipe-C-plane-0
[609.134968] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[609.146275] Starting dynamic subtest: pipe-C-plane-1
[609.268450] Dynamic subtest pipe-C-plane-1: SUCCESS (0.132s)
[609.280378] Starting dynamic subtest: pipe-C-plane-2
[609.401655] Dynamic subtest pipe-C-plane-2: SUCCESS (0.132s)
[610.663068] Starting dynamic subtest: pipe-D-plane-0
[610.786206] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[610.797049] Starting dynamic subtest: pipe-D-plane-1
[610.919653] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[610.930930] Starting dynamic subtest: pipe-D-plane-2
[611.052547] Dynamic subtest pipe-D-plane-2: SUCCESS (0.132s)
[611.352069] Subtest plane-ctm3x4: SUCCESS (5.904s)
[612.196136] [116/133] (409s left) kms_plane (plane-position-hole-dpms)
[612.286259] Starting subtest: plane-position-hole-dpms
[612.825067] Starting dynamic subtest: pipe-A-plane-1
[615.213365] Dynamic subtest pipe-A-plane-1: SUCCESS (2.390s)
[615.225048] Starting dynamic subtest: pipe-A-plane-2
[617.635923] Dynamic subtest pipe-A-plane-2: SUCCESS (2.421s)
[617.647613] Starting dynamic subtest: pipe-A-plane-3
[620.011795] Dynamic subtest pipe-A-plane-3: SUCCESS (2.377s)
[620.024205] Starting dynamic subtest: pipe-A-plane-4
[622.385339] Dynamic subtest pipe-A-plane-4: SUCCESS (2.371s)
[622.396642] Starting dynamic subtest: pipe-A-plane-5
[622.412887] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[623.638256] Starting dynamic subtest: pipe-B-plane-1
[626.084855] Dynamic subtest pipe-B-plane-1: SUCCESS (2.458s)
[626.097788] Starting dynamic subtest: pipe-B-plane-2
[628.545071] Dynamic subtest pipe-B-plane-2: SUCCESS (2.460s)
[628.557326] Starting dynamic subtest: pipe-B-plane-3
[631.006485] Dynamic subtest pipe-B-plane-3: SUCCESS (2.458s)
[631.017943] Starting dynamic subtest: pipe-B-plane-4
[633.429524] Dynamic subtest pipe-B-plane-4: SUCCESS (2.424s)
[633.441039] Starting dynamic subtest: pipe-B-plane-5
[633.455389] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[633.457691] Subtest plane-position-hole-dpms: SUCCESS (21.170s)
[634.254592] [117/133] (387s left) kms_plane_cursor (primary)
[634.342255] Starting subtest: primary
[634.346787] Starting dynamic subtest: pipe-A-eDP-1-size-64
[637.027858] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.685s)
[637.052929] Starting dynamic subtest: pipe-A-eDP-1-size-128
[639.672975] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.628s)
[639.698211] Starting dynamic subtest: pipe-A-eDP-1-size-256
[642.384558] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.694s)
[642.702048] Starting dynamic subtest: pipe-B-eDP-1-size-64
[646.054361] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.364s)
[646.080676] Starting dynamic subtest: pipe-B-eDP-1-size-128
[648.766682] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.694s)
[648.792093] Starting dynamic subtest: pipe-B-eDP-1-size-256
[651.433886] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.650s)
[651.758662] Starting dynamic subtest: pipe-C-eDP-1-size-64
[654.840274] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.094s)
[654.868464] Starting dynamic subtest: pipe-C-eDP-1-size-128
[657.217431] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.358s)
[657.246140] Starting dynamic subtest: pipe-C-eDP-1-size-256
[659.595792] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.359s)
[659.919583] Starting dynamic subtest: pipe-D-eDP-1-size-64
[662.991216] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.085s)
[663.022102] Starting dynamic subtest: pipe-D-eDP-1-size-128
[665.358405] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.348s)
[665.387171] Starting dynamic subtest: pipe-D-eDP-1-size-256
[667.727405] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.347s)
[668.034021] Subtest primary: SUCCESS (33.692s)
[668.877392] [118/133] (353s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[668.981785] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[668.986174] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[669.101973] [119/133] (352s left) gen9_exec_parse (unaligned-access)
[669.153630] Subtest unaligned-access: SKIP (0.000s)
[669.216298] [120/133] (352s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[669.267983] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[669.272259] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[669.463804] [121/133] (352s left) kms_cursor_crc (cursor-random-64x64)
[669.641645] Starting subtest: cursor-random-64x64
[669.646989] Starting dynamic subtest: pipe-A-eDP-1
[673.009701] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.365s)
[673.044121] Starting dynamic subtest: pipe-D-eDP-1
[677.007657] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.971s)
[677.010026] Subtest cursor-random-64x64: SUCCESS (7.364s)
[677.853911] [122/133] (344s left) dumb_buffer (create-valid-dumb)
[677.919759] Starting subtest: create-valid-dumb
[677.921980] Subtest create-valid-dumb: SUCCESS (0.000s)
[678.009658] [123/133] (344s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[678.071669] Starting subtest: etime-multi-wait-all-submitted-signaled
[678.177502] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[678.267592] [124/133] (343s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[678.368551] Starting subtest: 4-tiled-16bpp-rotate-180
[679.714812] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.346s)
[679.893219] [125/133] (342s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[679.987091] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[679.990470] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[680.119526] [126/133] (342s left) i915_pm_rc6_residency (rc6-idle)
[680.171500] Starting subtest: rc6-idle
[680.184746] Starting dynamic subtest: gt0-rcs0
[704.395266] Dynamic subtest gt0-rcs0: SUCCESS (24.209s)
[704.405781] Starting dynamic subtest: gt0-bcs0
[728.686209] Dynamic subtest gt0-bcs0: SUCCESS (24.289s)
[728.697077] Starting dynamic subtest: gt0-ccs0
[753.028480] Dynamic subtest gt0-ccs0: SUCCESS (24.339s)
[753.039658] Starting dynamic subtest: gt1-vcs0
[759.201949] Dynamic subtest gt1-vcs0: SUCCESS (6.168s)
[759.216193] Starting dynamic subtest: gt1-vecs0
[765.374229] Dynamic subtest gt1-vecs0: SUCCESS (6.168s)
[765.377145] Subtest rc6-idle: SUCCESS (85.202s)
[765.466828] [127/133] (256s left) kms_pm_rpm (universal-planes-dpms)
[767.503745] Starting subtest: universal-planes-dpms
[767.505950] Starting dynamic subtest: plane-35
[775.830358] Dynamic subtest plane-35: SUCCESS (8.330s)
[775.845769] Starting dynamic subtest: plane-65
[784.128175] Dynamic subtest plane-65: SUCCESS (8.295s)
[784.141273] Starting dynamic subtest: plane-95
[792.453995] Dynamic subtest plane-95: SUCCESS (8.323s)
[792.467051] Starting dynamic subtest: plane-125
[801.092832] Dynamic subtest plane-125: SUCCESS (8.636s)
[801.106654] Starting dynamic subtest: plane-135
[809.446098] Dynamic subtest plane-135: SUCCESS (8.350s)
[809.459843] Starting dynamic subtest: plane-145
[818.048470] Dynamic subtest plane-145: SUCCESS (8.599s)
[818.051859] Subtest universal-planes-dpms: SUCCESS (50.550s)
[818.432939] [128/133] (203s left) gem_mmap_offset (bad-object)
[818.493221] Starting subtest: bad-object
[818.495617] Subtest bad-object: SUCCESS (0.001s)
[818.614467] [129/133] (203s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[818.708356] Starting subtest: fbc-modesetfrombusy
[822.503349] Subtest fbc-modesetfrombusy: SUCCESS (3.798s)
[823.685739] [130/133] (198s left) kms_properties (invalid-properties-legacy)
[823.777029] Starting subtest: invalid-properties-legacy
[823.884219] Subtest invalid-properties-legacy: SUCCESS (0.107s)
[823.956676] [131/133] (198s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[824.020719] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[824.022644] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[824.110976] [132/133] (198s left) gem_softpin (evict-single-offset)
[824.172416] Starting subtest: evict-single-offset
[852.285808] Subtest evict-single-offset: SUCCESS (28.114s)
[852.388538] [133/133] (170s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[852.549588] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[852.553598] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[852.649976] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_ccs
results: parsing output: 3/ for test: gem_mmap_gtt
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_cursor_legacy
results: parsing output: 8/ for test: gem_softpin
results: parsing output: 9/ for test: gem_mmap_wc
results: parsing output: 10/ for test: gem_flink_basic
results: parsing output: 11/ for test: gem_exec_params
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: perf_pmu
results: parsing output: 14/ for test: gem_render_copy_redux
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_ctx_param
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: kms_vblank
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: i915_query
results: parsing output: 24/ for test: kms_chamelium_frames
results: parsing output: 25/ for test: kms_cursor_legacy
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: perf
results: parsing output: 28/ for test: kms_feature_discovery
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: gem_exec_reloc
results: parsing output: 32/ for test: core_getversion
results: parsing output: 33/ for test: gem_request_retire
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_exec_schedule
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: prime_vgem
results: parsing output: 39/ for test: kms_cursor_legacy
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: prime_vgem
results: parsing output: 42/ for test: gem_ctx_persistence
results: parsing output: 43/ for test: kms_chamelium_hpd
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: gem_eio
results: parsing output: 48/ for test: kms_cursor_crc
results: parsing output: 49/ for test: gen7_exec_parse
results: parsing output: 50/ for test: kms_flip_scaled_crc
results: parsing output: 51/ for test: kms_pipe_crc_basic
results: parsing output: 52/ for test: kms_rotation_crc
results: parsing output: 53/ for test: gem_ctx_persistence
results: parsing output: 54/ for test: gem_eio
results: parsing output: 55/ for test: gem_mmap_wc
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: kms_async_flips
results: parsing output: 59/ for test: gem_exec_flush
results: parsing output: 60/ for test: kms_cursor_edge_walk
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: gem_exec_fence
results: parsing output: 66/ for test: kms_mmap_write_crc
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_pm_rpm
results: parsing output: 69/ for test: gem_ctx_param
results: parsing output: 70/ for test: api_intel_allocator
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: kms_addfb_basic
results: parsing output: 73/ for test: gem_userptr_blits
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: kms_flip
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_chamelium_audio
results: parsing output: 83/ for test: gem_bad_reloc
results: parsing output: 84/ for test: kms_psr2_sf
results: parsing output: 85/ for test: i915_hangman
results: parsing output: 86/ for test: sysfs_heartbeat_interval
results: parsing output: 87/ for test: kms_universal_plane
results: parsing output: 88/ for test: kms_dp_link_training
results: parsing output: 89/ for test: gem_exec_balancer
results: parsing output: 90/ for test: gem_exec_fence
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_busy
results: parsing output: 95/ for test: gem_exec_async
results: parsing output: 96/ for test: prime_vgem
results: parsing output: 97/ for test: kms_properties
results: parsing output: 98/ for test: gem_mmap_wc
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: perf_pmu
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: gem_exec_whisper
results: parsing output: 105/ for test: gem_pxp
results: parsing output: 106/ for test: gem_exec_reloc
results: parsing output: 107/ for test: kms_content_protection
results: parsing output: 108/ for test: kms_invalid_mode
results: parsing output: 109/ for test: gem_userptr_blits
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_exec_whisper
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: gem_ctx_engines
results: parsing output: 114/ for test: kms_color_pipeline
results: parsing output: 115/ for test: kms_plane
results: parsing output: 116/ for test: kms_plane_cursor
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: gen9_exec_parse
results: parsing output: 119/ for test: gem_render_copy
results: parsing output: 120/ for test: kms_cursor_crc
results: parsing output: 121/ for test: dumb_buffer
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: i915_pm_rc6_residency
results: parsing output: 126/ for test: kms_pm_rpm
results: parsing output: 127/ for test: gem_mmap_offset
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_properties
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: gem_softpin
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.