[57.411330] Initializing watchdogs
[57.411377]   /dev/watchdog0
[57.424941] [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]
[57.429326] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.431751] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.435395] [001/133] (960s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[57.566708] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[57.571276] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[57.668139] [002/133] (960s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[57.746842] Starting subtest: y-tiled-to-vebox-x-tiled
[57.751404] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[57.943793] [003/133] (960s left) perf (missing-sample-flags)
[58.065115] Starting subtest: missing-sample-flags
[58.067391] Subtest missing-sample-flags: SUCCESS (0.000s)
[58.101199] [004/133] (959s left) kms_psr (psr2-suspend)
[58.198827] Starting subtest: psr2-suspend
[58.201104] Starting dynamic subtest: eDP-1
[76.237865] Dynamic subtest eDP-1: SUCCESS (3.785s)
[76.240202] Subtest psr2-suspend: SUCCESS (3.786s)
[77.140681] [005/133] (940s left) kms_hdmi_inject (inject-4k)
[77.490509] Starting subtest: inject-4k
[77.808288] Subtest inject-4k: SUCCESS (0.318s)
[78.382211] [006/133] (939s left) gem_mmap (bad-object)
[78.435639] Subtest bad-object: SKIP (0.000s)
[78.494303] [007/133] (939s left) kms_plane (pixel-format-y-tiled-modifier)
[78.590441] Starting subtest: pixel-format-y-tiled-modifier
[78.594590] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[78.658533] [008/133] (939s left) syncobj_timeline (wait-for-submit-complex)
[78.710219] Starting subtest: wait-for-submit-complex
[78.771599] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[78.855338] [009/133] (939s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[78.956968] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[78.961424] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[79.062640] [010/133] (939s left) gem_ctx_persistence (legacy-engines-hang)
[79.125096] Starting subtest: legacy-engines-hang
[79.127464] Starting dynamic subtest: render
[79.255660] Dynamic subtest render: SUCCESS (0.129s)
[79.265751] Starting dynamic subtest: bsd1
[79.364458] Dynamic subtest bsd1: SUCCESS (0.107s)
[79.373814] Starting dynamic subtest: bsd2
[79.453529] Dynamic subtest bsd2: SUCCESS (0.088s)
[79.462868] Starting dynamic subtest: blt
[79.557045] Dynamic subtest blt: SUCCESS (0.102s)
[79.567194] Starting dynamic subtest: vebox
[79.644998] Dynamic subtest vebox: SUCCESS (0.087s)
[79.647361] Subtest legacy-engines-hang: SUCCESS (0.521s)
[79.720172] [011/133] (938s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[79.816647] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[79.818575] Starting dynamic subtest: pipe-A-eDP-1
[79.826171] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[79.835039] Starting dynamic subtest: pipe-B-eDP-1
[79.842672] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[79.849682] Starting dynamic subtest: pipe-C-eDP-1
[79.857049] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[79.863573] Starting dynamic subtest: pipe-D-eDP-1
[79.870684] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[79.873999] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[82.076554] [012/133] (936s left) syncobj_timeline (invalid-signal-illegal-point)
[82.182863] Starting subtest: invalid-signal-illegal-point
[82.185242] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[82.438567] [013/133] (935s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[82.542317] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[89.213663] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.674s)
[89.348084] [014/133] (929s left) i915_selftest (live)
[91.065209] Starting subtest: live
[91.068030] Starting dynamic subtest: sanitycheck
[95.421668] Dynamic subtest sanitycheck: SUCCESS (4.356s)
[95.428449] Starting dynamic subtest: uncore
[99.769141] Dynamic subtest uncore: SUCCESS (4.346s)
[99.778649] Starting dynamic subtest: workarounds
[102.817060] Dynamic subtest workarounds: SUCCESS (3.047s)
[102.826438] Starting dynamic subtest: gt_engines
[107.294898] Dynamic subtest gt_engines: SUCCESS (4.476s)
[107.303671] Starting dynamic subtest: gt_timelines
[123.281440] Dynamic subtest gt_timelines: SUCCESS (15.985s)
[123.290913] Starting dynamic subtest: gt_contexts
[127.595280] Dynamic subtest gt_contexts: SUCCESS (4.312s)
[127.604609] Starting dynamic subtest: gt_lrc
[131.859582] Dynamic subtest gt_lrc: SUCCESS (4.264s)
[131.868808] Starting dynamic subtest: gt_mocs
[134.706040] Dynamic subtest gt_mocs: SUCCESS (2.845s)
[134.715437] Starting dynamic subtest: gt_pm
[141.339511] Dynamic subtest gt_pm: SUCCESS (6.632s)
[141.359486] Starting dynamic subtest: gt_heartbeat
[145.753015] Dynamic subtest gt_heartbeat: SUCCESS (4.410s)
[145.764277] Starting dynamic subtest: gt_tlb
[154.974880] Dynamic subtest gt_tlb: SUCCESS (9.221s)
[154.983519] Starting dynamic subtest: requests
[166.375982] Dynamic subtest requests: SUCCESS (11.400s)
[166.385103] Starting dynamic subtest: migrate
[174.125203] Dynamic subtest migrate: SUCCESS (7.748s)
[174.133061] Starting dynamic subtest: active
[176.808341] Dynamic subtest active: SUCCESS (2.681s)
[176.817153] Starting dynamic subtest: objects
[181.482071] Dynamic subtest objects: SUCCESS (4.673s)
[181.491592] Starting dynamic subtest: mman
[186.375359] Dynamic subtest mman: SUCCESS (4.892s)
[186.390682] Starting dynamic subtest: dmabuf
[190.709304] Dynamic subtest dmabuf: SUCCESS (4.332s)
[190.718669] Starting dynamic subtest: vma
[194.960137] Dynamic subtest vma: SUCCESS (4.249s)
[194.969661] Starting dynamic subtest: coherency
[199.915444] Dynamic subtest coherency: SUCCESS (4.952s)
[199.923544] Starting dynamic subtest: gtt
[211.862125] Dynamic subtest gtt: SUCCESS (11.945s)
[211.871168] Starting dynamic subtest: gem
[217.832521] Dynamic subtest gem: SUCCESS (5.970s)
[217.840263] Starting dynamic subtest: evict
[223.228092] Dynamic subtest evict: SUCCESS (5.393s)
[223.237371] Starting dynamic subtest: hugepages
[233.898416] Dynamic subtest hugepages: SUCCESS (10.669s)
[233.907823] Starting dynamic subtest: gem_contexts
[262.803944] Dynamic subtest gem_contexts: SUCCESS (28.904s)
[262.813329] Starting dynamic subtest: client
[267.073637] Dynamic subtest client: SUCCESS (4.269s)
[267.083080] Starting dynamic subtest: gem_migrate
[271.422309] Dynamic subtest gem_migrate: SUCCESS (4.347s)
[271.431292] Starting dynamic subtest: reset
[275.761581] Dynamic subtest reset: SUCCESS (4.337s)
[275.771065] Starting dynamic subtest: memory_region
[278.417729] Dynamic subtest memory_region: SUCCESS (2.655s)
[278.426567] Starting dynamic subtest: hangcheck
[291.230470] Dynamic subtest hangcheck: SUCCESS (12.811s)
[291.240073] Starting dynamic subtest: execlists
[295.567681] Dynamic subtest execlists: SUCCESS (4.336s)
[295.576495] Starting dynamic subtest: ring_submission
[299.890008] Dynamic subtest ring_submission: SUCCESS (4.320s)
[299.899153] Starting dynamic subtest: perf
[304.152074] Dynamic subtest perf: SUCCESS (4.261s)
[304.161854] Starting dynamic subtest: slpc
[316.188840] Dynamic subtest slpc: SUCCESS (12.035s)
[316.198387] Starting dynamic subtest: guc
[320.075333] Dynamic subtest guc: SUCCESS (3.885s)
[320.084426] Starting dynamic subtest: guc_multi_lrc
[324.443646] Dynamic subtest guc_multi_lrc: SUCCESS (4.366s)
[324.453166] Starting dynamic subtest: guc_hang
[329.270060] Dynamic subtest guc_hang: SUCCESS (4.824s)
[329.279650] Starting dynamic subtest: late_gt_pm
[333.601488] Dynamic subtest late_gt_pm: SUCCESS (4.330s)
[333.604655] Subtest live: SUCCESS (242.530s)
[333.632238] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[333.635398] [015/133] (684s left) gem_softpin (invalid)
[335.103921] Starting subtest: invalid
[335.116755] Subtest invalid: SUCCESS (0.014s)
[335.247537] [FACT gem_softpin (invalid)] new: kernel.kmod_is_loaded.i915: true
[335.253325] [016/133] (683s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[335.347396] Starting subtest: fbc-psr2-sprite-plane-onoff
[335.349635] Starting dynamic subtest: eDP-1
[335.353696] Dynamic subtest eDP-1: SKIP (0.000s)
[335.357793] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[335.422836] [017/133] (683s left) gem_tiled_partial_pwrite_pread (reads)
[335.473920] Subtest reads: SKIP (0.000s)
[335.546724] [018/133] (683s left) kms_getfb (getfb2-handle-zero)
[335.638828] Starting subtest: getfb2-handle-zero
[335.641012] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[335.733292] [019/133] (682s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[335.823331] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[335.825599] Starting dynamic subtest: pipe-A
[335.844766] Dynamic subtest pipe-A: SKIP (0.017s)
[335.852947] Starting dynamic subtest: pipe-B
[335.879602] Dynamic subtest pipe-B: SKIP (0.034s)
[335.887383] Starting dynamic subtest: pipe-C
[335.913877] Dynamic subtest pipe-C: SKIP (0.034s)
[335.921456] Starting dynamic subtest: pipe-D
[335.948041] Dynamic subtest pipe-D: SKIP (0.034s)
[335.952114] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.120s)
[336.055704] [020/133] (682s left) gem_exec_whisper (basic-fds-priority-all)
[336.107761] Starting subtest: basic-fds-priority-all
[358.320336] Subtest basic-fds-priority-all: SUCCESS (22.207s)
[358.496996] [021/133] (660s left) i915_hangman (gt-engine-error)
[358.555718] Starting subtest: gt-engine-error
[358.558086] Starting dynamic subtest: rcs0
[360.098532] Dynamic subtest rcs0: SUCCESS (1.541s)
[360.110346] Starting dynamic subtest: bcs0
[362.147085] Dynamic subtest bcs0: SUCCESS (2.046s)
[362.158124] Starting dynamic subtest: vcs0
[364.130554] Dynamic subtest vcs0: SUCCESS (1.981s)
[364.141459] Starting dynamic subtest: vcs1
[366.114900] Dynamic subtest vcs1: SUCCESS (1.982s)
[366.126560] Starting dynamic subtest: vecs0
[368.162556] Dynamic subtest vecs0: SUCCESS (2.044s)
[368.174584] Starting dynamic subtest: ccs0
[370.148834] Dynamic subtest ccs0: SUCCESS (1.983s)
[370.151931] Subtest gt-engine-error: SUCCESS (11.593s)
[370.229117] [022/133] (648s left) i915_pm_rpm (system-suspend-devices)
[372.294658] Starting subtest: system-suspend-devices
[379.946392] Subtest system-suspend-devices: SUCCESS (7.655s)
[380.323271] [023/133] (638s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[380.438208] Starting subtest: y-tiled-32bpp-rotate-180
[380.442706] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[380.530752] [024/133] (638s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[380.698215] Starting subtest: cursor-rapid-movement-32x10
[380.749450] Subtest cursor-rapid-movement-32x10: SKIP (0.045s)
[381.012188] [025/133] (637s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[381.099834] Starting subtest: primary-4-tiled-reflect-x-180
[388.133785] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.033s)
[389.368913] [026/133] (629s left) kms_psr (fbc-pr-primary-page-flip)
[389.471362] Starting subtest: fbc-pr-primary-page-flip
[389.475857] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[389.542046] [027/133] (629s left) core_getversion (basic)
[389.581693] Starting subtest: basic
[389.586280] Subtest basic: SUCCESS (0.000s)
[389.645709] [028/133] (629s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[389.737924] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[389.742043] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[389.834381] [029/133] (629s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[389.899091] Starting subtest: etime-multi-wait-for-submit-submitted
[389.999665] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.101s)
[390.087230] [030/133] (628s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[390.143568] Starting subtest: multi-wait-for-submit-available-signaled
[390.145839] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[390.236293] [031/133] (628s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[390.329453] Starting subtest: yf-tiled-addfb-size-offset-overflow
[390.333117] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[390.422068] [032/133] (628s left) kms_cursor_edge_walk (128x128-top-bottom)
[390.526900] Starting subtest: 128x128-top-bottom
[390.528815] Starting dynamic subtest: pipe-A-eDP-1
[393.941748] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.414s)
[393.950328] Starting dynamic subtest: pipe-D-eDP-1
[397.070697] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.126s)
[397.072971] Subtest 128x128-top-bottom: SUCCESS (6.543s)
[397.867353] [033/133] (621s left) gem_exec_reloc (basic-active)
[397.933561] Subtest basic-active: SKIP (0.000s)
[398.001460] [034/133] (621s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[398.105185] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[398.107302] Starting dynamic subtest: pipe-A-eDP-1
[408.806679] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.705s)
[408.815924] Starting dynamic subtest: pipe-B-eDP-1
[420.177938] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.369s)
[420.180286] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.077s)
[421.037285] [035/133] (598s left) gem_mmap_wc (write)
[421.107244] Subtest write: SKIP (0.000s)
[421.169712] [036/133] (598s left) kms_pm_backlight (brightness-with-dpms)
[421.264273] Starting subtest: brightness-with-dpms
[421.316354] Starting dynamic subtest: eDP-1
[423.952262] Dynamic subtest eDP-1: SUCCESS (2.636s)
[423.954579] Subtest brightness-with-dpms: SUCCESS (2.688s)
[424.830112] [037/133] (594s left) core_getstats (basic)
[424.884067] Starting subtest: basic
[424.886045] Subtest basic: SUCCESS (0.000s)
[424.970799] [038/133] (594s left) kms_atomic (atomic-plane-damage)
[425.065583] Starting subtest: atomic-plane-damage
[425.112602] Starting dynamic subtest: pipe-A-eDP-1
[425.647005] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.532s)
[425.649276] Subtest atomic-plane-damage: SUCCESS (0.582s)
[426.452153] [039/133] (592s left) kms_flip (basic-flip-vs-dpms)
[426.572782] Starting subtest: basic-flip-vs-dpms
[426.574663] Starting dynamic subtest: A-eDP1
[429.175685] Dynamic subtest A-eDP1: SUCCESS (2.602s)
[429.184488] Starting dynamic subtest: B-eDP1
[431.423756] Dynamic subtest B-eDP1: SUCCESS (2.246s)
[431.434325] Starting dynamic subtest: C-eDP1
[433.577257] Dynamic subtest C-eDP1: SUCCESS (2.151s)
[433.586921] Starting dynamic subtest: D-eDP1
[435.661404] Dynamic subtest D-eDP1: SUCCESS (2.082s)
[435.663849] Subtest basic-flip-vs-dpms: SUCCESS (9.092s)
[436.457838] [040/133] (583s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[436.511214] Starting subtest: invalid-smem-bo-on-discrete
[436.515844] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[436.632321] [041/133] (582s left) gem_eio (wait-wedge-10ms)
[436.874764] Starting subtest: wait-wedge-10ms
[438.221671] Subtest wait-wedge-10ms: SUCCESS (1.346s)
[438.361720] [042/133] (581s left) gem_ctx_persistence (engines-mixed-process)
[438.417542] Starting subtest: engines-mixed-process
[438.419414] Starting dynamic subtest: rcs0
[440.137224] Dynamic subtest rcs0: SUCCESS (1.722s)
[440.148821] Starting dynamic subtest: bcs0
[442.172690] Dynamic subtest bcs0: SUCCESS (2.034s)
[442.183477] Starting dynamic subtest: vcs0
[444.153503] Dynamic subtest vcs0: SUCCESS (1.976s)
[444.164244] Starting dynamic subtest: vcs1
[446.158253] Dynamic subtest vcs1: SUCCESS (2.007s)
[446.172124] Starting dynamic subtest: vecs0
[448.137413] Dynamic subtest vecs0: SUCCESS (1.977s)
[448.150764] Starting dynamic subtest: ccs0
[450.110438] Dynamic subtest ccs0: SUCCESS (1.972s)
[450.113067] Subtest engines-mixed-process: SUCCESS (11.695s)
[450.188276] [043/133] (569s left) gem_exec_reloc (basic-wc)
[450.248245] Subtest basic-wc: SKIP (0.000s)
[450.315756] [044/133] (569s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[450.425821] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[450.429817] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[450.504465] [045/133] (569s left) kms_plane_cursor (viewport)
[450.606423] Starting subtest: viewport
[450.610817] Starting dynamic subtest: pipe-A-eDP-1-size-64
[453.042076] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.435s)
[453.102871] Starting dynamic subtest: pipe-A-eDP-1-size-128
[455.498651] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.404s)
[455.559390] Starting dynamic subtest: pipe-A-eDP-1-size-256
[458.022206] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.471s)
[458.392364] Starting dynamic subtest: pipe-B-eDP-1-size-64
[461.684225] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.303s)
[461.752439] Starting dynamic subtest: pipe-B-eDP-1-size-128
[464.185302] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.449s)
[464.246184] Starting dynamic subtest: pipe-B-eDP-1-size-256
[466.709074] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.471s)
[467.060463] Starting dynamic subtest: pipe-C-eDP-1-size-64
[469.889441] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.837s)
[469.927793] Starting dynamic subtest: pipe-C-eDP-1-size-128
[471.999536] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.080s)
[472.037144] Starting dynamic subtest: pipe-C-eDP-1-size-256
[474.089237] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.058s)
[474.418213] Starting dynamic subtest: pipe-D-eDP-1-size-64
[477.203748] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.798s)
[477.245936] Starting dynamic subtest: pipe-D-eDP-1-size-128
[479.359939] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.125s)
[479.397033] Starting dynamic subtest: pipe-D-eDP-1-size-256
[481.481802] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.091s)
[481.804590] Subtest viewport: SUCCESS (31.198s)
[482.596261] [046/133] (537s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[482.713368] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[483.370837] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.659s)
[483.522839] [047/133] (536s left) kms_chamelium_frames (dp-crc-multiple)
[483.928236] Subtest dp-crc-multiple: SKIP (0.000s)
[484.716092] [048/133] (535s left) gem_spin_batch (engines)
[484.780630] Starting subtest: engines
[484.782807] Starting dynamic subtest: rcs0
[488.020426] Dynamic subtest rcs0: SUCCESS (3.237s)
[488.032712] Starting dynamic subtest: bcs0
[491.310053] Dynamic subtest bcs0: SUCCESS (3.236s)
[491.321825] Starting dynamic subtest: vcs0
[494.550257] Dynamic subtest vcs0: SUCCESS (3.237s)
[494.564356] Starting dynamic subtest: vcs1
[497.786607] Dynamic subtest vcs1: SUCCESS (3.234s)
[497.801806] Starting dynamic subtest: vecs0
[501.027440] Dynamic subtest vecs0: SUCCESS (3.238s)
[501.039041] Starting dynamic subtest: ccs0
[504.261038] Dynamic subtest ccs0: SUCCESS (3.229s)
[504.263832] Subtest engines: SUCCESS (19.428s)
[504.440888] [049/133] (515s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[504.553340] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[504.555607] Starting dynamic subtest: pipe-A-eDP-1
[504.577421] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[504.588125] Starting dynamic subtest: pipe-B-eDP-1
[504.609395] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[504.618307] Starting dynamic subtest: pipe-C-eDP-1
[504.638708] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[504.647493] Starting dynamic subtest: pipe-D-eDP-1
[504.669352] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[504.673419] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[506.890672] [050/133] (512s left) kms_vblank (wait-busy-hang)
[506.987256] Starting subtest: wait-busy-hang
[506.989336] Starting dynamic subtest: pipe-A-eDP-1
[533.904187] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.916s)
[533.918941] Starting dynamic subtest: pipe-D-eDP-1
[557.955015] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.046s)
[557.957231] Subtest wait-busy-hang: SUCCESS (50.967s)
[558.755513] [051/133] (461s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[558.889002] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[558.893255] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[558.989711] [052/133] (460s left) perf (invalid-remove-userspace-config)
[559.136792] Starting subtest: invalid-remove-userspace-config
[559.148613] Subtest invalid-remove-userspace-config: SUCCESS (0.010s)
[559.194563] [053/133] (460s left) sw_sync (sync_multi_consumer)
[559.229072] Starting subtest: sync_multi_consumer
[559.664968] Subtest sync_multi_consumer: SUCCESS (0.439s)
[559.726532] [054/133] (460s left) kms_plane (pixel-format-yf-tiled-modifier)
[559.804932] Starting subtest: pixel-format-yf-tiled-modifier
[559.808998] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[559.876562] [055/133] (460s left) prime_busy (after-wait)
[559.940800] Starting subtest: after-wait
[559.946123] Starting dynamic subtest: rcs0
[559.990437] Dynamic subtest rcs0: SUCCESS (0.044s)
[560.003645] Starting dynamic subtest: bcs0
[560.043950] Dynamic subtest bcs0: SUCCESS (0.051s)
[560.055688] Starting dynamic subtest: vcs0
[560.081959] Dynamic subtest vcs0: SUCCESS (0.037s)
[560.091215] Starting dynamic subtest: vcs1
[560.121148] Dynamic subtest vcs1: SUCCESS (0.038s)
[560.130267] Starting dynamic subtest: vecs0
[560.160577] Dynamic subtest vecs0: SUCCESS (0.038s)
[560.169664] Starting dynamic subtest: ccs0
[560.198481] Dynamic subtest ccs0: SUCCESS (0.038s)
[560.306960] Subtest after-wait: SUCCESS (0.367s)
[560.378652] [056/133] (459s left) template (a)
[560.435165] Starting subtest: A
[560.437142] Subtest A: SUCCESS (0.000s)
[560.555275] [057/133] (459s left) gem_exec_schedule (semaphore-codependency)
[560.633164] Starting subtest: semaphore-codependency
[560.640549] Subtest semaphore-codependency: SUCCESS (0.013s)
[560.993612] [058/133] (459s left) kms_chamelium_hpd (vga-hpd)
[561.386036] Subtest vga-hpd: SKIP (0.000s)
[562.177827] [059/133] (458s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[562.264532] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[562.266869] Starting dynamic subtest: pipe-A-default-mode
[563.475931] Dynamic subtest pipe-A-default-mode: SKIP (1.209s)
[563.480245] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.211s)
[563.632441] [060/133] (456s left) gem_mmap_wc (read)
[563.692611] Subtest read: SKIP (0.000s)
[563.767155] [061/133] (456s left) api_intel_allocator (execbuf-with-allocator)
[563.821076] Starting subtest: execbuf-with-allocator
[563.823314] Subtest execbuf-with-allocator: SUCCESS (0.005s)
[563.942042] [062/133] (456s left) gem_cs_tlb (engines)
[564.001299] Starting subtest: engines
[564.003556] Starting dynamic subtest: rcs0
[566.171761] Dynamic subtest rcs0: SUCCESS (2.152s)
[566.182554] Starting dynamic subtest: bcs0
[568.362451] Dynamic subtest bcs0: SUCCESS (2.153s)
[568.372038] Starting dynamic subtest: vcs0
[570.517269] Dynamic subtest vcs0: SUCCESS (2.152s)
[570.527201] Starting dynamic subtest: vcs1
[572.668591] Dynamic subtest vcs1: SUCCESS (2.152s)
[572.679442] Starting dynamic subtest: vecs0
[574.823631] Dynamic subtest vecs0: SUCCESS (2.153s)
[574.835553] Starting dynamic subtest: ccs0
[576.978674] Dynamic subtest ccs0: SUCCESS (2.154s)
[576.981367] Subtest engines: SUCCESS (12.923s)
[577.053303] [063/133] (443s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[577.162255] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[583.887196] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.728s)
[584.010301] [064/133] (436s left) kms_content_protection (atomic-dpms)
[584.152477] Starting subtest: atomic-dpms
[584.157050] Subtest atomic-dpms: SKIP (0.000s)
[584.288879] [065/133] (436s left) kms_big_fb (linear-16bpp-rotate-270)
[584.380983] Starting subtest: linear-16bpp-rotate-270
[584.401270] Subtest linear-16bpp-rotate-270: SKIP (0.019s)
[584.518146] [066/133] (436s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[584.612802] Starting subtest: x-tiled-16bpp-rotate-180
[585.891402] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.278s)
[586.023132] [067/133] (434s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[586.155208] Starting subtest: verify-pxp-key-change-after-suspend-resume
[593.328788] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.175s)
[593.443049] [068/133] (427s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[593.543353] Starting subtest: psr-modesetfrombusy
[599.680896] Subtest psr-modesetfrombusy: SUCCESS (6.142s)
[599.809326] [069/133] (420s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[599.908129] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[600.878066] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (0.969s)
[600.991057] [070/133] (419s left) gen7_exec_parse (bitmasks)
[601.058374] Subtest bitmasks: SKIP (0.000s)
[601.143158] [071/133] (419s left) gem_exec_flush (basic-uc-set-default)
[601.199083] Starting subtest: basic-uc-set-default
[606.690946] Subtest basic-uc-set-default: SUCCESS (5.458s)
[606.853435] [072/133] (413s left) gem_exec_fence (keep-in-fence)
[607.027164] Starting subtest: keep-in-fence
[607.029729] Starting dynamic subtest: rcs0
[607.103073] Dynamic subtest rcs0: SUCCESS (0.076s)
[607.114145] Starting dynamic subtest: bcs0
[607.161056] Dynamic subtest bcs0: SUCCESS (0.056s)
[607.173397] Starting dynamic subtest: vcs0
[609.156867] Dynamic subtest vcs0: SUCCESS (1.992s)
[609.169338] Starting dynamic subtest: vcs1
[611.142470] Dynamic subtest vcs1: SUCCESS (1.983s)
[611.154423] Starting dynamic subtest: vecs0
[613.242099] Dynamic subtest vecs0: SUCCESS (2.044s)
[613.252672] Starting dynamic subtest: ccs0
[613.434575] Dynamic subtest ccs0: SUCCESS (0.244s)
[613.437639] Subtest keep-in-fence: SUCCESS (6.409s)
[615.225564] [FACT gem_exec_fence (keep-in-fence)] new: kernel.kmod_is_loaded.vgem: true
[615.229193] [073/133] (405s left) kms_cursor_crc (cursor-onscreen-32x32)
[615.392914] Starting subtest: cursor-onscreen-32x32
[615.448735] Subtest cursor-onscreen-32x32: SKIP (0.051s)
[615.747443] [074/133] (405s left) gen3_render_tiledy_blits
[615.889707] [075/133] (405s left) gem_exec_schedule (pi-distinct-iova)
[616.081515] Starting subtest: pi-distinct-iova
[616.083562] Starting dynamic subtest: rcs0
[616.156942] Dynamic subtest rcs0: SUCCESS (0.079s)
[616.170187] Starting dynamic subtest: vcs0
[616.223716] Dynamic subtest vcs0: SUCCESS (0.066s)
[616.239224] Starting dynamic subtest: vcs1
[616.279002] Dynamic subtest vcs1: SUCCESS (0.055s)
[616.289234] Starting dynamic subtest: vecs0
[616.331532] Dynamic subtest vecs0: SUCCESS (0.051s)
[616.342211] Starting dynamic subtest: ccs0
[616.396227] Dynamic subtest ccs0: SUCCESS (0.064s)
[616.398703] Subtest pi-distinct-iova: SUCCESS (0.319s)
[618.286845] [076/133] (402s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[618.381662] Starting subtest: bad-pixel-format-y-tiled-ccs
[618.383950] Starting dynamic subtest: pipe-A-eDP-1
[618.406170] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[618.418314] Starting dynamic subtest: pipe-B-eDP-1
[618.439676] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[618.449051] Starting dynamic subtest: pipe-C-eDP-1
[618.470578] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[618.479978] Starting dynamic subtest: pipe-D-eDP-1
[618.501761] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[618.505767] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[620.636461] [077/133] (400s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[620.741904] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[620.745827] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[620.833442] [078/133] (400s left) gem_wait (write-busy)
[620.889552] Starting subtest: write-busy
[620.891763] Starting dynamic subtest: all-engines
[621.493552] Dynamic subtest all-engines: SUCCESS (0.603s)
[621.512590] Starting dynamic subtest: rcs0
[622.045647] Dynamic subtest rcs0: SUCCESS (0.549s)
[622.062262] Starting dynamic subtest: bcs0
[622.593649] Dynamic subtest bcs0: SUCCESS (0.547s)
[622.611178] Starting dynamic subtest: vcs0
[624.197646] Dynamic subtest vcs0: SUCCESS (1.601s)
[624.215148] Starting dynamic subtest: vcs1
[626.116581] Dynamic subtest vcs1: SUCCESS (1.915s)
[626.133959] Starting dynamic subtest: vecs0
[628.163696] Dynamic subtest vecs0: SUCCESS (2.044s)
[628.180775] Starting dynamic subtest: ccs0
[628.712505] Dynamic subtest ccs0: SUCCESS (0.548s)
[628.715065] Subtest write-busy: SUCCESS (7.823s)
[628.916751] [079/133] (392s left) gem_ctx_shared (q-out-order)
[629.000806] Starting subtest: Q-out-order
[629.002769] Starting dynamic subtest: rcs0
[629.162302] Dynamic subtest rcs0: SUCCESS (0.162s)
[629.180120] Starting dynamic subtest: vcs0
[629.262281] Dynamic subtest vcs0: SUCCESS (0.100s)
[629.280872] Starting dynamic subtest: vcs1
[629.368477] Dynamic subtest vcs1: SUCCESS (0.106s)
[629.386714] Starting dynamic subtest: vecs0
[629.485416] Dynamic subtest vecs0: SUCCESS (0.116s)
[629.504497] Starting dynamic subtest: ccs0
[629.619832] Dynamic subtest ccs0: SUCCESS (0.132s)
[629.622305] Subtest Q-out-order: SUCCESS (0.620s)
[631.205311] [080/133] (389s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[631.294537] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[631.299035] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[631.367730] [081/133] (389s left) gem_pxp (create-regular-context-1)
[631.425994] Starting subtest: create-regular-context-1
[631.428598] Subtest create-regular-context-1: SUCCESS (0.002s)
[631.549992] [082/133] (389s left) kms_color (gamma)
[631.646389] Starting subtest: gamma
[631.648590] Starting dynamic subtest: pipe-A-eDP-1
[632.355233] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[632.366392] Starting dynamic subtest: pipe-B-eDP-1
[633.754974] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.398s)
[633.764706] Starting dynamic subtest: pipe-C-eDP-1
[635.121378] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.367s)
[635.131753] Starting dynamic subtest: pipe-D-eDP-1
[636.454509] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[636.457349] Subtest gamma: SUCCESS (4.809s)
[637.268166] [083/133] (383s left) kms_addfb_basic (invalid-set-prop)
[637.319896] Starting subtest: invalid-set-prop
[637.322132] Subtest invalid-set-prop: SUCCESS (0.000s)
[637.452769] [084/133] (383s left) syncobj_basic (bad-pad-fd-to-handle)
[637.511405] Starting subtest: bad-pad-fd-to-handle
[637.513433] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[637.614658] [085/133] (383s left) gem_exec_balancer (sliced)
[637.675605] Starting subtest: sliced
[637.680385] Subtest sliced: SKIP (0.000s)
[637.967521] [086/133] (383s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[638.052784] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[638.064704] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.010s)
[638.144676] [087/133] (383s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[638.243918] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[638.248314] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[638.352630] [088/133] (383s left) kms_chamelium_audio (dp-audio)
[638.757865] Subtest dp-audio: SKIP (0.000s)
[639.594860] [089/133] (381s left) kms_addfb_basic (invalid-get-prop-any)
[639.646135] Starting subtest: invalid-get-prop-any
[639.648549] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[639.783190] [090/133] (381s left) kms_flip (flip-vs-modeset-vs-hang)
[639.881415] Starting subtest: flip-vs-modeset-vs-hang
[639.883752] Starting dynamic subtest: A-eDP1
[690.662492] Dynamic subtest A-eDP1: SUCCESS (50.771s)
[690.672027] Starting dynamic subtest: D-eDP1
[740.648501] Dynamic subtest D-eDP1: SUCCESS (49.980s)
[740.650935] Subtest flip-vs-modeset-vs-hang: SUCCESS (100.758s)
[741.011820] [091/133] (280s left) kms_psr (pr-sprite-mmap-gtt)
[741.102605] Starting subtest: pr-sprite-mmap-gtt
[741.107061] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[741.190099] [092/133] (280s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[741.285494] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[741.289913] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[741.394324] [093/133] (280s left) kms_lease (lease-invalid-connector)
[741.484775] Starting subtest: lease-invalid-connector
[741.487120] Starting dynamic subtest: pipe-A-eDP-1
[741.489440] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[741.499364] Starting dynamic subtest: pipe-B-eDP-1
[741.501712] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[741.511704] Starting dynamic subtest: pipe-C-eDP-1
[741.513937] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[741.523849] Starting dynamic subtest: pipe-D-eDP-1
[741.526058] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[741.528205] Subtest lease-invalid-connector: SUCCESS (0.004s)
[741.566127] [094/133] (280s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[741.655250] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[745.984437] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.332s)
[747.201678] [095/133] (274s left) gem_caching (read-writes)
[747.261133] Subtest read-writes: SKIP (0.000s)
[747.333161] [096/133] (274s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[747.424410] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[747.426672] Starting dynamic subtest: pipe-A-eDP-1
[747.448148] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[747.459196] Starting dynamic subtest: pipe-B-eDP-1
[747.479776] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[747.489394] Starting dynamic subtest: pipe-C-eDP-1
[747.510098] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[747.520577] Starting dynamic subtest: pipe-D-eDP-1
[747.541316] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[747.545236] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.008s)
[749.748396] [097/133] (272s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[749.868783] Starting subtest: cursorB-vs-flipA-atomic
[749.873194] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[750.016885] [098/133] (271s left) gem_mmap_gtt (basic)
[750.073258] Subtest basic: SKIP (0.000s)
[750.128215] [099/133] (271s left) gem_mmap_offset (open-flood)
[750.175479] Starting subtest: open-flood
[771.662488] Subtest open-flood: SUCCESS (21.490s)
[771.768375] [100/133] (250s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[771.869570] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[771.873297] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[771.988287] [101/133] (250s left) kms_pm_rpm (universal-planes)
[774.322854] Starting subtest: universal-planes
[774.325757] Starting dynamic subtest: plane-34
[782.665506] Dynamic subtest plane-34: SUCCESS (8.341s)
[782.677059] Starting dynamic subtest: plane-64
[790.958969] Dynamic subtest plane-64: SUCCESS (8.291s)
[790.972695] Starting dynamic subtest: plane-94
[799.268937] Dynamic subtest plane-94: SUCCESS (8.308s)
[799.281361] Starting dynamic subtest: plane-124
[807.556114] Dynamic subtest plane-124: SUCCESS (8.285s)
[807.567931] Starting dynamic subtest: plane-134
[815.837945] Dynamic subtest plane-134: SUCCESS (8.279s)
[815.850925] Starting dynamic subtest: plane-144
[824.154163] Dynamic subtest plane-144: SUCCESS (8.313s)
[824.157175] Subtest universal-planes: SUCCESS (49.832s)
[824.545586] [102/133] (197s left) kms_flip (2x-flip-vs-dpms)
[824.639907] Starting subtest: 2x-flip-vs-dpms
[824.648146] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[824.729150] [103/133] (197s left) perf (per-context-mode-unprivileged)
[824.825607] Starting subtest: per-context-mode-unprivileged
[824.830344] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[824.868668] [104/133] (197s left) gem_mmap_gtt (basic-small-bo-tiledx)
[824.915719] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[824.980111] [105/133] (197s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[825.097889] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[825.102397] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[825.243059] [106/133] (196s left) prime_udl
[825.328433] [107/133] (196s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[825.437460] Starting subtest: flip-vs-cursor-busy-crc-legacy
[826.256113] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.819s)
[827.054710] [108/133] (195s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[827.170973] Starting subtest: basic-busy-flip-before-cursor-legacy
[827.175380] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[827.317305] [109/133] (195s left) gem_tiled_wb
[827.446002] [110/133] (194s left) kms_pipe_crc_basic (suspend-read-crc)
[827.558944] Starting subtest: suspend-read-crc
[827.560883] Starting dynamic subtest: pipe-A-eDP-1
[845.754895] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.543s)
[845.767048] Starting dynamic subtest: pipe-B-eDP-1
[863.757659] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.112s)
[863.768965] Starting dynamic subtest: pipe-C-eDP-1
[881.727401] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.046s)
[881.738701] Starting dynamic subtest: pipe-D-eDP-1
[899.732606] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.091s)
[899.735198] Subtest suspend-read-crc: SUCCESS (11.798s)
[900.589300] [111/133] (121s left) gem_exec_balancer (nohangcheck)
[900.865503] Starting subtest: nohangcheck
[901.738196] Subtest nohangcheck: SUCCESS (0.873s)
[901.869814] [112/133] (120s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[901.974617] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[908.546598] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.566s)
[908.673138] [113/133] (113s left) i915_drm_fdinfo (all-busy-check-all)
[908.724260] Starting subtest: all-busy-check-all
[908.728810] Subtest all-busy-check-all: SKIP (0.004s)
[908.813584] [114/133] (113s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[908.941304] Starting subtest: fbc-pr-cursor-mmap-gtt
[908.945819] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[909.028980] [115/133] (113s left) kms_hdr (static-swap)
[909.127075] Starting subtest: static-swap
[909.133673] Subtest static-swap: SKIP (0.000s)
[909.215944] [116/133] (113s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[909.311501] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[913.920740] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.609s)
[915.107714] [117/133] (107s left) gem_exec_whisper (basic-queues-priority-all)
[915.171960] Starting subtest: basic-queues-priority-all
[936.823038] Subtest basic-queues-priority-all: SUCCESS (21.635s)
[937.035787] [118/133] ( 85s left) syncobj_eventfd (invalid-bad-flags)
[937.093785] Starting subtest: invalid-bad-flags
[937.096008] Subtest invalid-bad-flags: SUCCESS (0.000s)
[937.187406] [119/133] ( 85s left) prime_self_import (basic-llseek-bad)
[937.220315] Starting subtest: basic-llseek-bad
[937.243200] Subtest basic-llseek-bad: SUCCESS (0.022s)
[937.334617] [120/133] ( 85s left) syncobj_wait (multi-wait-for-submit-signaled)
[937.389856] Starting subtest: multi-wait-for-submit-signaled
[937.392267] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[937.512638] [121/133] ( 85s left) syncobj_timeline (transfer-timeline-point)
[937.561008] Starting subtest: transfer-timeline-point
[937.563212] Subtest transfer-timeline-point: SUCCESS (0.000s)
[937.680361] [122/133] ( 85s left) perf_pmu (busy-check-all)
[937.720012] Starting subtest: busy-check-all
[937.722384] Starting dynamic subtest: rcs0
[938.274158] Dynamic subtest rcs0: SUCCESS (0.555s)
[938.287674] Starting dynamic subtest: bcs0
[938.828483] Dynamic subtest bcs0: SUCCESS (0.552s)
[938.841340] Starting dynamic subtest: vcs0
[940.494651] Dynamic subtest vcs0: SUCCESS (1.661s)
[940.507779] Starting dynamic subtest: vcs1
[942.542212] Dynamic subtest vcs1: SUCCESS (2.044s)
[942.556224] Starting dynamic subtest: vecs0
[944.525848] Dynamic subtest vecs0: SUCCESS (1.979s)
[944.539446] Starting dynamic subtest: ccs0
[945.081698] Dynamic subtest ccs0: SUCCESS (0.554s)
[945.084261] Subtest busy-check-all: SUCCESS (7.361s)
[945.185057] [123/133] ( 77s left) perf (short-reads)
[945.267150] Starting subtest: short-reads
[945.282271] Subtest short-reads: SUCCESS (0.015s)
[945.329589] [124/133] ( 77s left) perf_pmu (most-busy-check-all)
[945.369599] Starting subtest: most-busy-check-all
[945.371828] Starting dynamic subtest: rcs0
[946.511074] Dynamic subtest rcs0: SUCCESS (1.140s)
[946.526301] Starting dynamic subtest: bcs0
[948.495141] Dynamic subtest bcs0: SUCCESS (1.980s)
[948.508146] Starting dynamic subtest: vcs0
[950.542822] Dynamic subtest vcs0: SUCCESS (2.043s)
[950.556023] Starting dynamic subtest: vcs1
[952.528608] Dynamic subtest vcs1: SUCCESS (1.981s)
[952.542636] Starting dynamic subtest: vecs0
[954.510899] Dynamic subtest vecs0: SUCCESS (1.979s)
[954.524648] Starting dynamic subtest: ccs0
[956.494839] Dynamic subtest ccs0: SUCCESS (1.979s)
[956.498058] Subtest most-busy-check-all: SUCCESS (11.120s)
[956.602420] [125/133] ( 66s left) kms_selftest (drm_dp_mst_helper)
[956.642535] Starting subtest: drm_dp_mst_helper
[956.670869] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[956.771521] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.100s)
[956.783774] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[956.878837] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.106s)
[956.888793] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[956.996731] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.116s)
[956.999004] Subtest drm_dp_mst_helper: SUCCESS (0.357s)
[957.148715] [126/133] ( 65s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[957.247077] Starting subtest: blocking-absolute-wf_vblank-interruptible
[957.249371] Starting dynamic subtest: A-eDP1
[966.005300] Dynamic subtest A-eDP1: SUCCESS (8.718s)
[966.015386] Starting dynamic subtest: B-eDP1
[974.734380] Dynamic subtest B-eDP1: SUCCESS (8.723s)
[974.747148] Starting dynamic subtest: C-eDP1
[983.424005] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[983.434525] Starting dynamic subtest: D-eDP1
[992.210801] Dynamic subtest D-eDP1: SUCCESS (8.734s)
[992.212815] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.909s)
[993.397676] [127/133] ( 29s left) gem_basic (multigpu-create-close)
[993.455192] Starting subtest: multigpu-create-close
[993.459681] Subtest multigpu-create-close: SKIP (0.000s)
[993.580771] [128/133] ( 29s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[993.676930] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[1001.024589] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.347s)
[1001.142069] [129/133] ( 22s left) gem_mmap (bad-size)
[1001.200189] Subtest bad-size: SKIP (0.000s)
[1001.270941] [130/133] ( 21s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[1001.368528] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[1001.372476] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[1001.464780] [131/133] ( 21s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[1001.557487] Starting subtest: fbcpsr-rgb565-draw-pwrite
[1008.501595] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (6.943s)
[1008.607316] [132/133] ( 14s left) kms_draw_crc (draw-method-render)
[1008.708084] Starting subtest: draw-method-render
[1008.710304] Starting dynamic subtest: xrgb8888-untiled
[1008.974049] Dynamic subtest xrgb8888-untiled: SUCCESS (0.267s)
[1008.984848] Starting dynamic subtest: rgb565-untiled
[1009.207514] Dynamic subtest rgb565-untiled: SUCCESS (0.231s)
[1009.218199] Starting dynamic subtest: xrgb2101010-untiled
[1009.440822] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.232s)
[1009.453005] Starting dynamic subtest: xbgr16161616f-untiled
[1009.651769] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[1009.662350] Starting dynamic subtest: xrgb8888-xtiled
[1009.774095] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[1009.786306] Starting dynamic subtest: rgb565-xtiled
[1009.885275] Dynamic subtest rgb565-xtiled: SUCCESS (0.108s)
[1009.897526] Starting dynamic subtest: xrgb2101010-xtiled
[1010.007436] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[1010.017324] Starting dynamic subtest: xbgr16161616f-xtiled
[1010.130094] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s)
[1010.140056] Starting dynamic subtest: xrgb8888-4tiled
[1010.263251] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.132s)
[1010.273085] Starting dynamic subtest: rgb565-4tiled
[1010.374597] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[1010.387061] Starting dynamic subtest: xrgb2101010-4tiled
[1010.485845] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.108s)
[1010.496510] Starting dynamic subtest: xbgr16161616f-4tiled
[1010.608192] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s)
[1010.610082] Subtest draw-method-render: SUCCESS (1.902s)
[1010.752534] [133/133] ( 12s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[1010.855354] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[1010.858726] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[1010.968276] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: perf
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: kms_hdmi_inject
results: parsing output: 5/ for test: gem_mmap
results: parsing output: 6/ for test: kms_plane
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_ctx_persistence
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: i915_selftest
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 17/ for test: kms_getfb
results: parsing output: 18/ for test: kms_plane_scaling
results: parsing output: 19/ for test: gem_exec_whisper
results: parsing output: 20/ for test: i915_hangman
results: parsing output: 21/ for test: i915_pm_rpm
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: kms_cursor_crc
results: parsing output: 24/ for test: kms_rotation_crc
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: core_getversion
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_cursor_edge_walk
results: parsing output: 32/ for test: gem_exec_reloc
results: parsing output: 33/ for test: kms_psr2_sf
results: parsing output: 34/ for test: gem_mmap_wc
results: parsing output: 35/ for test: kms_pm_backlight
results: parsing output: 36/ for test: core_getstats
results: parsing output: 37/ for test: kms_atomic
results: parsing output: 38/ for test: kms_flip
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: gem_eio
results: parsing output: 41/ for test: gem_ctx_persistence
results: parsing output: 42/ for test: gem_exec_reloc
results: parsing output: 43/ for test: kms_psr2_sf
results: parsing output: 44/ for test: kms_plane_cursor
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_chamelium_frames
results: parsing output: 47/ for test: gem_spin_batch
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_vblank
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: perf
results: parsing output: 52/ for test: sw_sync
results: parsing output: 53/ for test: kms_plane
results: parsing output: 54/ for test: prime_busy
results: parsing output: 55/ for test: template
results: parsing output: 56/ for test: gem_exec_schedule
results: parsing output: 57/ for test: kms_chamelium_hpd
results: parsing output: 58/ for test: kms_flip_scaled_crc
results: parsing output: 59/ for test: gem_mmap_wc
results: parsing output: 60/ for test: api_intel_allocator
results: parsing output: 61/ for test: gem_cs_tlb
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_content_protection
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: gem_pxp
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: gen7_exec_parse
results: parsing output: 70/ for test: gem_exec_flush
results: parsing output: 71/ for test: gem_exec_fence
results: parsing output: 72/ for test: kms_cursor_crc
results: parsing output: 73/ for test: gen3_render_tiledy_blits
results: parsing output: 74/ for test: gem_exec_schedule
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_wait
results: parsing output: 78/ for test: gem_ctx_shared
results: parsing output: 79/ for test: kms_flip_scaled_crc
results: parsing output: 80/ for test: gem_pxp
results: parsing output: 81/ for test: kms_color
results: parsing output: 82/ for test: kms_addfb_basic
results: parsing output: 83/ for test: syncobj_basic
results: parsing output: 84/ for test: gem_exec_balancer
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_chamelium_audio
results: parsing output: 88/ for test: kms_addfb_basic
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_lease
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_caching
results: parsing output: 95/ for test: kms_ccs
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: gem_mmap_gtt
results: parsing output: 98/ for test: gem_mmap_offset
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_pm_rpm
results: parsing output: 101/ for test: kms_flip
results: parsing output: 102/ for test: perf
results: parsing output: 103/ for test: gem_mmap_gtt
results: parsing output: 104/ for test: kms_cursor_legacy
results: parsing output: 105/ for test: prime_udl
results: parsing output: 106/ for test: kms_cursor_legacy
results: parsing output: 107/ for test: kms_cursor_legacy
results: parsing output: 108/ for test: gem_tiled_wb
results: parsing output: 109/ for test: kms_pipe_crc_basic
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: i915_drm_fdinfo
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: kms_hdr
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_exec_whisper
results: parsing output: 117/ for test: syncobj_eventfd
results: parsing output: 118/ for test: prime_self_import
results: parsing output: 119/ for test: syncobj_wait
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: perf_pmu
results: parsing output: 122/ for test: perf
results: parsing output: 123/ for test: perf_pmu
results: parsing output: 124/ for test: kms_selftest
results: parsing output: 125/ for test: kms_flip
results: parsing output: 126/ for test: gem_basic
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_mmap
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_draw_crc
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.