[55.697242] Initializing watchdogs
[55.697287]   /dev/watchdog0
[55.711301] [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]
[55.715605] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.718022] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.721213] [001/154] (960s left) gem_exec_async (concurrent-writes)
[55.795953] Starting subtest: concurrent-writes
[55.798226] Starting dynamic subtest: rcs0
[55.819375] Dynamic subtest rcs0: SUCCESS (0.023s)
[55.828712] Starting dynamic subtest: bcs0
[55.830886] Dynamic subtest bcs0: SUCCESS (0.009s)
[55.840836] Starting dynamic subtest: vcs0
[55.842851] Dynamic subtest vcs0: SUCCESS (0.010s)
[55.850051] Starting dynamic subtest: vcs1
[55.852802] Dynamic subtest vcs1: SUCCESS (0.011s)
[55.859808] Starting dynamic subtest: vecs0
[55.862061] Dynamic subtest vecs0: SUCCESS (0.011s)
[55.869090] Starting dynamic subtest: ccs0
[55.871358] Dynamic subtest ccs0: SUCCESS (0.008s)
[55.873114] Subtest concurrent-writes: SUCCESS (0.075s)
[56.112937] [002/154] (960s left) gem_mmap_gtt (cpuset-big-copy-odd)
[56.174059] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[56.231842] [003/154] (960s left) perf_pmu (busy-idle-no-semaphores)
[56.273522] Starting subtest: busy-idle-no-semaphores
[56.275811] Starting dynamic subtest: rcs0
[56.912221] Dynamic subtest rcs0: SUCCESS (0.646s)
[56.922751] Starting dynamic subtest: bcs0
[57.565690] Dynamic subtest bcs0: SUCCESS (0.660s)
[57.575910] Starting dynamic subtest: vcs0
[58.216958] Dynamic subtest vcs0: SUCCESS (0.657s)
[58.227678] Starting dynamic subtest: vcs1
[59.878918] Dynamic subtest vcs1: SUCCESS (1.675s)
[59.889734] Starting dynamic subtest: vecs0
[61.927844] Dynamic subtest vecs0: SUCCESS (2.062s)
[61.938350] Starting dynamic subtest: ccs0
[63.909108] Dynamic subtest ccs0: SUCCESS (1.990s)
[63.911945] Subtest busy-idle-no-semaphores: SUCCESS (7.695s)
[64.023607] [004/154] (952s left) kms_psr (psr-cursor-mmap-cpu)
[64.128411] Starting subtest: psr-cursor-mmap-cpu
[64.132804] Starting dynamic subtest: eDP-1
[65.866466] Dynamic subtest eDP-1: SUCCESS (1.744s)
[65.868648] Subtest psr-cursor-mmap-cpu: SUCCESS (1.745s)
[66.719917] [005/154] (949s left) core_auth (basic-auth)
[66.788069] Starting subtest: basic-auth
[66.790482] Subtest basic-auth: SUCCESS (0.001s)
[66.877125] [006/154] (949s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[66.968071] Starting subtest: addfb25-y-tiled-legacy
[66.970247] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[67.075148] [007/154] (949s left) syncobj_timeline (host-signal-ordered)
[67.131018] Starting subtest: host-signal-ordered
[67.133067] Subtest host-signal-ordered: SUCCESS (0.001s)
[67.219736] [008/154] (949s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt)
[67.328265] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt
[67.332330] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[67.426189] [009/154] (949s left) kms_mmap_write_crc (main)
[67.525123] Starting subtest: main
[67.527440] Starting dynamic subtest: pipe-A-eDP-1
[70.970885] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.452s)
[70.973227] Subtest main: SUCCESS (3.453s)
[71.843608] [010/154] (944s left) sriov_basic (enable-vfs-bind-unbind-each)
[71.904406] Starting subtest: enable-vfs-bind-unbind-each
[71.906516] Starting dynamic subtest: numvfs-1
[71.983620] Dynamic subtest numvfs-1: FAIL (0.014s)
[71.994077] Starting dynamic subtest: numvfs-2
[72.068631] Dynamic subtest numvfs-2: FAIL (0.004s)
[72.078150] Starting dynamic subtest: numvfs-3
[72.151996] Dynamic subtest numvfs-3: FAIL (0.003s)
[72.161687] Starting dynamic subtest: numvfs-4
[72.235883] Dynamic subtest numvfs-4: FAIL (0.003s)
[72.245744] Starting dynamic subtest: numvfs-5
[72.320408] Dynamic subtest numvfs-5: FAIL (0.003s)
[72.329760] Starting dynamic subtest: numvfs-6
[72.404655] Dynamic subtest numvfs-6: FAIL (0.004s)
[72.414838] Starting dynamic subtest: numvfs-7
[72.492769] Dynamic subtest numvfs-7: FAIL (0.003s)
[72.502868] Starting dynamic subtest: numvfs-random
[72.584779] Dynamic subtest numvfs-random: FAIL (0.003s)
[72.586844] Subtest enable-vfs-bind-unbind-each: FAIL (0.040s)
[72.645285] [011/154] (943s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-render)
[72.819667] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-render
[72.823176] Subtest fbchdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[73.076036] [012/154] (943s left) syncobj_timeline (wait-all-for-submit-snapshot)
[73.167870] Starting subtest: wait-all-for-submit-snapshot
[73.250036] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[73.331414] [013/154] (943s left) kms_plane_alpha_blend (alpha-transparent-fb)
[73.449146] Starting subtest: alpha-transparent-fb
[73.740058] Starting dynamic subtest: pipe-A-eDP-1
[74.342395] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.600s)
[74.351770] Starting dynamic subtest: pipe-D-eDP-1
[75.704227] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.364s)
[75.706655] Subtest alpha-transparent-fb: SUCCESS (2.259s)
[76.520021] [014/154] (940s left) gem_userptr_blits (process-exit)
[76.586926] Starting subtest: process-exit
[76.594186] Subtest process-exit: SUCCESS (0.007s)
[76.716627] [015/154] (940s left) kms_flip (plain-flip-ts-check)
[76.813307] Starting subtest: plain-flip-ts-check
[76.815751] Starting dynamic subtest: A-eDP1
[85.718864] Dynamic subtest A-eDP1: SUCCESS (8.908s)
[85.726944] Starting dynamic subtest: B-eDP1
[94.540431] Dynamic subtest B-eDP1: SUCCESS (8.821s)
[94.550825] Starting dynamic subtest: C-eDP1
[103.363765] Dynamic subtest C-eDP1: SUCCESS (8.821s)
[103.377238] Starting dynamic subtest: D-eDP1
[112.192609] Dynamic subtest D-eDP1: SUCCESS (8.828s)
[112.197285] Subtest plain-flip-ts-check: SUCCESS (35.387s)
[113.388896] [016/154] (903s left) kms_psr (psr-primary-page-flip)
[113.479230] Starting subtest: psr-primary-page-flip
[113.481813] Starting dynamic subtest: eDP-1
[115.180057] Dynamic subtest eDP-1: SUCCESS (1.701s)
[115.182384] Subtest psr-primary-page-flip: SUCCESS (1.702s)
[116.031804] [017/154] (900s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt)
[116.128335] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt
[116.132582] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[116.228937] [018/154] (900s left) core_setmaster_vs_auth
[116.382466] [019/154] (900s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[116.485749] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[116.490271] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[116.576943] [020/154] (900s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[116.666752] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[116.669074] Starting dynamic subtest: pipe-A-eDP-1
[116.691664] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[116.702110] Starting dynamic subtest: pipe-B-eDP-1
[116.723298] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[116.731115] Starting dynamic subtest: pipe-C-eDP-1
[116.752277] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[116.759974] Starting dynamic subtest: pipe-D-eDP-1
[116.781559] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[116.785972] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[118.847379] [021/154] (898s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[118.917070] Starting subtest: etime-multi-wait-all-available-unsubmitted
[119.025177] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.107s)
[119.103717] [022/154] (897s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[119.211414] Starting subtest: yf-tiled-16bpp-rotate-90
[119.215748] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[119.322251] [023/154] (897s left) syncobj_timeline (multi-wait-all-available-submitted)
[119.393442] Starting subtest: multi-wait-all-available-submitted
[119.395789] Subtest multi-wait-all-available-submitted: SUCCESS (0.001s)
[119.509113] [024/154] (897s left) gem_ctx_exec (basic-invalid-context)
[119.567398] Starting subtest: basic-invalid-context
[119.569738] Subtest basic-invalid-context: SUCCESS (0.004s)
[119.700225] [025/154] (897s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[119.800405] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[119.804472] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[119.887861] [026/154] (897s left) api_intel_bb (render)
[119.949603] Starting subtest: render
[119.951487] Starting dynamic subtest: render-none-512
[120.046323] Dynamic subtest render-none-512: SUCCESS (0.098s)
[120.056845] Starting dynamic subtest: render-none-1024
[120.443062] Dynamic subtest render-none-1024: SUCCESS (0.395s)
[120.453087] Starting dynamic subtest: render-x-512
[120.526897] Dynamic subtest render-x-512: SUCCESS (0.084s)
[120.536764] Starting dynamic subtest: render-x-1024
[120.862493] Dynamic subtest render-x-1024: SUCCESS (0.335s)
[120.870503] Starting dynamic subtest: render-y-512
[120.947687] Dynamic subtest render-y-512: SUCCESS (0.085s)
[120.955772] Starting dynamic subtest: render-y-1024
[121.294082] Dynamic subtest render-y-1024: SUCCESS (0.344s)
[121.297065] Subtest render: SUCCESS (1.345s)
[121.406495] [027/154] (895s left) kms_pm_rpm (cursor-dpms)
[123.452989] Starting subtest: cursor-dpms
[123.457719] Subtest cursor-dpms: SKIP (0.000s)
[123.834866] [028/154] (893s left) kms_flip (2x-flip-vs-expired-vblank)
[123.926256] Starting subtest: 2x-flip-vs-expired-vblank
[123.935073] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[124.012832] [029/154] (893s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[124.120802] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[124.125220] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[124.203480] [030/154] (893s left) gem_exec_nop (basic-sequential)
[124.281522] Starting subtest: basic-sequential
[140.393584] Subtest basic-sequential: SUCCESS (16.122s)
[140.623188] [031/154] (876s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-linear)
[140.721955] Starting subtest: fbcpsrhdr-tiling-linear
[140.726368] Subtest fbcpsrhdr-tiling-linear: SKIP (0.000s)
[140.809935] [032/154] (876s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[140.896103] Starting subtest: fbc-psr-sprite-mmap-gtt
[140.898491] Starting dynamic subtest: eDP-1
[140.903042] Dynamic subtest eDP-1: SKIP (0.000s)
[140.907609] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[140.986437] [033/154] (876s left) vgem_basic (debugfs)
[141.065855] Starting subtest: debugfs
[141.068196] Subtest debugfs: SUCCESS (0.000s)
[141.120398] [FACT vgem_basic (debugfs)] new: kernel.kmod_is_loaded.vgem: true
[141.124050] [034/154] (876s left) gem_mmap_offset (pf-nonblock)
[141.170035] Starting subtest: pf-nonblock
[141.174617] Subtest pf-nonblock: SUCCESS (0.007s)
[141.288816] [035/154] (876s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[141.405382] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[141.409845] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[141.502443] [036/154] (876s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[141.601651] Starting subtest: y-tiled-8bpp-rotate-90
[141.605431] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[141.705710] [037/154] (875s left) gem_exec_fence (syncobj-timeline-export)
[142.236081] Starting subtest: syncobj-timeline-export
[142.241312] Subtest syncobj-timeline-export: SUCCESS (0.007s)
[143.368783] [038/154] (874s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-blt)
[143.465419] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-blt
[143.469447] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[143.559879] [039/154] (874s left) gem_busy (busy)
[143.624856] Starting subtest: busy
[143.627142] Starting dynamic subtest: all-engines
[143.692266] Dynamic subtest all-engines: SUCCESS (0.070s)
[143.704765] Starting dynamic subtest: rcs0
[145.284657] Dynamic subtest rcs0: SUCCESS (1.590s)
[145.295514] Starting dynamic subtest: bcs0
[145.338674] Dynamic subtest bcs0: SUCCESS (0.052s)
[145.348740] Starting dynamic subtest: vcs0
[145.376766] Dynamic subtest vcs0: SUCCESS (0.039s)
[145.386023] Starting dynamic subtest: vcs1
[147.268070] Dynamic subtest vcs1: SUCCESS (1.887s)
[147.278240] Starting dynamic subtest: vecs0
[149.316068] Dynamic subtest vecs0: SUCCESS (2.047s)
[149.326260] Starting dynamic subtest: ccs0
[151.299120] Dynamic subtest ccs0: SUCCESS (1.983s)
[151.301657] Subtest busy: SUCCESS (7.674s)
[151.658329] [040/154] (866s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[151.750863] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[151.753128] Starting dynamic subtest: pipe-A-eDP-1
[151.776044] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[151.788248] Starting dynamic subtest: pipe-B-eDP-1
[151.809575] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[151.818197] Starting dynamic subtest: pipe-C-eDP-1
[151.840067] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[151.848443] Starting dynamic subtest: pipe-D-eDP-1
[151.869677] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[151.873537] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[153.990118] [041/154] (863s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[155.519057] Starting subtest: gem-execbuf-stress-pc8
[155.560761] Subtest gem-execbuf-stress-pc8: SKIP (0.041s)
[156.224869] [042/154] (861s left) gem_unref_active_buffers
[156.440136] [043/154] (861s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[156.534295] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[156.538662] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[156.613955] [044/154] (861s left) gem_spin_batch (resubmit)
[156.675765] Starting subtest: resubmit
[156.678134] Starting dynamic subtest: rcs0
[156.682824] Dynamic subtest rcs0: SUCCESS (0.007s)
[156.693618] Starting dynamic subtest: bcs0
[156.695977] Dynamic subtest bcs0: SUCCESS (0.002s)
[156.704151] Starting dynamic subtest: vcs0
[156.705939] Dynamic subtest vcs0: SUCCESS (0.003s)
[156.713647] Starting dynamic subtest: vcs1
[156.715533] Dynamic subtest vcs1: SUCCESS (0.002s)
[156.723265] Starting dynamic subtest: vecs0
[156.725142] Dynamic subtest vecs0: SUCCESS (0.003s)
[156.732894] Starting dynamic subtest: ccs0
[156.734839] Dynamic subtest ccs0: SUCCESS (0.003s)
[156.736791] Subtest resubmit: SUCCESS (0.022s)
[156.931950] [045/154] (861s left) gem_mmap_wc (close)
[156.999458] Subtest close: SKIP (0.000s)
[157.075689] [046/154] (860s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-render)
[157.171479] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-render
[157.174997] Subtest hdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[157.257242] [047/154] (860s left) kms_tv_load_detect (load-detect)
[157.321048] Subtest load-detect: SKIP (0.000s)
[157.390272] [048/154] (860s left) syncobj_wait (multi-wait-all-signaled)
[157.443047] Starting subtest: multi-wait-all-signaled
[157.445051] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[157.543333] [049/154] (860s left) kms_cursor_crc (cursor-sliding-512x512)
[157.818130] Starting subtest: cursor-sliding-512x512
[157.821786] Subtest cursor-sliding-512x512: SKIP (0.001s)
[157.977756] [050/154] (860s left) i915_hangman (engine-error-state-capture)
[158.036056] Starting subtest: engine-error-state-capture
[158.038120] Starting dynamic subtest: rcs0
[161.244930] Dynamic subtest rcs0: SUCCESS (3.208s)
[161.256581] Starting dynamic subtest: bcs0
[167.260736] Dynamic subtest bcs0: SUCCESS (6.014s)
[167.272402] Starting dynamic subtest: vcs0
[173.213657] Dynamic subtest vcs0: SUCCESS (5.951s)
[173.224692] Starting dynamic subtest: vcs1
[178.203085] Dynamic subtest vcs1: SUCCESS (4.988s)
[178.215197] Starting dynamic subtest: vecs0
[184.284075] Dynamic subtest vecs0: SUCCESS (6.079s)
[184.296414] Starting dynamic subtest: ccs0
[190.236492] Dynamic subtest ccs0: SUCCESS (5.950s)
[190.239809] Subtest engine-error-state-capture: SUCCESS (32.199s)
[190.322781] [051/154] (827s left) kms_psr (fbc-psr2-primary-render)
[190.426058] Starting subtest: fbc-psr2-primary-render
[190.428410] Starting dynamic subtest: eDP-1
[190.432849] Dynamic subtest eDP-1: SKIP (0.000s)
[190.437290] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[190.501509] [052/154] (827s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[190.557266] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[190.561763] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[190.741916] [053/154] (827s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[190.834662] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[190.839043] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[190.938870] [054/154] (827s left) gem_bad_reloc (negative-reloc-bltcopy)
[190.990572] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[191.071496] [055/154] (827s left) kms_cursor_crc (cursor-onscreen-256x256)
[191.300659] Starting subtest: cursor-onscreen-256x256
[191.305440] Starting dynamic subtest: pipe-A-eDP-1
[193.413426] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.107s)
[193.438853] Starting dynamic subtest: pipe-D-eDP-1
[196.182103] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.742s)
[196.184066] Subtest cursor-onscreen-256x256: SUCCESS (4.873s)
[196.979424] [056/154] (821s left) syncobj_wait (invalid-signal-one-illegal-handle)
[197.044512] Starting subtest: invalid-signal-one-illegal-handle
[197.046547] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[197.139879] [057/154] (821s left) kms_dsc (dsc-with-formats)
[197.248608] Starting subtest: dsc-with-formats
[197.291131] Subtest dsc-with-formats: SKIP (0.001s)
[197.330712] [058/154] (821s left) gem_exec_capture (capture-invisible)
[197.408205] Starting subtest: capture-invisible
[197.410621] Starting dynamic subtest: smem0
[197.415196] Dynamic subtest smem0: SKIP (0.000s)
[197.419773] Subtest capture-invisible: SKIP (0.000s)
[197.518326] [059/154] (821s left) kms_addfb_basic (basic)
[197.578342] Starting subtest: basic
[197.580937] Subtest basic: SUCCESS (0.000s)
[197.750127] [060/154] (820s left) kms_vblank (ts-continuation-dpms-rpm)
[197.842947] Starting subtest: ts-continuation-dpms-rpm
[197.845268] Starting dynamic subtest: pipe-A-eDP-1
[200.384757] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.541s)
[200.399760] Starting dynamic subtest: pipe-D-eDP-1
[202.638769] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.252s)
[202.641188] Subtest ts-continuation-dpms-rpm: SUCCESS (4.796s)
[203.518863] [061/154] (815s left) gem_exec_schedule (smoketest)
[203.697503] Starting subtest: smoketest
[203.699655] Starting dynamic subtest: rcs0
[209.237591] Dynamic subtest rcs0: SUCCESS (5.494s)
[209.249141] Starting dynamic subtest: bcs0
[214.755693] Dynamic subtest bcs0: SUCCESS (5.512s)
[214.768011] Starting dynamic subtest: vcs0
[220.238452] Dynamic subtest vcs0: SUCCESS (5.482s)
[220.249043] Starting dynamic subtest: vcs1
[225.731918] Dynamic subtest vcs1: SUCCESS (5.499s)
[225.741992] Starting dynamic subtest: vecs0
[231.248795] Dynamic subtest vecs0: SUCCESS (5.513s)
[231.259477] Starting dynamic subtest: ccs0
[236.756600] Dynamic subtest ccs0: SUCCESS (5.506s)
[236.758677] Subtest smoketest: SUCCESS (33.011s)
[236.894280] [062/154] (781s left) gem_exec_schedule (implicit-read-write)
[236.984748] Starting subtest: implicit-read-write
[236.986681] Starting dynamic subtest: rcs0
[237.155608] Dynamic subtest rcs0: SUCCESS (0.178s)
[237.167395] Starting dynamic subtest: vcs0
[237.262600] Dynamic subtest vcs0: SUCCESS (0.106s)
[237.274163] Starting dynamic subtest: vcs1
[237.376548] Dynamic subtest vcs1: SUCCESS (0.114s)
[237.388129] Starting dynamic subtest: vecs0
[237.487238] Dynamic subtest vecs0: SUCCESS (0.110s)
[237.498632] Starting dynamic subtest: ccs0
[237.607987] Dynamic subtest ccs0: SUCCESS (0.121s)
[237.610696] Subtest implicit-read-write: SUCCESS (0.630s)
[238.367940] [063/154] (780s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[238.469969] Starting subtest: sprite-rotation-90-pos-100-0
[238.474526] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[238.543554] [064/154] (780s left) perf (enable-disable)
[238.657371] Starting subtest: enable-disable
[238.659958] Starting dynamic subtest: 0-rcs0
[240.974189] Dynamic subtest 0-rcs0: SUCCESS (2.317s)
[240.984260] Starting dynamic subtest: 1-vcs0
[245.441079] Dynamic subtest 1-vcs0: SUCCESS (4.466s)
[245.443417] Subtest enable-disable: SUCCESS (6.784s)
[245.488493] [065/154] (773s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[245.615989] Starting subtest: cursorB-vs-flipA-toggle
[245.620535] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[245.762802] [066/154] (773s left) gem_exec_flush (basic-wb-rw-default)
[245.818225] Starting subtest: basic-wb-rw-default
[251.270088] Subtest basic-wb-rw-default: SUCCESS (5.452s)
[251.456258] [067/154] (767s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[251.564742] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc
[251.569146] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[251.667617] [068/154] (767s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-render)
[251.773216] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-render
[251.777273] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[251.866155] [069/154] (767s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[251.963375] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[251.965715] Starting dynamic subtest: pipe-A-eDP-1
[251.974785] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[251.988920] Starting dynamic subtest: pipe-B-eDP-1
[251.997778] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[252.006263] Starting dynamic subtest: pipe-C-eDP-1
[252.014611] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[252.022865] Starting dynamic subtest: pipe-D-eDP-1
[252.031094] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[252.034991] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[254.267597] [070/154] (764s left) gem_render_copy (linear-to-vebox-yf-tiled)
[254.336630] Starting subtest: linear-to-vebox-yf-tiled
[254.341225] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[254.535292] [071/154] (764s left) kms_chamelium_color (gamma)
[254.648608] Subtest gamma: SKIP (0.000s)
[254.692103] [072/154] (764s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-gtt)
[254.799882] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-gtt
[254.804326] Subtest fbcpsrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[254.904416] [073/154] (764s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[255.000836] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc
[255.005228] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[255.106305] [074/154] (764s left) kms_plane (plane-position-hole-dpms)
[255.202423] Starting subtest: plane-position-hole-dpms
[255.694444] Starting dynamic subtest: pipe-A-plane-1
[258.049388] Dynamic subtest pipe-A-plane-1: SUCCESS (2.355s)
[258.059583] Starting dynamic subtest: pipe-A-plane-2
[260.337150] Dynamic subtest pipe-A-plane-2: SUCCESS (2.288s)
[260.347173] Starting dynamic subtest: pipe-A-plane-3
[262.697499] Dynamic subtest pipe-A-plane-3: SUCCESS (2.359s)
[262.707800] Starting dynamic subtest: pipe-A-plane-4
[265.061274] Dynamic subtest pipe-A-plane-4: SUCCESS (2.361s)
[265.078054] Starting dynamic subtest: pipe-A-plane-5
[265.085102] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[266.253585] Starting dynamic subtest: pipe-B-plane-1
[268.694550] Dynamic subtest pipe-B-plane-1: SUCCESS (2.449s)
[268.707135] Starting dynamic subtest: pipe-B-plane-2
[271.094923] Dynamic subtest pipe-B-plane-2: SUCCESS (2.399s)
[271.104879] Starting dynamic subtest: pipe-B-plane-3
[273.503285] Dynamic subtest pipe-B-plane-3: SUCCESS (2.408s)
[273.513679] Starting dynamic subtest: pipe-B-plane-4
[275.810436] Dynamic subtest pipe-B-plane-4: SUCCESS (2.304s)
[275.820501] Starting dynamic subtest: pipe-B-plane-5
[275.833754] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[275.836114] Subtest plane-position-hole-dpms: SUCCESS (20.629s)
[276.679243] [075/154] (742s left) kms_chamelium_audio (dp-audio)
[277.081850] Subtest dp-audio: SKIP (0.000s)
[277.882734] [076/154] (741s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[277.942180] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[278.010411] [077/154] (741s left) gem_mmap_gtt (fault-concurrent)
[278.060506] Subtest fault-concurrent: SKIP (0.000s)
[278.119284] [078/154] (741s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-render)
[278.212066] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-render
[278.216313] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[278.307351] [079/154] (741s left) gem_exec_schedule (fairslice)
[278.388071] Starting subtest: fairslice
[278.390133] Starting dynamic subtest: rcs0
[280.399406] Dynamic subtest rcs0: SUCCESS (2.019s)
[280.410575] Starting dynamic subtest: bcs0
[282.417805] Dynamic subtest bcs0: SUCCESS (2.017s)
[282.427374] Starting dynamic subtest: vcs0
[284.444755] Dynamic subtest vcs0: SUCCESS (2.023s)
[284.454350] Starting dynamic subtest: vcs1
[286.462150] Dynamic subtest vcs1: SUCCESS (2.017s)
[286.472062] Starting dynamic subtest: vecs0
[288.481016] Dynamic subtest vecs0: SUCCESS (2.017s)
[288.490599] Starting dynamic subtest: ccs0
[290.497239] Dynamic subtest ccs0: SUCCESS (2.017s)
[290.499189] Subtest fairslice: SUCCESS (12.116s)
[290.811717] [080/154] (728s left) gem_workarounds (reset)
[290.882755] Starting subtest: reset
[291.056695] Subtest reset: SUCCESS (0.174s)
[291.189943] [081/154] (728s left) i915_drm_fdinfo (virtual-busy-all)
[291.230886] Starting subtest: virtual-busy-all
[291.235333] Subtest virtual-busy-all: SKIP (0.000s)
[291.300758] [082/154] (728s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[291.397690] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[291.401803] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[291.493281] [083/154] (728s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[291.593404] Starting subtest: psr2-overlay-plane-update-continuous-sf
[291.595611] Starting dynamic subtest: pipe-A-eDP-1
[292.705083] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.116s)
[292.715305] Starting dynamic subtest: pipe-B-eDP-1
[294.461463] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.753s)
[294.463749] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.873s)
[295.263819] [084/154] (724s left) kms_busy (basic-hang)
[295.363034] Starting subtest: basic-hang
[295.365412] Starting dynamic subtest: flip-pipe-A
[295.953016] Dynamic subtest flip-pipe-A: SUCCESS (0.589s)
[295.963477] Starting dynamic subtest: modeset-pipe-A
[299.407796] Dynamic subtest modeset-pipe-A: SUCCESS (3.454s)
[299.417481] Starting dynamic subtest: flip-pipe-D
[300.613987] Dynamic subtest flip-pipe-D: SUCCESS (1.205s)
[300.626446] Starting dynamic subtest: modeset-pipe-D
[304.045847] Dynamic subtest modeset-pipe-D: SUCCESS (3.430s)
[304.048254] Subtest basic-hang: SUCCESS (8.684s)
[304.859836] [085/154] (714s left) gem_lmem_swapping (heavy-verify-random-ccs)
[304.901271] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[304.941785] [086/154] (714s left) kms_big_fb (linear-64bpp-rotate-90)
[305.033791] Starting subtest: linear-64bpp-rotate-90
[305.058104] Subtest linear-64bpp-rotate-90: SKIP (0.024s)
[305.173919] [087/154] (714s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[305.276773] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-wc
[305.280974] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[305.368845] [088/154] (714s left) gem_softpin (noreloc)
[305.430818] Starting subtest: noreloc
[310.856744] Subtest noreloc: SUCCESS (5.424s)
[310.988594] [089/154] (708s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[311.105904] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[311.108180] Starting dynamic subtest: pipe-A-default-mode
[313.835152] Dynamic subtest pipe-A-default-mode: SUCCESS (2.728s)
[313.837577] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.730s)
[314.660930] [090/154] (705s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[314.761095] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[314.765244] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[314.855400] [091/154] (705s left) gem_mmap_wc (coherency)
[314.900607] Subtest coherency: SKIP (0.000s)
[314.959198] [092/154] (704s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[315.054935] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[315.058953] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[315.148678] [093/154] (704s left) perf (polling-parameterized)
[315.250315] Starting subtest: polling-parameterized
[335.265857] Subtest polling-parameterized: SUCCESS (20.016s)
[335.316727] [094/154] (684s left) gem_exec_reloc (basic-write-cpu-active)
[335.399434] Subtest basic-write-cpu-active: SKIP (0.000s)
[335.477500] [095/154] (684s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[335.573429] Starting subtest: plane-upscale-20x20-with-modifiers
[335.575828] Starting dynamic subtest: pipe-A
[335.908764] Dynamic subtest pipe-A: SUCCESS (0.338s)
[335.917662] Starting dynamic subtest: pipe-B
[337.350270] Dynamic subtest pipe-B: SUCCESS (1.441s)
[337.364714] Starting dynamic subtest: pipe-C
[338.769288] Dynamic subtest pipe-C: SUCCESS (1.417s)
[338.779624] Starting dynamic subtest: pipe-D
[340.206199] Dynamic subtest pipe-D: SUCCESS (1.436s)
[340.208383] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.636s)
[341.379643] [096/154] (678s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[341.477409] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[341.481760] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[341.573300] [097/154] (678s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[341.671630] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[341.675724] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[341.757499] [098/154] (678s left) kms_flip (dpms-off-confusion-interruptible)
[341.843984] Starting subtest: dpms-off-confusion-interruptible
[341.846312] Starting dynamic subtest: A-eDP1
[350.481082] Dynamic subtest A-eDP1: SUCCESS (8.637s)
[350.494543] Starting dynamic subtest: B-eDP1
[359.105048] Dynamic subtest B-eDP1: SUCCESS (8.621s)
[359.114659] Starting dynamic subtest: C-eDP1
[367.717504] Dynamic subtest C-eDP1: SUCCESS (8.610s)
[367.731116] Starting dynamic subtest: D-eDP1
[376.351182] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[376.356822] Subtest dpms-off-confusion-interruptible: SUCCESS (34.513s)
[377.566722] [099/154] (642s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[377.666708] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[382.469155] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.808s)
[383.631323] [100/154] (636s left) vgem_basic (second-client)
[383.670534] Starting subtest: second-client
[383.690858] Subtest second-client: SUCCESS (0.022s)
[383.749393] [101/154] (636s left) gem_exec_reloc (basic-wc)
[383.811098] Subtest basic-wc: SKIP (0.000s)
[383.895022] [102/154] (636s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[383.958718] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[383.960809] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[384.068059] [103/154] (636s left) kms_plane_multiple (2x-tiling-x)
[384.167119] Starting subtest: 2x-tiling-x
[384.171218] Subtest 2x-tiling-x: SKIP (0.000s)
[384.246357] [104/154] (636s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[384.336874] Starting subtest: fbc-pr-cursor-mmap-cpu
[384.341213] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[384.421346] [105/154] (636s left) gem_mmap_offset (bad-object)
[384.488460] Starting subtest: bad-object
[384.490783] Subtest bad-object: SUCCESS (0.001s)
[384.596987] [106/154] (635s left) gem_exec_whisper (basic-contexts-forked-all)
[384.662648] Starting subtest: basic-contexts-forked-all
[407.253093] Subtest basic-contexts-forked-all: SUCCESS (22.588s)
[407.440037] [107/154] (613s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[407.548203] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[407.552124] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[407.673925] [108/154] (612s left) i915_query (engine-info)
[407.730340] Starting subtest: engine-info
[407.734785] Subtest engine-info: SUCCESS (0.005s)
[407.867647] [109/154] (612s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[407.970698] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[407.975063] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[408.069690] [110/154] (612s left) dmabuf (all-tests)
[408.108604] Starting subtest: all-tests
[408.113062] Subtest all-tests: SKIP (0.000s)
[408.180012] [111/154] (612s left) kms_content_protection (dp-mst-type-0-suspend-resume)
[408.314277] Starting subtest: dp-mst-type-0-suspend-resume
[408.318608] Subtest dp-mst-type-0-suspend-resume: SKIP (0.000s)
[408.430693] [112/154] (612s left) syncobj_wait (multi-wait-all-submitted-signaled)
[408.481115] Starting subtest: multi-wait-all-submitted-signaled
[408.586058] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.103s)
[408.661975] [113/154] (612s left) gem_ctx_persistence (processes)
[408.716420] Starting subtest: processes
[410.301689] Subtest processes: SUCCESS (1.585s)
[410.382122] [114/154] (610s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[410.437229] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[410.439479] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[410.547202] [115/154] (610s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[410.658105] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[417.395096] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.744s)
[417.519286] [116/154] (603s left) kms_feature_discovery (psr2)
[417.626297] Starting subtest: psr2
[417.628138] Subtest psr2: SUCCESS (0.000s)
[417.698794] [117/154] (603s left) gen9_exec_parse (bb-start-param)
[417.760168] Subtest bb-start-param: SKIP (0.000s)
[417.840991] [118/154] (603s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[417.952073] Starting subtest: basic-flip-after-cursor-atomic
[418.882063] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.930s)
[419.694398] [119/154] (601s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-pgflip-blt)
[419.788640] Starting subtest: psrhdr-2p-scndscrn-indfb-pgflip-blt
[419.792744] Subtest psrhdr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[419.896293] [120/154] (601s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[419.983835] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[419.988084] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[420.103905] [121/154] (601s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[420.474695] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[421.311539] [122/154] (599s left) gem_mmap_gtt (big-copy)
[421.360980] Subtest big-copy: SKIP (0.000s)
[421.419932] [123/154] (599s left) api_intel_bb (crc32)
[421.478843] Starting subtest: crc32
[421.481153] Starting dynamic subtest: rcs0-smem0
[421.758220] Dynamic subtest rcs0-smem0: SUCCESS (0.278s)
[421.770877] Starting dynamic subtest: bcs0-smem0
[422.067376] Dynamic subtest bcs0-smem0: SUCCESS (0.306s)
[422.079730] Starting dynamic subtest: vcs0-smem0
[422.486069] Dynamic subtest vcs0-smem0: SUCCESS (0.416s)
[422.499038] Starting dynamic subtest: vcs1-smem0
[422.859872] Dynamic subtest vcs1-smem0: SUCCESS (0.370s)
[422.872941] Starting dynamic subtest: vecs0-smem0
[423.175685] Dynamic subtest vecs0-smem0: SUCCESS (0.313s)
[423.189761] Starting dynamic subtest: ccs0-smem0
[423.494318] Dynamic subtest ccs0-smem0: SUCCESS (0.315s)
[423.496934] Subtest crc32: SUCCESS (2.016s)
[423.622397] [124/154] (597s left) kms_psr (psr2-cursor-plane-move)
[423.721980] Starting subtest: psr2-cursor-plane-move
[423.724224] Starting dynamic subtest: eDP-1
[425.404497] Dynamic subtest eDP-1: SUCCESS (1.683s)
[425.406854] Subtest psr2-cursor-plane-move: SUCCESS (1.684s)
[426.216663] [125/154] (595s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-pwrite)
[426.312794] Starting subtest: fbchdr-rgb565-draw-pwrite
[426.317212] Subtest fbchdr-rgb565-draw-pwrite: SKIP (0.000s)
[426.418729] [126/154] (595s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[426.508134] Starting subtest: plane-lut1d-pre-ctm3x4
[426.677630] Starting dynamic subtest: pipe-A-plane-0
[426.887023] Dynamic subtest pipe-A-plane-0: SUCCESS (0.211s)
[426.899355] Starting dynamic subtest: pipe-A-plane-1
[427.075898] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[427.085814] Starting dynamic subtest: pipe-A-plane-2
[427.220380] Dynamic subtest pipe-A-plane-2: SUCCESS (0.143s)
[428.421591] Starting dynamic subtest: pipe-B-plane-0
[428.557343] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[428.567395] Starting dynamic subtest: pipe-B-plane-1
[428.702543] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s)
[428.712452] Starting dynamic subtest: pipe-B-plane-2
[428.890934] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[430.116531] Starting dynamic subtest: pipe-C-plane-0
[430.250103] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[430.263832] Starting dynamic subtest: pipe-C-plane-1
[430.383115] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s)
[430.392593] Starting dynamic subtest: pipe-C-plane-2
[430.539253] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[431.748424] Starting dynamic subtest: pipe-D-plane-0
[431.874098] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[431.883637] Starting dynamic subtest: pipe-D-plane-1
[432.007446] Dynamic subtest pipe-D-plane-1: SUCCESS (0.132s)
[432.019242] Starting dynamic subtest: pipe-D-plane-2
[432.151682] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[432.439656] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (5.933s)
[433.326207] [127/154] (588s left) gem_exec_reloc (basic-wc-active)
[433.385413] Subtest basic-wc-active: SKIP (0.000s)
[433.455525] [128/154] (588s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[433.561488] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[433.565929] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[433.674994] [129/154] (587s left) gem_exec_balancer (invalid-bonds)
[433.735872] Starting subtest: invalid-bonds
[433.741040] Subtest invalid-bonds: SKIP (0.000s)
[434.051444] [130/154] (587s left) kms_atomic (crtc-invalid-params-fence)
[434.144326] Starting subtest: crtc-invalid-params-fence
[434.146600] Starting dynamic subtest: pipe-A-eDP-1
[434.579405] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.435s)
[434.581789] Subtest crtc-invalid-params-fence: SUCCESS (0.437s)
[435.456596] [131/154] (586s left) prime_mmap_kms (buffer-sharing)
[435.556319] Starting subtest: buffer-sharing
[436.012750] Subtest buffer-sharing: SUCCESS (0.457s)
[436.881746] [132/154] (584s left) gem_userptr_blits (userfault)
[436.952488] Starting subtest: userfault
[436.954576] Subtest userfault: SUCCESS (0.005s)
[437.079734] [133/154] (584s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[437.172810] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[437.177048] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[437.278085] [134/154] (584s left) kms_frontbuffer_tracking (psrhdr-shrfb-scaledprimary)
[437.375743] Starting subtest: psrhdr-shrfb-scaledprimary
[437.380109] Subtest psrhdr-shrfb-scaledprimary: SKIP (0.000s)
[437.470606] [135/154] (584s left) kms_cursor_legacy (forked-bo)
[437.581326] Starting subtest: forked-bo
[437.583197] Starting dynamic subtest: pipe-A
[443.080793] Dynamic subtest pipe-A: SUCCESS (5.496s)
[443.100046] Starting dynamic subtest: pipe-B
[448.550368] Dynamic subtest pipe-B: SUCCESS (5.468s)
[448.562037] Starting dynamic subtest: pipe-C
[454.019687] Dynamic subtest pipe-C: SUCCESS (5.466s)
[454.031721] Starting dynamic subtest: pipe-D
[459.504916] Dynamic subtest pipe-D: SUCCESS (5.482s)
[459.527972] Starting dynamic subtest: all-pipes
[465.027906] Dynamic subtest all-pipes: SUCCESS (5.522s)
[465.030330] Subtest forked-bo: SUCCESS (27.445s)
[465.174141] [136/154] (556s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[465.278827] Starting subtest: psr-rgb101010-draw-blt
[472.623533] Subtest psr-rgb101010-draw-blt: SUCCESS (7.352s)
[472.735827] [137/154] (549s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[472.836578] Starting subtest: fbcpsr-suspend
[513.706852] Subtest fbcpsr-suspend: SUCCESS (10.848s)
[513.831541] [138/154] (508s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[513.923287] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[513.933954] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[514.039524] [139/154] (507s left) gem_ctx_isolation (nonpriv)
[514.098078] Starting subtest: nonpriv
[514.100535] Starting dynamic subtest: rcs0
[514.336617] Dynamic subtest rcs0: SUCCESS (0.242s)
[514.347533] Starting dynamic subtest: bcs0
[514.579047] Dynamic subtest bcs0: SUCCESS (0.245s)
[514.591557] Starting dynamic subtest: vcs0
[514.834796] Dynamic subtest vcs0: SUCCESS (0.258s)
[514.847820] Starting dynamic subtest: vcs1
[516.564573] Dynamic subtest vcs1: SUCCESS (1.748s)
[516.574838] Starting dynamic subtest: vecs0
[518.627308] Dynamic subtest vecs0: SUCCESS (2.080s)
[518.637532] Starting dynamic subtest: ccs0
[520.704081] Dynamic subtest ccs0: SUCCESS (2.089s)
[520.706634] Subtest nonpriv: SUCCESS (6.669s)
[520.978809] [140/154] (501s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[521.082249] Starting subtest: invalid-modeset-force-ultra-joiner
[521.086069] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[521.151827] [141/154] (500s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[521.436331] Starting subtest: cursor-rapid-movement-512x512
[521.440964] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[521.587979] [142/154] (500s left) kms_big_fb (4-tiled-addfb)
[521.679080] Starting subtest: 4-tiled-addfb
[521.681467] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[521.787932] [143/154] (500s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-render)
[521.895609] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-render
[521.899084] Subtest psrhdr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[521.992853] [144/154] (500s left) syncobj_wait (invalid-signal-illegal-handle)
[522.045296] Starting subtest: invalid-signal-illegal-handle
[522.047179] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[522.164609] [145/154] (500s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[522.246122] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[522.248032] Starting dynamic subtest: pipe-A-eDP-1
[522.265869] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[522.276854] Starting dynamic subtest: pipe-B-eDP-1
[522.293239] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[522.303139] Starting dynamic subtest: pipe-C-eDP-1
[522.321082] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[522.331120] Starting dynamic subtest: pipe-D-eDP-1
[522.349109] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[522.352620] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[524.491097] [146/154] (497s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[524.589677] Starting subtest: psrhdr-rgb101010-draw-pwrite
[524.594153] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[524.698462] [147/154] (497s left) kms_vblank (query-busy)
[524.790159] Starting subtest: query-busy
[524.791999] Starting dynamic subtest: pipe-A-eDP-1
[526.662667] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[526.679228] Starting dynamic subtest: pipe-D-eDP-1
[529.232633] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.577s)
[529.234911] Subtest query-busy: SUCCESS (4.457s)
[530.020230] [148/154] (492s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[530.115351] Starting subtest: yf-tiled-8bpp-rotate-0
[530.119568] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[530.204139] [149/154] (492s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[530.301000] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[530.305027] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[530.380154] [150/154] (492s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[530.437478] Starting subtest: multi-wait-for-submit-submitted-signaled
[530.439760] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[530.556201] [151/154] (491s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[530.659828] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[530.664326] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[530.756929] [152/154] (491s left) kms_prop_blob (blob-prop-validate)
[530.815942] Starting subtest: blob-prop-validate
[530.817960] Subtest blob-prop-validate: SUCCESS (0.000s)
[530.915623] [153/154] (491s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[531.018787] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[537.964266] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (6.960s)
[538.089583] [154/154] (484s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-render)
[538.193387] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-render
[538.197665] Subtest fbchdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[538.281424] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_async
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: perf_pmu
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: core_auth
results: parsing output: 5/ for test: kms_addfb_basic
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_mmap_write_crc
results: parsing output: 9/ for test: sriov_basic
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_plane_alpha_blend
results: parsing output: 13/ for test: gem_userptr_blits
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: core_setmaster_vs_auth
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: gem_ctx_exec
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: api_intel_bb
results: parsing output: 26/ for test: kms_pm_rpm
results: parsing output: 27/ for test: kms_flip
results: parsing output: 28/ for test: kms_plane
results: parsing output: 29/ for test: gem_exec_nop
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: vgem_basic
results: parsing output: 33/ for test: gem_mmap_offset
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: gem_exec_fence
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: gem_busy
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: i915_pm_rpm
results: parsing output: 41/ for test: gem_unref_active_buffers
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_spin_batch
results: parsing output: 44/ for test: gem_mmap_wc
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_tv_load_detect
results: parsing output: 47/ for test: syncobj_wait
results: parsing output: 48/ for test: kms_cursor_crc
results: parsing output: 49/ for test: i915_hangman
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: gem_render_copy
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_bad_reloc
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: syncobj_wait
results: parsing output: 56/ for test: kms_dsc
results: parsing output: 57/ for test: gem_exec_capture
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: kms_vblank
results: parsing output: 60/ for test: gem_exec_schedule
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_rotation_crc
results: parsing output: 63/ for test: perf
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: gem_exec_flush
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_ccs
results: parsing output: 69/ for test: gem_render_copy
results: parsing output: 70/ for test: kms_chamelium_color
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_plane
results: parsing output: 74/ for test: kms_chamelium_audio
results: parsing output: 75/ for test: gem_fenced_exec_thrash
results: parsing output: 76/ for test: gem_mmap_gtt
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_exec_schedule
results: parsing output: 79/ for test: gem_workarounds
results: parsing output: 80/ for test: i915_drm_fdinfo
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: kms_busy
results: parsing output: 84/ for test: gem_lmem_swapping
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_softpin
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: gem_mmap_wc
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: perf
results: parsing output: 93/ for test: gem_exec_reloc
results: parsing output: 94/ for test: kms_plane_scaling
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_flip
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: vgem_basic
results: parsing output: 100/ for test: gem_exec_reloc
results: parsing output: 101/ for test: syncobj_wait
results: parsing output: 102/ for test: kms_plane_multiple
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: gem_exec_whisper
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: i915_query
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: dmabuf
results: parsing output: 110/ for test: kms_content_protection
results: parsing output: 111/ for test: syncobj_wait
results: parsing output: 112/ for test: gem_ctx_persistence
results: parsing output: 113/ for test: syncobj_wait
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_feature_discovery
results: parsing output: 116/ for test: gen9_exec_parse
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_chamelium_hpd
results: parsing output: 121/ for test: gem_mmap_gtt
results: parsing output: 122/ for test: api_intel_bb
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_color_pipeline
results: parsing output: 126/ for test: gem_exec_reloc
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_exec_balancer
results: parsing output: 129/ for test: kms_atomic
results: parsing output: 130/ for test: prime_mmap_kms
results: parsing output: 131/ for test: gem_userptr_blits
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_cursor_legacy
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_flip
results: parsing output: 138/ for test: gem_ctx_isolation
results: parsing output: 139/ for test: kms_joiner
results: parsing output: 140/ for test: kms_cursor_crc
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: syncobj_wait
results: parsing output: 144/ for test: kms_ccs
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_vblank
results: parsing output: 147/ for test: kms_big_fb
results: parsing output: 148/ for test: kms_psr2_sf
results: parsing output: 149/ for test: syncobj_timeline
results: parsing output: 150/ for test: kms_big_fb
results: parsing output: 151/ for test: kms_prop_blob
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_frontbuffer_tracking
Done.