[58.702482] Initializing watchdogs
[58.702534]   /dev/watchdog0
[58.717186] [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]
[58.721527] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.723589] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.725495] [001/130] (960s left) kms_async_flips (alternate-sync-async-flip-atomic)
[58.827672] Starting subtest: alternate-sync-async-flip-atomic
[59.199068] Starting dynamic subtest: pipe-A-eDP-1
[61.947648] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.753s)
[62.323296] Starting dynamic subtest: pipe-B-eDP-1
[65.079248] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.763s)
[65.463497] Starting dynamic subtest: pipe-C-eDP-1
[68.219983] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.764s)
[68.579592] Starting dynamic subtest: pipe-D-eDP-1
[71.349081] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.777s)
[71.350186] Subtest alternate-sync-async-flip-atomic: SUCCESS (12.534s)
[72.532391] [002/130] (946s left) i915_pm_rpm (sysfs-read)
[74.078995] Starting subtest: sysfs-read
[75.242244] Subtest sysfs-read: SUCCESS (1.164s)
[75.631201] [003/130] (943s left) gem_ctx_param (invalid-size-set)
[75.686348] Starting subtest: invalid-size-set
[75.687257] Subtest invalid-size-set: SUCCESS (0.001s)
[75.814471] [004/130] (943s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[75.910284] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[75.912114] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[76.048398] [005/130] (943s left) kms_lease (lease-invalid-crtc)
[76.146949] Starting subtest: lease-invalid-crtc
[76.147893] Starting dynamic subtest: pipe-A-eDP-1
[76.148800] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[76.157995] Starting dynamic subtest: pipe-B-eDP-1
[76.159026] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[76.167264] Starting dynamic subtest: pipe-C-eDP-1
[76.168108] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[76.173718] Starting dynamic subtest: pipe-D-eDP-1
[76.174675] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[76.175561] Subtest lease-invalid-crtc: SUCCESS (0.003s)
[76.235636] [006/130] (943s left) gem_mmap_offset (blt-coherency)
[76.293255] Starting subtest: blt-coherency
[76.301108] Subtest blt-coherency: SUCCESS (0.008s)
[76.471459] [007/130] (942s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[76.526893] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[76.635395] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.106s)
[76.713199] [008/130] (942s left) gem_ctx_shared (detached-shared-gtt)
[76.776426] Starting subtest: detached-shared-gtt
[78.930121] Subtest detached-shared-gtt: SUCCESS (2.151s)
[79.340419] [009/130] (940s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[79.437926] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[79.438854] Starting dynamic subtest: pipe-A-eDP-1
[81.476138] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.037s)
[81.483474] Starting dynamic subtest: pipe-B-eDP-1
[83.547818] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.070s)
[83.554038] Starting dynamic subtest: pipe-C-eDP-1
[85.548749] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.000s)
[85.555058] Starting dynamic subtest: pipe-D-eDP-1
[87.689097] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.139s)
[87.690093] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.251s)
[88.957872] [010/130] (930s left) gem_exec_schedule (wide)
[89.134794] Starting subtest: wide
[89.136415] Starting dynamic subtest: rcs0
[99.940788] Dynamic subtest rcs0: SUCCESS (10.804s)
[99.949828] Starting dynamic subtest: vcs0
[110.991443] Dynamic subtest vcs0: SUCCESS (11.049s)
[110.998461] Starting dynamic subtest: vcs1
[122.153961] Dynamic subtest vcs1: SUCCESS (11.161s)
[122.163171] Starting dynamic subtest: vecs0
[137.327397] Dynamic subtest vecs0: SUCCESS (15.172s)
[137.337029] Starting dynamic subtest: ccs0
[156.651705] Dynamic subtest ccs0: SUCCESS (19.322s)
[156.652982] Subtest wide: SUCCESS (67.514s)
[158.751939] [FACT gem_exec_schedule (wide)] new: kernel.kmod_is_loaded.vgem: true
[158.753688] [011/130] (860s left) kms_pm_rpm (drm-resources-equal)
[161.078931] Starting subtest: drm-resources-equal
[166.070098] Subtest drm-resources-equal: SUCCESS (4.993s)
[166.179260] [012/130] (853s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[166.242762] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[166.243866] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[166.359732] [013/130] (853s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[166.453783] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[166.454770] Starting dynamic subtest: pipe-A-eDP-1
[166.464212] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[166.471485] Starting dynamic subtest: pipe-B-eDP-1
[166.480599] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[166.487153] Starting dynamic subtest: pipe-C-eDP-1
[166.496162] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[166.501981] Starting dynamic subtest: pipe-D-eDP-1
[166.511450] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[166.513076] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[168.679144] [014/130] (850s left) syncobj_timeline (single-wait-all-signaled)
[168.724172] Starting subtest: single-wait-all-signaled
[168.725107] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[168.840738] [015/130] (850s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[168.947962] Starting subtest: yf-tiled-16bpp-rotate-90
[168.949898] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[169.058932] [016/130] (850s left) gem_ctx_bad_destroy (double-destroy)
[169.120984] Starting subtest: double-destroy
[169.122536] Subtest double-destroy: SUCCESS (0.000s)
[169.210805] [017/130] (850s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[169.305163] Starting subtest: psr-rgb565-draw-mmap-cpu
[175.974779] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.670s)
[176.107260] [018/130] (843s left) fbdev (eof)
[176.151115] Starting subtest: eof
[176.152192] Subtest eof: SUCCESS (0.000s)
[176.240241] [019/130] (843s left) syncobj_basic (bad-fd-to-handle)
[176.301528] Starting subtest: bad-fd-to-handle
[176.302518] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[176.439163] [020/130] (843s left) gem_exec_fence (basic-busy-all)
[176.508194] Starting subtest: basic-busy-all
[176.597500] Subtest basic-busy-all: SUCCESS (0.090s)
[178.974911] [021/130] (840s left) gem_exec_fence (syncobj-signal)
[179.412610] Starting subtest: syncobj-signal
[179.420540] Subtest syncobj-signal: SUCCESS (0.008s)
[180.987897] [022/130] (838s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[181.051939] Starting subtest: invalid-single-wait-all-unsubmitted
[181.053887] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[181.188638] [023/130] (838s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[181.296491] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[181.932963] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.637s)
[182.132169] [024/130] (837s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[182.193581] Starting subtest: yf-tiled-to-vebox-x-tiled
[182.195268] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[182.393230] [025/130] (837s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[182.487276] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[190.035759] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.549s)
[190.163755] [026/130] (829s left) perf (invalid-create-userspace-config)
[190.299713] Starting subtest: invalid-create-userspace-config
[190.300978] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[190.341278] [027/130] (829s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[190.433947] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[190.435773] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[190.547577] [028/130] (829s left) gem_cs_tlb (engines)
[190.607237] Starting subtest: engines
[190.608364] Starting dynamic subtest: rcs0
[192.763227] Dynamic subtest rcs0: SUCCESS (2.152s)
[192.769917] Starting dynamic subtest: bcs0
[194.915899] Dynamic subtest bcs0: SUCCESS (2.151s)
[194.925074] Starting dynamic subtest: vcs0
[197.067487] Dynamic subtest vcs0: SUCCESS (2.152s)
[197.073979] Starting dynamic subtest: vcs1
[199.218661] Dynamic subtest vcs1: SUCCESS (2.151s)
[199.226057] Starting dynamic subtest: vecs0
[201.372869] Dynamic subtest vecs0: SUCCESS (2.152s)
[201.379828] Starting dynamic subtest: ccs0
[203.527467] Dynamic subtest ccs0: SUCCESS (2.154s)
[203.528365] Subtest engines: SUCCESS (12.917s)
[203.680912] [029/130] (816s left) syncobj_timeline (etime-single-wait-submitted)
[203.736271] Starting subtest: etime-single-wait-submitted
[203.843421] Subtest etime-single-wait-submitted: SUCCESS (0.104s)
[203.939847] [030/130] (816s left) gem_spin_batch (resubmit-all)
[204.000506] Starting subtest: resubmit-all
[204.001489] Starting dynamic subtest: rcs0
[204.023281] Dynamic subtest rcs0: SUCCESS (0.024s)
[204.034310] Starting dynamic subtest: bcs0
[204.035197] Dynamic subtest bcs0: SUCCESS (0.004s)
[204.045832] Starting dynamic subtest: vcs0
[204.047315] Dynamic subtest vcs0: SUCCESS (0.003s)
[204.055863] Starting dynamic subtest: vcs1
[204.056765] Dynamic subtest vcs1: SUCCESS (0.004s)
[204.062778] Starting dynamic subtest: vecs0
[204.063658] Dynamic subtest vecs0: SUCCESS (0.003s)
[204.070367] Starting dynamic subtest: ccs0
[204.071696] Dynamic subtest ccs0: SUCCESS (0.004s)
[204.072744] Subtest resubmit-all: SUCCESS (0.047s)
[205.968547] [031/130] (814s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[206.063455] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[206.074401] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[206.195865] [032/130] (813s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[206.274447] Starting subtest: psr-2p-pri-indfb-multidraw
[206.276088] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[206.397653] [033/130] (813s left) gem_pxp (reject-modify-context-protection-off-3)
[206.510027] Starting subtest: reject-modify-context-protection-off-3
[206.511597] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[206.636241] [034/130] (813s left) gem_exec_schedule (semaphore-user)
[206.695553] Starting subtest: semaphore-user
[206.753975] Subtest semaphore-user: SUCCESS (0.057s)
[207.952227] [035/130] (812s left) gem_tiled_partial_pwrite_pread (writes)
[208.012959] Subtest writes: SKIP (0.000s)
[208.061864] [036/130] (812s left) kms_cursor_crc (cursor-dpms)
[208.187210] Starting subtest: cursor-dpms
[208.189054] Starting dynamic subtest: pipe-A-eDP-1
[211.243211] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.055s)
[211.251281] Starting dynamic subtest: pipe-D-eDP-1
[214.841852] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.597s)
[214.842865] Subtest cursor-dpms: SUCCESS (6.654s)
[215.691157] [037/130] (804s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[215.801963] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[215.803971] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[215.937433] [038/130] (804s left) i915_pm_freq_api (freq-reset)
[216.010522] Starting subtest: freq-reset
[216.011631] Starting dynamic subtest: gt0
[216.207025] Dynamic subtest gt0: SUCCESS (0.196s)
[216.213883] Starting dynamic subtest: gt1
[216.376265] Dynamic subtest gt1: SUCCESS (0.165s)
[216.377403] Subtest freq-reset: SUCCESS (0.363s)
[216.461540] [039/130] (803s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[216.564551] Starting subtest: fbc-psr2-sprite-plane-onoff
[216.565443] Starting dynamic subtest: eDP-1
[216.567083] Dynamic subtest eDP-1: SKIP (0.000s)
[216.568703] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.003s)
[216.657163] [040/130] (803s left) perf_pmu (busy-accuracy-2)
[216.690216] Starting subtest: busy-accuracy-2
[216.691091] Starting dynamic subtest: rcs0
[219.731618] Dynamic subtest rcs0: SUCCESS (3.040s)
[219.742510] Starting dynamic subtest: bcs0
[222.793291] Dynamic subtest bcs0: SUCCESS (3.059s)
[222.802565] Starting dynamic subtest: vcs0
[225.836273] Dynamic subtest vcs0: SUCCESS (3.042s)
[225.844862] Starting dynamic subtest: vcs1
[228.884174] Dynamic subtest vcs1: SUCCESS (3.045s)
[228.893546] Starting dynamic subtest: vecs0
[231.933737] Dynamic subtest vecs0: SUCCESS (3.047s)
[231.942826] Starting dynamic subtest: ccs0
[234.990035] Dynamic subtest ccs0: SUCCESS (3.056s)
[234.991059] Subtest busy-accuracy-2: SUCCESS (18.300s)
[235.121958] [041/130] (785s left) gem_exec_balancer (bonded-true-hang)
[235.368451] Starting subtest: bonded-true-hang
[235.370824] Subtest bonded-true-hang: SKIP (0.000s)
[235.507582] [042/130] (784s left) gen9_exec_parse (valid-registers)
[235.562919] Subtest valid-registers: SKIP (0.000s)
[235.635954] [043/130] (784s left) gem_lmem_swapping (massive)
[235.670803] Subtest massive: SKIP (0.000s)
[235.712806] [044/130] (784s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[235.802378] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[235.804298] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[235.943687] [045/130] (784s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[236.038036] Starting subtest: extended-pageflip-modeset-hang-oldfb
[236.039066] Starting dynamic subtest: pipe-A
[243.945982] Dynamic subtest pipe-A: SUCCESS (7.907s)
[243.954484] Starting dynamic subtest: pipe-D
[252.578162] Dynamic subtest pipe-D: SUCCESS (8.629s)
[252.579053] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.540s)
[253.446740] [046/130] (767s left) kms_vblank (wait-busy)
[253.542306] Starting subtest: wait-busy
[253.543196] Starting dynamic subtest: pipe-A-eDP-1
[255.427447] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.885s)
[255.437561] Starting dynamic subtest: pipe-D-eDP-1
[257.993987] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.563s)
[257.995033] Subtest wait-busy: SUCCESS (4.452s)
[258.817345] [047/130] (761s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[258.923677] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[258.925531] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[259.063612] [048/130] (761s left) gem_tiled_pread_basic
[259.185251] [049/130] (761s left) drm_read (fault-buffer)
[259.349083] Starting subtest: fault-buffer
[259.350148] Subtest fault-buffer: SUCCESS (0.000s)
[259.512859] [050/130] (761s left) perf (short-reads)
[259.619268] Starting subtest: short-reads
[259.633491] Subtest short-reads: SUCCESS (0.015s)
[259.664601] [051/130] (761s left) kms_plane_lowres (tiling-x)
[259.756440] Starting subtest: tiling-x
[259.757396] Starting dynamic subtest: pipe-A-eDP-1
[259.759260] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[260.056228] Starting dynamic subtest: pipe-B-eDP-1
[260.058232] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[260.067251] Starting dynamic subtest: pipe-C-eDP-1
[260.069124] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[260.076162] Starting dynamic subtest: pipe-D-eDP-1
[260.077967] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[260.079831] Subtest tiling-x: SKIP (0.300s)
[260.863663] [052/130] (759s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[260.951362] Starting subtest: sprite-rotation-90-pos-100-0
[260.953227] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[261.042080] [053/130] (759s left) syncobj_wait (invalid-reset-zero-handles)
[261.106101] Starting subtest: invalid-reset-zero-handles
[261.107170] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[261.233365] [054/130] (759s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[261.349606] Starting subtest: basic-busy-flip-before-cursor-legacy
[261.351512] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[261.511876] [055/130] (759s left) api_intel_allocator (reopen)
[261.568772] Starting subtest: reopen
[261.571571] Subtest reopen: SUCCESS (0.004s)
[261.720873] [056/130] (759s left) kms_plane_scaling (planes-upscale-20x20)
[261.803599] Starting subtest: planes-upscale-20x20
[261.804525] Starting dynamic subtest: pipe-A
[261.865576] Dynamic subtest pipe-A: SUCCESS (0.063s)
[261.876034] Starting dynamic subtest: pipe-B
[262.969911] Dynamic subtest pipe-B: SUCCESS (1.102s)
[262.981788] Starting dynamic subtest: pipe-C
[264.075062] Dynamic subtest pipe-C: SUCCESS (1.103s)
[264.089840] Starting dynamic subtest: pipe-D
[265.205575] Dynamic subtest pipe-D: SUCCESS (1.128s)
[265.207875] Subtest planes-upscale-20x20: SUCCESS (3.404s)
[266.409839] [057/130] (754s left) kms_cursor_legacy (forked-bo)
[266.535421] Starting subtest: forked-bo
[266.536380] Starting dynamic subtest: pipe-A
[271.990819] Dynamic subtest pipe-A: SUCCESS (5.452s)
[272.001524] Starting dynamic subtest: pipe-B
[277.469230] Dynamic subtest pipe-B: SUCCESS (5.476s)
[277.478595] Starting dynamic subtest: pipe-C
[282.938816] Dynamic subtest pipe-C: SUCCESS (5.467s)
[282.947578] Starting dynamic subtest: pipe-D
[288.408639] Dynamic subtest pipe-D: SUCCESS (5.467s)
[288.417618] Starting dynamic subtest: all-pipes
[293.918702] Dynamic subtest all-pipes: SUCCESS (5.508s)
[293.919880] Subtest forked-bo: SUCCESS (27.381s)
[294.082342] [058/130] (726s left) kms_atomic_interruptible (universal-setplane-cursor)
[294.181027] Starting subtest: universal-setplane-cursor
[294.181836] Starting dynamic subtest: pipe-A-eDP-1
[301.561473] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.379s)
[301.562348] Subtest universal-setplane-cursor: SUCCESS (7.381s)
[302.424148] [059/130] (718s left) kms_properties (crtc-properties-atomic)
[302.508370] Starting subtest: crtc-properties-atomic
[302.509331] Starting dynamic subtest: pipe-A-eDP-1
[302.953254] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.444s)
[302.961470] Starting dynamic subtest: pipe-B-eDP-1
[304.079415] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.125s)
[304.087053] Starting dynamic subtest: pipe-C-eDP-1
[305.211305] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.130s)
[305.218921] Starting dynamic subtest: pipe-D-eDP-1
[306.340802] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.128s)
[306.341860] Subtest crtc-properties-atomic: SUCCESS (3.833s)
[307.210506] [060/130] (713s left) drm_read (short-buffer-block)
[307.355289] Starting subtest: short-buffer-block
[307.356458] Subtest short-buffer-block: SUCCESS (0.000s)
[307.511126] [061/130] (713s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[307.600901] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[312.366873] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.765s)
[313.595812] [062/130] (707s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[313.687450] Starting subtest: primary-y-tiled-reflect-x-180
[313.746198] Subtest primary-y-tiled-reflect-x-180: SKIP (0.059s)
[313.863963] [063/130] (707s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[313.981121] Starting subtest: short-flip-after-cursor-atomic-transitions
[314.937854] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.957s)
[315.807216] [064/130] (705s left) gem_ctx_isolation (preservation)
[315.865408] Starting subtest: preservation
[315.866386] Starting dynamic subtest: rcs0
[315.986958] Dynamic subtest rcs0: SUCCESS (0.121s)
[315.995882] Starting dynamic subtest: bcs0
[316.147358] Dynamic subtest bcs0: SUCCESS (0.159s)
[316.157550] Starting dynamic subtest: vcs0
[316.287875] Dynamic subtest vcs0: SUCCESS (0.139s)
[316.298571] Starting dynamic subtest: vcs1
[318.054993] Dynamic subtest vcs1: SUCCESS (1.767s)
[318.063412] Starting dynamic subtest: vecs0
[320.042968] Dynamic subtest vecs0: SUCCESS (1.987s)
[320.053066] Starting dynamic subtest: ccs0
[322.112936] Dynamic subtest ccs0: SUCCESS (2.069s)
[322.114765] Subtest preservation: SUCCESS (6.247s)
[322.338585] [065/130] (698s left) kms_invalid_mode (overflow-vrefresh)
[322.441573] Starting subtest: overflow-vrefresh
[322.442399] Starting dynamic subtest: pipe-A-eDP-1
[322.734669] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[322.742114] Starting dynamic subtest: pipe-B-eDP-1
[322.743061] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[322.750040] Starting dynamic subtest: pipe-C-eDP-1
[322.750979] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[322.758275] Starting dynamic subtest: pipe-D-eDP-1
[322.759181] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[322.760047] Subtest overflow-vrefresh: SUCCESS (0.297s)
[323.549497] [066/130] (697s left) kms_sequence (queue-busy)
[323.653631] Starting subtest: queue-busy
[323.654600] Starting dynamic subtest: pipe-A-eDP-1
[325.597931] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.942s)
[325.606813] Starting dynamic subtest: pipe-B-eDP-1
[328.218932] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.621s)
[328.227765] Starting dynamic subtest: pipe-C-eDP-1
[330.819519] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.598s)
[330.827984] Starting dynamic subtest: pipe-D-eDP-1
[333.360539] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.540s)
[333.361539] Subtest queue-busy: SUCCESS (9.707s)
[334.250433] [067/130] (687s left) kms_psr (psr-cursor-mmap-cpu)
[334.345502] Starting subtest: psr-cursor-mmap-cpu
[334.346396] Starting dynamic subtest: eDP-1
[336.037374] Dynamic subtest eDP-1: SUCCESS (1.690s)
[336.038240] Subtest psr-cursor-mmap-cpu: SUCCESS (1.691s)
[336.932739] [068/130] (684s left) gem_mmap_wc (fault-concurrent)
[336.993130] Subtest fault-concurrent: SKIP (0.000s)
[337.061155] [069/130] (684s left) kms_big_fb (linear-64bpp-rotate-0)
[337.153863] Starting subtest: linear-64bpp-rotate-0
[338.709825] Subtest linear-64bpp-rotate-0: SUCCESS (1.556s)
[338.939509] [070/130] (682s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[339.026311] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[342.991246] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (3.966s)
[344.134021] [071/130] (677s left) perf_pmu (pmu-read)
[344.169967] Starting subtest: pmu-read
[344.171716] Subtest pmu-read: SUCCESS (0.003s)
[344.304088] [072/130] (677s left) gen3_render_tiledx_blits
[344.436079] [073/130] (677s left) gem_mmap (pf-nonblock)
[344.489198] Subtest pf-nonblock: SKIP (0.000s)
[344.555847] [074/130] (677s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[344.637403] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt
[344.639094] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[344.759199] [075/130] (676s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[349.789119] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[349.790063] Starting dynamic subtest: pipe-A-eDP-1
[366.967745] Dynamic subtest pipe-A-eDP-1: SUCCESS (17.177s)
[366.976763] Starting dynamic subtest: pipe-B-eDP-1
[384.105419] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.134s)
[384.106311] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (34.316s)
[384.922855] [076/130] (636s left) api_intel_bb (destroy-bb)
[384.979214] Starting subtest: destroy-bb
[384.992218] Subtest destroy-bb: SUCCESS (0.014s)
[385.140707] [077/130] (636s left) kms_content_protection (atomic)
[385.284341] Starting subtest: atomic
[385.286126] Subtest atomic: SKIP (0.000s)
[385.454482] [078/130] (636s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[385.546479] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[385.547307] Starting dynamic subtest: pipe-A-eDP-1
[385.568322] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[385.575944] Starting dynamic subtest: pipe-B-eDP-1
[385.598912] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[385.610308] Starting dynamic subtest: pipe-C-eDP-1
[385.632835] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[385.640041] Starting dynamic subtest: pipe-D-eDP-1
[385.661541] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[385.663232] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[387.881430] [079/130] (633s left) kms_rotation_crc (bad-pixel-format)
[387.977510] Starting subtest: bad-pixel-format
[387.979489] Subtest bad-pixel-format: SKIP (0.001s)
[388.067465] [080/130] (633s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[393.057282] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[393.061598] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.003s)
[393.912709] [081/130] (627s left) gem_partial_pwrite_pread (writes-after-reads-display)
[393.972969] Subtest writes-after-reads-display: SKIP (0.000s)
[394.054907] [082/130] (627s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[394.153473] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[394.154344] Starting dynamic subtest: pipe-A-eDP-1
[394.299614] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.141s)
[394.309202] Starting dynamic subtest: pipe-B-eDP-1
[394.317078] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[394.324628] Starting dynamic subtest: pipe-C-eDP-1
[394.332385] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[394.339481] Starting dynamic subtest: pipe-D-eDP-1
[394.347021] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[394.347852] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.150s)
[396.561078] [083/130] (625s left) kms_chamelium_hpd (dp-hpd-storm)
[396.959963] Subtest dp-hpd-storm: SKIP (0.000s)
[397.751145] [084/130] (624s left) gem_ctx_bad_destroy (invalid-pad)
[397.808766] Starting subtest: invalid-pad
[397.809963] Subtest invalid-pad: SUCCESS (0.000s)
[397.897988] [085/130] (624s left) gem_exec_schedule (pi-common)
[398.076379] Starting subtest: pi-common
[398.077477] Starting dynamic subtest: rcs0
[398.111797] Dynamic subtest rcs0: SUCCESS (0.037s)
[398.119258] Starting dynamic subtest: bcs0
[398.144581] Dynamic subtest bcs0: SUCCESS (0.032s)
[398.152301] Starting dynamic subtest: vcs0
[398.179093] Dynamic subtest vcs0: SUCCESS (0.034s)
[398.186719] Starting dynamic subtest: vcs1
[398.209316] Dynamic subtest vcs1: SUCCESS (0.029s)
[398.216988] Starting dynamic subtest: vecs0
[398.237328] Dynamic subtest vecs0: SUCCESS (0.027s)
[398.245035] Starting dynamic subtest: ccs0
[398.265572] Dynamic subtest ccs0: SUCCESS (0.027s)
[398.266423] Subtest pi-common: SUCCESS (0.192s)
[398.522407] [086/130] (623s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[398.621192] Starting subtest: fbcpsr-rgb565-draw-blt
[405.555114] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (6.935s)
[405.711906] [087/130] (616s left) gem_lmem_swapping (verify-ccs)
[405.748648] Subtest verify-ccs: SKIP (0.000s)
[405.793664] [088/130] (616s left) kms_flip (modeset-vs-vblank-race-interruptible)
[405.882855] Starting subtest: modeset-vs-vblank-race-interruptible
[405.885237] Starting dynamic subtest: A-eDP1
[410.542390] Dynamic subtest A-eDP1: SUCCESS (4.657s)
[410.553486] Starting dynamic subtest: B-eDP1
[415.280062] Dynamic subtest B-eDP1: SUCCESS (4.735s)
[415.291875] Starting dynamic subtest: C-eDP1
[419.828154] Dynamic subtest C-eDP1: SUCCESS (4.546s)
[419.836105] Starting dynamic subtest: D-eDP1
[424.464224] Dynamic subtest D-eDP1: SUCCESS (4.634s)
[424.467265] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.582s)
[425.644681] [089/130] (596s left) kms_frontbuffer_tracking (fbc-tiling-4)
[425.748233] Starting subtest: fbc-tiling-4
[429.957222] Subtest fbc-tiling-4: SUCCESS (4.209s)
[431.143891] [090/130] (591s left) gem_ctx_persistence (legacy-engines-mixed-process)
[431.207804] Starting subtest: legacy-engines-mixed-process
[431.208755] Starting dynamic subtest: render
[432.926454] Dynamic subtest render: SUCCESS (1.719s)
[432.935782] Starting dynamic subtest: bsd1
[434.906488] Dynamic subtest bsd1: SUCCESS (1.977s)
[434.917523] Starting dynamic subtest: bsd2
[436.904236] Dynamic subtest bsd2: SUCCESS (1.995s)
[436.914227] Starting dynamic subtest: blt
[438.909784] Dynamic subtest blt: SUCCESS (2.003s)
[438.921109] Starting dynamic subtest: vebox
[440.900941] Dynamic subtest vebox: SUCCESS (1.988s)
[440.901897] Subtest legacy-engines-mixed-process: SUCCESS (9.695s)
[440.998611] [091/130] (581s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[441.109575] Starting subtest: disable-crc-after-crtc
[441.110502] Starting dynamic subtest: pipe-A-eDP-1
[442.755338] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.645s)
[442.764677] Starting dynamic subtest: pipe-B-eDP-1
[445.013828] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.256s)
[445.022690] Starting dynamic subtest: pipe-C-eDP-1
[447.255057] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.240s)
[447.263984] Starting dynamic subtest: pipe-D-eDP-1
[449.501342] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.244s)
[449.502333] Subtest disable-crc-after-crtc: SUCCESS (8.393s)
[450.359889] [092/130] (571s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[450.474526] Starting subtest: x-tiled-8bpp-rotate-270
[450.495526] Subtest x-tiled-8bpp-rotate-270: SKIP (0.020s)
[450.642656] [093/130] (571s left) gem_partial_pwrite_pread (reads-display)
[450.688068] Subtest reads-display: SKIP (0.000s)
[450.766852] [094/130] (571s left) gem_partial_pwrite_pread (write-display)
[450.831845] Subtest write-display: SKIP (0.000s)
[450.902383] [095/130] (571s left) gem_exec_reloc (basic-cpu-noreloc)
[450.967061] Subtest basic-cpu-noreloc: SKIP (0.000s)
[451.040347] [096/130] (571s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[451.123347] Starting subtest: blocking-absolute-wf_vblank-interruptible
[451.125503] Starting dynamic subtest: A-eDP1
[459.796888] Dynamic subtest A-eDP1: SUCCESS (8.672s)
[459.807468] Starting dynamic subtest: B-eDP1
[468.525138] Dynamic subtest B-eDP1: SUCCESS (8.726s)
[468.541715] Starting dynamic subtest: C-eDP1
[477.235558] Dynamic subtest C-eDP1: SUCCESS (8.707s)
[477.256082] Starting dynamic subtest: D-eDP1
[485.960573] Dynamic subtest D-eDP1: SUCCESS (8.722s)
[485.963440] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.839s)
[487.200519] [097/130] (535s left) sw_sync (sync_multi_producer_single_consumer)
[487.227138] Starting subtest: sync_multi_producer_single_consumer
[488.304133] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[488.380145] [098/130] (534s left) gen7_exec_parse (basic-allocation)
[488.438283] Subtest basic-allocation: SKIP (0.000s)
[488.526660] [099/130] (533s left) kms_atomic (crtc-invalid-params)
[488.617152] Starting subtest: crtc-invalid-params
[488.618044] Starting dynamic subtest: pipe-A-eDP-1
[488.978361] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.360s)
[488.979254] Subtest crtc-invalid-params: SUCCESS (0.362s)
[489.837433] [100/130] (532s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[489.910447] Starting subtest: map-fixed-invalidate-overlap
[489.912387] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[490.092530] [101/130] (532s left) kms_cursor_crc (cursor-sliding-256x256)
[490.333758] Starting subtest: cursor-sliding-256x256
[490.337287] Starting dynamic subtest: pipe-A-eDP-1
[493.532026] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.195s)
[493.559907] Starting dynamic subtest: pipe-D-eDP-1
[497.411426] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.858s)
[497.412596] Subtest cursor-sliding-256x256: SUCCESS (7.077s)
[498.307117] [102/130] (524s left) kms_dp_linktrain_fallback (dsc-fallback)
[498.416486] Starting subtest: dsc-fallback
[498.418915] Subtest dsc-fallback: SKIP (0.000s)
[498.506075] [103/130] (524s left) gem_softpin (softpin)
[498.555884] Starting subtest: softpin
[530.902206] Subtest softpin: SUCCESS (32.341s)
[531.033489] [104/130] (491s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[531.140451] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[537.618599] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.479s)
[537.752409] [105/130] (484s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[537.842097] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[537.842939] Starting dynamic subtest: pipe-A-default-mode
[538.957444] Dynamic subtest pipe-A-default-mode: SKIP (1.114s)
[538.959037] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.115s)
[539.142741] [106/130] (483s left) prime_busy (hang)
[539.202348] Starting subtest: hang
[539.203306] Starting dynamic subtest: rcs0
[565.427410] Dynamic subtest rcs0: SUCCESS (26.223s)
[565.435252] Starting dynamic subtest: bcs0
[572.584086] Dynamic subtest bcs0: SUCCESS (7.153s)
[572.593194] Starting dynamic subtest: vcs0
[579.562381] Dynamic subtest vcs0: SUCCESS (6.976s)
[579.571778] Starting dynamic subtest: vcs1
[586.615846] Dynamic subtest vcs1: SUCCESS (7.054s)
[586.623356] Starting dynamic subtest: vecs0
[593.591709] Dynamic subtest vecs0: SUCCESS (6.975s)
[593.599145] Starting dynamic subtest: ccs0
[607.412332] Dynamic subtest ccs0: SUCCESS (13.819s)
[607.413260] Subtest hang: SUCCESS (68.207s)
[607.568433] [107/130] (415s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[607.652062] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[607.652954] Starting dynamic subtest: pipe-A
[608.780267] Dynamic subtest pipe-A: SUCCESS (1.129s)
[608.787927] Starting dynamic subtest: pipe-B
[610.989126] Dynamic subtest pipe-B: SUCCESS (2.208s)
[611.000887] Starting dynamic subtest: pipe-C
[612.225814] Dynamic subtest pipe-C: SUCCESS (1.234s)
[612.247255] Starting dynamic subtest: pipe-D
[613.452633] Dynamic subtest pipe-D: SUCCESS (1.225s)
[613.455213] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.802s)
[614.670538] [108/130] (408s left) kms_addfb_basic (addfb25-bad-modifier)
[614.760524] Starting subtest: addfb25-bad-modifier
[614.761456] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[614.897096] [109/130] (407s left) gem_exec_balancer (parallel-ordering)
[615.166715] Starting subtest: parallel-ordering
[616.136364] Subtest parallel-ordering: SUCCESS (0.969s)
[616.280732] [110/130] (406s left) kms_flip (2x-plain-flip-fb-recreate)
[616.359220] Starting subtest: 2x-plain-flip-fb-recreate
[616.370476] Subtest 2x-plain-flip-fb-recreate: SKIP (0.011s)
[616.466537] [111/130] (406s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[616.525114] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[616.594379] [112/130] (406s left) gem_eio (in-flight-1us)
[616.773581] Starting subtest: in-flight-1us
[625.400904] Subtest in-flight-1us: SUCCESS (8.626s)
[625.597855] [113/130] (397s left) kms_flip_event_leak (basic)
[625.681345] Starting subtest: basic
[625.682223] Starting dynamic subtest: pipe-A-eDP-1
[626.108054] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.427s)
[626.116065] Starting dynamic subtest: pipe-B-eDP-1
[627.196994] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.086s)
[627.206591] Starting dynamic subtest: pipe-C-eDP-1
[628.314769] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s)
[628.322577] Starting dynamic subtest: pipe-D-eDP-1
[629.457990] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.143s)
[629.458902] Subtest basic: SUCCESS (3.778s)
[630.328991] [114/130] (392s left) kms_addfb_basic (clobberred-modifier)
[630.394506] Starting subtest: clobberred-modifier
[630.396297] Subtest clobberred-modifier: SKIP (0.000s)
[630.565046] [115/130] (392s left) dmabuf_sync_file (export-wait-after-attach)
[630.610200] Starting subtest: export-wait-after-attach
[630.611190] Subtest export-wait-after-attach: SUCCESS (0.001s)
[630.686481] [116/130] (392s left) kms_psr (pr-dpms)
[630.773238] Starting subtest: pr-dpms
[630.774860] Subtest pr-dpms: SKIP (0.000s)
[630.862576] [117/130] (392s left) kms_pwrite_crc
[632.467567] [118/130] (390s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[632.674930] Starting subtest: cursor-rapid-movement-128x128
[632.677086] Starting dynamic subtest: pipe-A-eDP-1
[633.219591] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.542s)
[633.245574] Starting dynamic subtest: pipe-D-eDP-1
[634.461345] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.223s)
[634.462231] Subtest cursor-rapid-movement-128x128: SUCCESS (1.788s)
[635.327990] [119/130] (387s left) kms_chamelium_hpd (common-hpd-after-suspend)
[635.708573] Subtest common-hpd-after-suspend: SKIP (0.000s)
[636.505084] [120/130] (386s left) i915_query (query-topology-garbage-items)
[636.553247] Starting subtest: query-topology-garbage-items
[636.554116] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[636.684566] [121/130] (386s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[636.773850] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[643.668532] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.893s)
[643.829427] [122/130] (379s left) kms_scaling_modes (scaling-mode-center)
[643.927386] Starting subtest: scaling-mode-center
[643.928349] Starting dynamic subtest: pipe-A-eDP-1
[644.044770] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.117s)
[644.054782] Starting dynamic subtest: pipe-B-eDP-1
[645.194176] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.148s)
[645.203483] Starting dynamic subtest: pipe-C-eDP-1
[646.313901] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s)
[646.328159] Starting dynamic subtest: pipe-D-eDP-1
[647.452132] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.135s)
[647.452944] Subtest scaling-mode-center: SUCCESS (3.526s)
[648.629091] [123/130] (374s left) kms_pm_rpm (pm-caching)
[650.167397] Starting subtest: pm-caching
[650.169841] Subtest pm-caching: SKIP (0.000s)
[650.891217] [124/130] (372s left) kms_psr (fbc-pr-primary-mmap-gtt)
[650.991377] Starting subtest: fbc-pr-primary-mmap-gtt
[650.993102] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[651.079575] [125/130] (372s left) kms_psr (fbc-psr-basic)
[651.168666] Starting subtest: fbc-psr-basic
[651.169580] Starting dynamic subtest: eDP-1
[651.171302] Dynamic subtest eDP-1: SKIP (0.000s)
[651.172891] Subtest fbc-psr-basic: SKIP (0.001s)
[651.260027] [126/130] (372s left) gem_exec_fence (syncobj-timeline-signal)
[651.783594] Starting subtest: syncobj-timeline-signal
[651.789845] Subtest syncobj-timeline-signal: SUCCESS (0.009s)
[653.030084] [127/130] (370s left) gem_eio (wait-wedge-immediate)
[653.252627] Starting subtest: wait-wedge-immediate
[655.012967] Subtest wait-wedge-immediate: SUCCESS (1.760s)
[655.191402] [128/130] (368s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[655.284296] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[655.285076] Starting dynamic subtest: pipe-A-eDP-1
[655.292999] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[655.300979] Starting dynamic subtest: pipe-B-eDP-1
[655.309030] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[655.317228] Starting dynamic subtest: pipe-C-eDP-1
[655.325151] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[655.332746] Starting dynamic subtest: pipe-D-eDP-1
[655.340821] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[655.342420] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[657.540594] [129/130] (365s left) gen9_exec_parse (batch-zero-length)
[657.598431] Subtest batch-zero-length: SKIP (0.000s)
[657.666838] [130/130] (365s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[657.767026] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[657.768699] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[657.895210] Closing watchdogs
results: parsing output: 0/ for test: kms_async_flips
results: parsing output: 1/ for test: i915_pm_rpm
results: parsing output: 2/ for test: gem_ctx_param
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_lease
results: parsing output: 5/ for test: gem_mmap_offset
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: gem_ctx_shared
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: gem_exec_schedule
results: parsing output: 10/ for test: kms_pm_rpm
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: syncobj_timeline
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: gem_ctx_bad_destroy
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: fbdev
results: parsing output: 18/ for test: syncobj_basic
results: parsing output: 19/ for test: gem_exec_fence
results: parsing output: 20/ for test: gem_exec_fence
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: gem_render_copy
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: perf
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_cs_tlb
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: gem_spin_batch
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_pxp
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 35/ for test: kms_cursor_crc
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: i915_pm_freq_api
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: gem_exec_balancer
results: parsing output: 41/ for test: gen9_exec_parse
results: parsing output: 42/ for test: gem_lmem_swapping
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_busy
results: parsing output: 45/ for test: kms_vblank
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_tiled_pread_basic
results: parsing output: 48/ for test: drm_read
results: parsing output: 49/ for test: perf
results: parsing output: 50/ for test: kms_plane_lowres
results: parsing output: 51/ for test: kms_rotation_crc
results: parsing output: 52/ for test: syncobj_wait
results: parsing output: 53/ for test: kms_cursor_legacy
results: parsing output: 54/ for test: api_intel_allocator
results: parsing output: 55/ for test: kms_plane_scaling
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: kms_atomic_interruptible
results: parsing output: 58/ for test: kms_properties
results: parsing output: 59/ for test: drm_read
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_rotation_crc
results: parsing output: 62/ for test: kms_cursor_legacy
results: parsing output: 63/ for test: gem_ctx_isolation
results: parsing output: 64/ for test: kms_invalid_mode
results: parsing output: 65/ for test: kms_sequence
results: parsing output: 66/ for test: kms_psr
results: parsing output: 67/ for test: gem_mmap_wc
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf_pmu
results: parsing output: 71/ for test: gen3_render_tiledx_blits
results: parsing output: 72/ for test: gem_mmap
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_psr2_sf
results: parsing output: 75/ for test: api_intel_bb
results: parsing output: 76/ for test: kms_content_protection
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_rotation_crc
results: parsing output: 79/ for test: kms_psr2_sf
results: parsing output: 80/ for test: gem_partial_pwrite_pread
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_chamelium_hpd
results: parsing output: 83/ for test: gem_ctx_bad_destroy
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_lmem_swapping
results: parsing output: 87/ for test: kms_flip
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_ctx_persistence
results: parsing output: 90/ for test: kms_pipe_crc_basic
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: gem_partial_pwrite_pread
results: parsing output: 93/ for test: gem_partial_pwrite_pread
results: parsing output: 94/ for test: gem_exec_reloc
results: parsing output: 95/ for test: kms_flip
results: parsing output: 96/ for test: sw_sync
results: parsing output: 97/ for test: gen7_exec_parse
results: parsing output: 98/ for test: kms_atomic
results: parsing output: 99/ for test: gem_userptr_blits
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: kms_dp_linktrain_fallback
results: parsing output: 102/ for test: gem_softpin
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_flip_scaled_crc
results: parsing output: 105/ for test: prime_busy
results: parsing output: 106/ for test: kms_plane_scaling
results: parsing output: 107/ for test: kms_addfb_basic
results: parsing output: 108/ for test: gem_exec_balancer
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: gem_exec_reloc
results: parsing output: 111/ for test: gem_eio
results: parsing output: 112/ for test: kms_flip_event_leak
results: parsing output: 113/ for test: kms_addfb_basic
results: parsing output: 114/ for test: dmabuf_sync_file
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: kms_pwrite_crc
results: parsing output: 117/ for test: kms_cursor_crc
results: parsing output: 118/ for test: kms_chamelium_hpd
results: parsing output: 119/ for test: i915_query
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_scaling_modes
results: parsing output: 122/ for test: kms_pm_rpm
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: gem_exec_fence
results: parsing output: 126/ for test: gem_eio
results: parsing output: 127/ for test: kms_ccs
results: parsing output: 128/ for test: gen9_exec_parse
results: parsing output: 129/ for test: kms_frontbuffer_tracking
Done.