[58.008228] Initializing watchdogs
[58.008273]   /dev/watchdog0
[58.022496] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.027063] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.029436] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.032888] [001/132] (960s left) kms_flip (2x-absolute-wf_vblank)
[58.155103] Starting subtest: 2x-absolute-wf_vblank
[58.163367] Subtest 2x-absolute-wf_vblank: SKIP (0.010s)
[58.251797] [002/132] (960s left) perf (gen12-group-exclusive-stream-sample-oa)
[58.384892] Starting subtest: gen12-group-exclusive-stream-sample-oa
[58.404300] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.019s)
[58.446611] [003/132] (960s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[58.564309] Starting subtest: flip-vs-cursor-legacy
[65.752545] Subtest flip-vs-cursor-legacy: SUCCESS (7.172s)
[66.549605] [004/132] (952s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[66.922363] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[67.771114] [005/132] (950s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[67.909720] Starting subtest: fbcpsr-stridechange
[74.372289] Subtest fbcpsr-stridechange: SUCCESS (6.461s)
[74.502979] [006/132] (944s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[74.605286] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[81.805556] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.201s)
[81.928423] [007/132] (936s left) gem_userptr_blits (create-destroy-unsync)
[81.997576] Subtest create-destroy-unsync: SKIP (0.000s)
[82.127372] [008/132] (936s left) api_intel_allocator (reopen-fork)
[82.173777] Starting subtest: reopen-fork
[85.647084] Subtest reopen-fork: SUCCESS (3.473s)
[85.726901] [009/132] (933s left) kms_addfb_basic (basic)
[85.788383] Starting subtest: basic
[85.791285] Subtest basic: SUCCESS (0.000s)
[85.921191] [010/132] (932s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[85.976250] Starting subtest: multi-wait-all-for-submit-unsubmitted
[86.082601] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[86.155094] [011/132] (932s left) gem_userptr_blits (userfault)
[86.221143] Starting subtest: userfault
[86.223594] Subtest userfault: SUCCESS (0.008s)
[86.341232] [012/132] (932s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[86.439314] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[86.451064] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[86.550921] [013/132] (932s left) vgem_basic (create)
[86.617336] Starting subtest: create
[86.619264] Subtest create: SUCCESS (0.000s)
[86.683986] [FACT vgem_basic (create)] new: kernel.kmod_is_loaded.vgem: true
[86.687064] [014/132] (932s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[86.753591] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[86.811978] [015/132] (932s left) kms_plane_alpha_blend (coverage-7efc)
[86.894177] Starting subtest: coverage-7efc
[87.181157] Starting dynamic subtest: pipe-A-eDP-1
[87.780790] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.597s)
[87.807708] Starting dynamic subtest: pipe-D-eDP-1
[89.101500] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.299s)
[89.103946] Subtest coverage-7efc: SUCCESS (2.207s)
[89.941203] [016/132] (929s left) dmabuf_sync_file (export-before-signal)
[89.989468] Starting subtest: export-before-signal
[89.991819] Subtest export-before-signal: SUCCESS (0.001s)
[90.047061] [017/132] (929s left) gem_userptr_blits (coherency-sync)
[90.111306] Starting subtest: coherency-sync
[90.445686] Subtest coherency-sync: SKIP (0.295s)
[90.531820] [018/132] (928s left) kms_vblank (ts-continuation-dpms-rpm)
[90.687352] Starting subtest: ts-continuation-dpms-rpm
[90.689407] Starting dynamic subtest: pipe-A-eDP-1
[93.243997] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.557s)
[93.256768] Starting dynamic subtest: pipe-D-eDP-1
[95.474328] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.227s)
[95.476689] Subtest ts-continuation-dpms-rpm: SUCCESS (4.787s)
[96.275231] [019/132] (922s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[96.367376] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[96.371301] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[96.446312] [020/132] (922s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[96.547126] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[96.551308] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[96.647938] [021/132] (922s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[96.737309] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[96.739590] Starting dynamic subtest: A-eDP1
[100.396325] Dynamic subtest A-eDP1: SUCCESS (3.655s)
[100.405423] Starting dynamic subtest: B-eDP1
[103.592458] Dynamic subtest B-eDP1: SUCCESS (3.198s)
[103.601411] Starting dynamic subtest: C-eDP1
[106.696570] Dynamic subtest C-eDP1: SUCCESS (3.102s)
[106.706680] Starting dynamic subtest: D-eDP1
[109.816788] Dynamic subtest D-eDP1: SUCCESS (3.119s)
[109.819166] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.080s)
[110.617254] [022/132] (908s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[110.713428] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[110.717877] Starting dynamic subtest: pipe-A-eDP-1
[110.740328] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[110.747879] Starting dynamic subtest: pipe-B-eDP-1
[110.769226] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[110.775777] Starting dynamic subtest: pipe-C-eDP-1
[110.796399] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[110.803318] Starting dynamic subtest: pipe-D-eDP-1
[110.824818] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[110.829249] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[112.891831] [023/132] (906s left) gem_ccs (block-multicopy-compressed)
[112.945859] Starting subtest: block-multicopy-compressed
[112.950054] Subtest block-multicopy-compressed: SKIP (0.000s)
[113.044922] [024/132] (906s left) gem_exec_create (madvise)
[113.105405] Starting subtest: madvise
[113.107752] Starting dynamic subtest: smem
[135.000468] Dynamic subtest smem: SUCCESS (21.892s)
[135.003193] Subtest madvise: SUCCESS (21.893s)
[135.858020] [025/132] (883s left) perf (invalid-open-flags)
[135.984091] Starting subtest: invalid-open-flags
[135.986480] Subtest invalid-open-flags: SUCCESS (0.000s)
[136.030775] [026/132] (883s left) sysfs_timeslice_duration (duration)
[136.091445] Starting subtest: duration
[136.093658] Starting dynamic subtest: vecs0
[149.812747] Dynamic subtest vecs0: SUCCESS (13.718s)
[149.823186] Starting dynamic subtest: ccs0
[162.966650] Dynamic subtest ccs0: SUCCESS (13.137s)
[162.975600] Starting dynamic subtest: vcs0
[176.820266] Dynamic subtest vcs0: SUCCESS (13.866s)
[176.829331] Starting dynamic subtest: bcs0
[189.979559] Dynamic subtest bcs0: SUCCESS (13.144s)
[189.987797] Starting dynamic subtest: rcs0
[203.099361] Dynamic subtest rcs0: SUCCESS (13.132s)
[203.114808] Starting dynamic subtest: vcs1
[217.843607] Dynamic subtest vcs1: SUCCESS (14.740s)
[217.846660] Subtest duration: SUCCESS (81.749s)
[217.918717] [027/132] (801s left) gem_eio (wait-10ms)
[218.118341] Starting subtest: wait-10ms
[218.410122] Subtest wait-10ms: SUCCESS (0.292s)
[218.539300] [028/132] (801s left) kms_pm_rpm (system-suspend-idle)
[220.047923] Starting subtest: system-suspend-idle
[242.875074] Subtest system-suspend-idle: SUCCESS (7.788s)
[243.266740] [029/132] (776s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[243.389068] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[243.393150] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[243.500862] [030/132] (776s left) gem_exec_fence (submit)
[243.684055] Starting subtest: submit
[243.688951] Subtest submit: SKIP (0.000s)
[244.950857] [031/132] (774s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[245.044429] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[245.070978] Starting dynamic subtest: pipe-A-plane-0
[245.496631] Dynamic subtest pipe-A-plane-0: SUCCESS (0.422s)
[245.506192] Starting dynamic subtest: pipe-A-plane-3
[245.775521] Dynamic subtest pipe-A-plane-3: SUCCESS (0.276s)
[246.863940] Starting dynamic subtest: pipe-B-plane-0
[247.314096] Dynamic subtest pipe-B-plane-0: SUCCESS (0.455s)
[247.322480] Starting dynamic subtest: pipe-B-plane-3
[247.582204] Dynamic subtest pipe-B-plane-3: SUCCESS (0.266s)
[247.893067] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.832s)
[248.743225] [032/132] (771s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[248.850159] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[248.854002] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[248.946554] [033/132] (770s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[249.066804] Starting subtest: basic-flip-before-cursor-legacy
[249.988461] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.916s)
[250.784232] [034/132] (769s left) kms_flip (flip-vs-fences)
[250.888823] Starting subtest: flip-vs-fences
[250.892997] Subtest flip-vs-fences: SKIP (0.000s)
[250.977944] [035/132] (768s left) sriov_basic (bind-unbind-vf)
[251.023743] Starting subtest: bind-unbind-vf
[251.026004] Starting dynamic subtest: vf-1
[251.101952] Dynamic subtest vf-1: FAIL (0.028s)
[251.110363] Starting dynamic subtest: vf-2
[251.181008] Dynamic subtest vf-2: FAIL (0.004s)
[251.191087] Starting dynamic subtest: vf-3
[251.263549] Dynamic subtest vf-3: FAIL (0.004s)
[251.273232] Starting dynamic subtest: vf-4
[251.346017] Dynamic subtest vf-4: FAIL (0.004s)
[251.356571] Starting dynamic subtest: vf-5
[251.428632] Dynamic subtest vf-5: FAIL (0.004s)
[251.438840] Starting dynamic subtest: vf-6
[251.511677] Dynamic subtest vf-6: FAIL (0.004s)
[251.521090] Starting dynamic subtest: vf-7
[251.594695] Dynamic subtest vf-7: FAIL (0.004s)
[251.605058] Starting dynamic subtest: vf-random
[251.674835] Dynamic subtest vf-random: FAIL (0.004s)
[251.685092] Starting dynamic subtest: vf-last
[251.751154] Dynamic subtest vf-last: FAIL (0.503s)
[251.752907] Subtest bind-unbind-vf: FAIL (0.562s)
[251.788472] [036/132] (768s left) gem_ctx_shared (q-in-order)
[251.852521] Starting subtest: Q-in-order
[251.854503] Starting dynamic subtest: rcs0
[251.952415] Dynamic subtest rcs0: SUCCESS (0.099s)
[251.968003] Starting dynamic subtest: vcs0
[252.034815] Dynamic subtest vcs0: SUCCESS (0.081s)
[252.050307] Starting dynamic subtest: vcs1
[252.109566] Dynamic subtest vcs1: SUCCESS (0.074s)
[252.125191] Starting dynamic subtest: vecs0
[252.190434] Dynamic subtest vecs0: SUCCESS (0.080s)
[252.205205] Starting dynamic subtest: ccs0
[252.263410] Dynamic subtest ccs0: SUCCESS (0.072s)
[252.265458] Subtest Q-in-order: SUCCESS (0.410s)
[253.984870] [037/132] (765s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[254.057003] Starting subtest: rel-constants-invalid-rel-gen5
[254.059178] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[254.159186] [038/132] (765s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[254.268862] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[254.272933] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[254.378349] [039/132] (765s left) kms_big_fb (linear-64bpp-rotate-270)
[254.476315] Starting subtest: linear-64bpp-rotate-270
[254.496005] Subtest linear-64bpp-rotate-270: SKIP (0.019s)
[254.610859] [040/132] (765s left) perf_pmu (init-wait)
[254.657101] Starting subtest: init-wait
[254.659335] Starting dynamic subtest: rcs0
[254.661489] Dynamic subtest rcs0: SUCCESS (0.002s)
[254.671048] Starting dynamic subtest: bcs0
[254.673152] Dynamic subtest bcs0: SUCCESS (0.000s)
[254.681685] Starting dynamic subtest: vcs0
[254.683793] Dynamic subtest vcs0: SUCCESS (0.000s)
[254.691974] Starting dynamic subtest: vcs1
[254.694045] Dynamic subtest vcs1: SUCCESS (0.001s)
[254.702159] Starting dynamic subtest: vecs0
[254.704306] Dynamic subtest vecs0: SUCCESS (0.001s)
[254.712297] Starting dynamic subtest: ccs0
[254.714102] Dynamic subtest ccs0: SUCCESS (0.000s)
[254.715937] Subtest init-wait: SUCCESS (0.006s)
[254.768511] [041/132] (765s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[254.858476] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[254.862624] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[254.962991] [042/132] (765s left) kms_plane_cursor (primary)
[255.053341] Starting subtest: primary
[255.056891] Starting dynamic subtest: pipe-A-eDP-1-size-64
[257.610787] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.551s)
[257.634587] Starting dynamic subtest: pipe-A-eDP-1-size-128
[260.170904] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.539s)
[260.194544] Starting dynamic subtest: pipe-A-eDP-1-size-256
[262.730603] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.538s)
[263.044929] Starting dynamic subtest: pipe-B-eDP-1-size-64
[266.318110] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.279s)
[266.342326] Starting dynamic subtest: pipe-B-eDP-1-size-128
[268.909696] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.571s)
[268.933722] Starting dynamic subtest: pipe-B-eDP-1-size-256
[271.534800] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.606s)
[271.860302] Starting dynamic subtest: pipe-C-eDP-1-size-64
[274.821353] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.971s)
[274.847987] Starting dynamic subtest: pipe-C-eDP-1-size-128
[277.056509] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.214s)
[277.082088] Starting dynamic subtest: pipe-C-eDP-1-size-256
[279.257299] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.181s)
[279.578777] Starting dynamic subtest: pipe-D-eDP-1-size-64
[282.512011] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.942s)
[282.538000] Starting dynamic subtest: pipe-D-eDP-1-size-128
[284.724199] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.191s)
[284.751005] Starting dynamic subtest: pipe-D-eDP-1-size-256
[286.970175] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.225s)
[287.278751] Subtest primary: SUCCESS (32.194s)
[288.151145] [043/132] (732s left) gem_ctx_persistence (legacy-engines-mixed-process)
[288.224661] Starting subtest: legacy-engines-mixed-process
[288.226954] Starting dynamic subtest: render
[289.876696] Dynamic subtest render: SUCCESS (1.651s)
[289.888177] Starting dynamic subtest: bsd1
[291.864787] Dynamic subtest bsd1: SUCCESS (1.985s)
[291.876632] Starting dynamic subtest: bsd2
[293.902580] Dynamic subtest bsd2: SUCCESS (2.034s)
[293.913844] Starting dynamic subtest: blt
[295.896195] Dynamic subtest blt: SUCCESS (1.990s)
[295.907913] Starting dynamic subtest: vebox
[297.891853] Dynamic subtest vebox: SUCCESS (1.993s)
[297.894282] Subtest legacy-engines-mixed-process: SUCCESS (9.663s)
[297.992561] [044/132] (722s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[298.114429] Starting subtest: cursorA-vs-flipB-varying-size
[298.119002] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[298.257498] [045/132] (722s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[298.318356] Starting subtest: invalid-multi-wait-all-unsubmitted
[298.320620] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[298.411585] [046/132] (721s left) gem_exec_balancer (busy)
[298.489948] Starting subtest: busy
[299.851273] Subtest busy: SUCCESS (1.359s)
[300.144218] [047/132] (720s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[300.256309] Starting subtest: primary-y-tiled-reflect-x-180
[300.326755] Subtest primary-y-tiled-reflect-x-180: SKIP (0.071s)
[300.441953] [048/132] (719s left) i915_drm_fdinfo (virtual-busy-all)
[300.485349] Starting subtest: virtual-busy-all
[300.489626] Subtest virtual-busy-all: SKIP (0.000s)
[300.551090] [049/132] (719s left) kms_psr (psr2-sprite-mmap-cpu)
[300.640152] Starting subtest: psr2-sprite-mmap-cpu
[300.642325] Starting dynamic subtest: eDP-1
[302.333630] Dynamic subtest eDP-1: SUCCESS (1.692s)
[302.336006] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.693s)
[303.207623] [050/132] (717s left) gem_ctx_persistence (engines-hang)
[303.262274] Starting subtest: engines-hang
[303.264552] Starting dynamic subtest: rcs0
[303.358445] Dynamic subtest rcs0: SUCCESS (0.099s)
[303.368921] Starting dynamic subtest: bcs0
[303.469394] Dynamic subtest bcs0: SUCCESS (0.109s)
[303.479804] Starting dynamic subtest: vcs0
[303.543461] Dynamic subtest vcs0: SUCCESS (0.072s)
[303.553996] Starting dynamic subtest: vcs1
[303.654614] Dynamic subtest vcs1: SUCCESS (0.109s)
[303.666177] Starting dynamic subtest: vecs0
[303.753790] Dynamic subtest vecs0: SUCCESS (0.097s)
[303.766088] Starting dynamic subtest: ccs0
[303.858468] Dynamic subtest ccs0: SUCCESS (0.102s)
[303.860985] Subtest engines-hang: SUCCESS (0.600s)
[303.938825] [051/132] (716s left) syncobj_wait (invalid-signal-bad-pad)
[304.001961] Starting subtest: invalid-signal-bad-pad
[304.004274] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[304.096440] [052/132] (716s left) syncobj_timeline (wait-any-interrupted)
[304.144894] Starting subtest: wait-any-interrupted
[304.248753] Subtest wait-any-interrupted: SUCCESS (0.103s)
[304.324846] [053/132] (716s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[304.414145] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[304.418491] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[304.488038] [054/132] (716s left) gem_ctx_exec (basic-close-race)
[304.549360] Starting subtest: basic-close-race
[310.648577] Subtest basic-close-race: SUCCESS (6.045s)
[310.736121] [055/132] (709s left) gem_flink_basic (flink-lifetime)
[310.792917] Starting subtest: flink-lifetime
[310.795151] Subtest flink-lifetime: SUCCESS (0.002s)
[310.903005] [056/132] (709s left) gen9_exec_parse (batch-without-end)
[310.967458] Subtest batch-without-end: SKIP (0.000s)
[311.029625] [057/132] (709s left) gem_exec_whisper (basic-sync)
[311.092932] Starting subtest: basic-sync
[321.415823] Subtest basic-sync: SUCCESS (10.315s)
[321.600162] [058/132] (699s left) i915_pm_rpm (reg-read-ioctl)
[323.122132] Starting subtest: reg-read-ioctl
[325.402731] Subtest reg-read-ioctl: SUCCESS (2.283s)
[325.777200] [059/132] (695s left) kms_async_flips (test-cursor)
[325.871394] Starting subtest: test-cursor
[326.251107] Starting dynamic subtest: pipe-A-eDP-1
[327.057016] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.805s)
[327.437496] Starting dynamic subtest: pipe-B-eDP-1
[328.193848] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.765s)
[328.586522] Starting dynamic subtest: pipe-C-eDP-1
[329.345735] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.768s)
[329.713898] Starting dynamic subtest: pipe-D-eDP-1
[330.462816] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.754s)
[330.465175] Subtest test-cursor: SUCCESS (4.590s)
[331.651657] [060/132] (689s left) gem_exec_fence (concurrent)
[331.852307] Starting subtest: concurrent
[331.856669] Subtest concurrent: SKIP (0.000s)
[333.982873] [061/132] (686s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[334.064121] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[334.068688] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[335.179351] [062/132] (685s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[335.282683] Starting subtest: fbc-rgb101010-draw-blt
[339.870277] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.590s)
[341.086156] [063/132] (679s left) syncobj_timeline (invalid-query-one-illegal-handle)
[341.143452] Starting subtest: invalid-query-one-illegal-handle
[341.145601] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[341.229916] [064/132] (679s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[341.335667] Starting subtest: primary-4-tiled-reflect-x-0
[348.385800] Subtest primary-4-tiled-reflect-x-0: SUCCESS (7.056s)
[349.603804] [065/132] (671s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[349.724165] Starting subtest: 4-tiled-16bpp-rotate-270
[349.743667] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[349.848373] [066/132] (671s left) perf_pmu (interrupts)
[349.891335] Starting subtest: interrupts
[350.974634] Subtest interrupts: SUCCESS (1.085s)
[351.081838] [067/132] (670s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[351.187121] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[357.747606] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.566s)
[357.871346] [068/132] (663s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[357.967021] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[357.969358] Starting dynamic subtest: pipe-A
[357.988881] Dynamic subtest pipe-A: SKIP (0.017s)
[357.999330] Starting dynamic subtest: pipe-B
[358.019348] Dynamic subtest pipe-B: SKIP (0.030s)
[358.029147] Starting dynamic subtest: pipe-C
[358.049480] Dynamic subtest pipe-C: SKIP (0.030s)
[358.058834] Starting dynamic subtest: pipe-D
[358.081446] Dynamic subtest pipe-D: SKIP (0.030s)
[358.085242] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.111s)
[358.188334] [069/132] (663s left) kms_lease (invalid-create-leases)
[358.287248] Starting subtest: invalid-create-leases
[358.291831] Subtest invalid-create-leases: SUCCESS (0.006s)
[358.398028] [070/132] (662s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[358.485622] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[358.489706] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[358.553377] [071/132] (662s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[358.635792] Starting subtest: fbc-psr2-sprite-plane-onoff
[358.638072] Starting dynamic subtest: eDP-1
[358.642081] Dynamic subtest eDP-1: SKIP (0.000s)
[358.646135] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[358.711911] [072/132] (662s left) kms_color (deep-color)
[358.800766] Starting subtest: deep-color
[359.898749] Starting dynamic subtest: pipe-A-eDP-1-gamma
[360.642645] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.742s)
[360.652003] Starting dynamic subtest: pipe-A-eDP-1-degamma
[362.085942] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.444s)
[362.096022] Starting dynamic subtest: pipe-A-eDP-1-ctm
[363.497953] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.410s)
[364.288988] Starting dynamic subtest: pipe-B-eDP-1-gamma
[364.983515] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.703s)
[364.992712] Starting dynamic subtest: pipe-B-eDP-1-degamma
[366.473533] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.486s)
[366.483518] Starting dynamic subtest: pipe-B-eDP-1-ctm
[367.878884] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.404s)
[368.661974] Starting dynamic subtest: pipe-C-eDP-1-gamma
[369.334032] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.680s)
[369.344159] Starting dynamic subtest: pipe-C-eDP-1-degamma
[370.719504] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.384s)
[370.730270] Starting dynamic subtest: pipe-C-eDP-1-ctm
[372.126931] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.405s)
[372.951478] Starting dynamic subtest: pipe-D-eDP-1-gamma
[373.660291] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.718s)
[373.672282] Starting dynamic subtest: pipe-D-eDP-1-degamma
[375.114097] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.453s)
[375.124758] Starting dynamic subtest: pipe-D-eDP-1-ctm
[376.541710] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.427s)
[376.543983] Subtest deep-color: SUCCESS (17.745s)
[377.392705] [073/132] (644s left) syncobj_wait (wait-any-interrupted)
[377.457985] Starting subtest: wait-any-interrupted
[377.561990] Subtest wait-any-interrupted: SUCCESS (0.102s)
[377.636334] [074/132] (643s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[377.747736] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[377.760097] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[377.839594] [075/132] (643s left) syncobj_wait (invalid-single-wait-unsubmitted)
[377.896910] Starting subtest: invalid-single-wait-unsubmitted
[377.899219] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[378.011741] [076/132] (643s left) kms_flip_event_leak (basic)
[378.114029] Starting subtest: basic
[378.115835] Starting dynamic subtest: pipe-A-eDP-1
[378.534585] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.421s)
[378.543737] Starting dynamic subtest: pipe-B-eDP-1
[379.625294] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.087s)
[379.635602] Starting dynamic subtest: pipe-C-eDP-1
[380.731094] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[380.741365] Starting dynamic subtest: pipe-D-eDP-1
[381.855220] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[381.857674] Subtest basic: SUCCESS (3.743s)
[382.731212] [077/132] (638s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[382.827779] Starting subtest: y-tiled-64bpp-rotate-270
[382.832097] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[382.917962] [078/132] (638s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[383.026348] Starting subtest: yf-tiled-16bpp-rotate-90
[383.030700] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[383.125851] [079/132] (638s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[383.186709] Starting subtest: invalid-smem-bo-on-discrete
[383.191080] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[383.319357] [080/132] (638s left) kms_hdr (static-toggle)
[383.423141] Starting subtest: static-toggle
[383.429564] Subtest static-toggle: SKIP (0.000s)
[383.501283] [081/132] (638s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[383.591082] Starting subtest: fbc-psr2-primary-mmap-cpu
[383.593405] Starting dynamic subtest: eDP-1
[383.597630] Dynamic subtest eDP-1: SKIP (0.000s)
[383.601799] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[383.665230] [082/132] (638s left) gem_pxp (protected-raw-src-copy-not-readible)
[383.783818] Starting subtest: protected-raw-src-copy-not-readible
[383.842335] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.059s)
[383.971431] [083/132] (637s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[384.064232] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[385.132322] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.068s)
[385.266491] [084/132] (636s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[385.366958] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[385.369057] Starting dynamic subtest: pipe-A-default-mode
[386.489938] Dynamic subtest pipe-A-default-mode: SKIP (1.119s)
[386.494388] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.121s)
[386.640151] [085/132] (635s left) gem_exec_params (rel-constants-invalid)
[386.699295] Starting subtest: rel-constants-invalid
[386.701628] Subtest rel-constants-invalid: SUCCESS (0.000s)
[386.795053] [086/132] (635s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[386.887041] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[386.891567] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[387.981798] [087/132] (634s left) gem_exec_fence (syncobj-wait)
[388.429595] Starting subtest: syncobj-wait
[389.903549] Subtest syncobj-wait: SUCCESS (1.475s)
[391.868215] [088/132] (630s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[391.930099] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[392.032296] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.101s)
[392.111282] [089/132] (630s left) kms_cursor_crc (cursor-onscreen-128x42)
[392.363188] Starting subtest: cursor-onscreen-128x42
[392.408498] Subtest cursor-onscreen-128x42: SKIP (0.041s)
[392.610249] [090/132] (629s left) kms_cursor_legacy (forked-move)
[392.732406] Starting subtest: forked-move
[392.734321] Starting dynamic subtest: pipe-A
[398.259127] Dynamic subtest pipe-A: SUCCESS (5.470s)
[398.270459] Starting dynamic subtest: pipe-B
[403.722658] Dynamic subtest pipe-B: SUCCESS (5.462s)
[403.734209] Starting dynamic subtest: pipe-C
[409.194080] Dynamic subtest pipe-C: SUCCESS (5.468s)
[409.204920] Starting dynamic subtest: pipe-D
[414.661409] Dynamic subtest pipe-D: SUCCESS (5.463s)
[414.677275] Starting dynamic subtest: all-pipes
[420.169584] Dynamic subtest all-pipes: SUCCESS (5.503s)
[420.171933] Subtest forked-move: SUCCESS (27.378s)
[420.268858] [091/132] (601s left) kms_psr (pr-primary-page-flip)
[420.383486] Starting subtest: pr-primary-page-flip
[420.387962] Subtest pr-primary-page-flip: SKIP (0.000s)
[420.462564] [092/132] (601s left) kms_rotation_crc (cursor-rotation-180)
[420.559149] Starting subtest: cursor-rotation-180
[422.069724] Subtest cursor-rotation-180: SUCCESS (1.510s)
[423.254293] [093/132] (599s left) kms_cursor_crc (cursor-sliding-128x128)
[423.472215] Starting subtest: cursor-sliding-128x128
[423.477055] Starting dynamic subtest: pipe-A-eDP-1
[426.723947] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.246s)
[426.752463] Starting dynamic subtest: pipe-D-eDP-1
[430.658887] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.911s)
[430.661301] Subtest cursor-sliding-128x128: SUCCESS (7.183s)
[431.522299] [094/132] (590s left) kms_frontbuffer_tracking (psr-1p-rte)
[431.644572] Starting subtest: psr-1p-rte
[437.036031] Subtest psr-1p-rte: SUCCESS (5.395s)
[437.184601] [095/132] (585s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[437.284013] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf
[437.286348] Starting dynamic subtest: pipe-A-eDP-1
[437.369515] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[437.379641] Starting dynamic subtest: pipe-B-eDP-1
[438.532565] Dynamic subtest pipe-B-eDP-1: SKIP (1.159s)
[438.536959] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.250s)
[439.730675] [096/132] (582s left) kms_pm_dc (dc9-dpms)
[440.839299] Starting subtest: dc9-dpms
[442.252581] Subtest dc9-dpms: SUCCESS (1.414s)
[442.325015] [097/132] (580s left) gem_exec_schedule (smoketest)
[442.513122] Starting subtest: smoketest
[442.515598] Starting dynamic subtest: rcs0
[448.113339] Dynamic subtest rcs0: SUCCESS (5.490s)
[448.125149] Starting dynamic subtest: bcs0
[453.623915] Dynamic subtest bcs0: SUCCESS (5.515s)
[453.640659] Starting dynamic subtest: vcs0
[459.127063] Dynamic subtest vcs0: SUCCESS (5.498s)
[459.138749] Starting dynamic subtest: vcs1
[464.630751] Dynamic subtest vcs1: SUCCESS (5.505s)
[464.642022] Starting dynamic subtest: vecs0
[470.136257] Dynamic subtest vecs0: SUCCESS (5.502s)
[470.148577] Starting dynamic subtest: ccs0
[475.645476] Dynamic subtest ccs0: SUCCESS (5.509s)
[475.647597] Subtest smoketest: SUCCESS (33.029s)
[475.744904] [098/132] (546s left) gem_eio (reset-stress)
[475.984159] Starting subtest: reset-stress
[475.986093] Starting dynamic subtest: render
[481.154651] Dynamic subtest render: SUCCESS (5.170s)
[481.170951] Starting dynamic subtest: bsd1
[499.446477] Dynamic subtest bsd1: SUCCESS (18.293s)
[499.463364] Starting dynamic subtest: bsd2
[517.420714] Dynamic subtest bsd2: SUCCESS (17.974s)
[517.430362] Starting dynamic subtest: blt
[517.438646] Dynamic subtest blt: SKIP (0.000s)
[517.448484] Starting dynamic subtest: vebox
[535.476144] Dynamic subtest vebox: SUCCESS (18.054s)
[535.478074] Subtest reset-stress: SUCCESS (59.495s)
[536.888890] [099/132] (485s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[536.958804] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[536.961424] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[537.066412] [100/132] (485s left) template (b)
[537.123754] Starting subtest: B
[537.126089] Subtest B: SUCCESS (0.000s)
[537.216123] [101/132] (485s left) gem_mmap_gtt (basic-write)
[537.280924] Subtest basic-write: SKIP (0.000s)
[537.346011] [102/132] (485s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[537.412059] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[537.487142] [103/132] (485s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[537.585627] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[537.589749] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[537.703994] [104/132] (485s left) gem_exec_schedule (pi-userfault)
[537.885854] Starting subtest: pi-userfault
[537.887824] Starting dynamic subtest: rcs0
[537.895829] Dynamic subtest rcs0: SUCCESS (0.012s)
[537.905924] Starting dynamic subtest: bcs0
[537.908210] Dynamic subtest bcs0: SUCCESS (0.010s)
[537.917950] Starting dynamic subtest: vcs0
[537.920168] Dynamic subtest vcs0: SUCCESS (0.009s)
[537.929873] Starting dynamic subtest: vcs1
[537.931792] Dynamic subtest vcs1: SUCCESS (0.009s)
[537.941981] Starting dynamic subtest: vecs0
[537.944152] Dynamic subtest vecs0: SUCCESS (0.009s)
[537.953736] Starting dynamic subtest: ccs0
[537.955663] Dynamic subtest ccs0: SUCCESS (0.010s)
[537.957662] Subtest pi-userfault: SUCCESS (0.063s)
[538.145400] [105/132] (484s left) kms_content_protection (type1)
[538.297673] Starting subtest: type1
[538.302194] Subtest type1: SKIP (0.000s)
[538.419143] [106/132] (484s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[538.513620] Starting subtest: psr2-overlay-plane-update-continuous-sf
[538.515855] Starting dynamic subtest: pipe-A-eDP-1
[539.644663] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.136s)
[539.656413] Starting dynamic subtest: pipe-B-eDP-1
[541.457941] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.810s)
[541.460077] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.949s)
[542.318213] [107/132] (480s left) gem_pxp (create-regular-context-1)
[542.431691] Starting subtest: create-regular-context-1
[542.434104] Subtest create-regular-context-1: SUCCESS (0.002s)
[542.535153] [108/132] (480s left) kms_universal_plane (universal-plane-pageflip-windowed)
[542.635923] Starting subtest: universal-plane-pageflip-windowed
[542.638185] Starting dynamic subtest: pipe-A-eDP-1
[543.236533] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.599s)
[543.247407] Starting dynamic subtest: pipe-B-eDP-1
[544.486756] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.249s)
[544.497097] Starting dynamic subtest: pipe-C-eDP-1
[545.701285] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.211s)
[545.713655] Starting dynamic subtest: pipe-D-eDP-1
[546.939173] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.238s)
[546.941458] Subtest universal-plane-pageflip-windowed: SUCCESS (4.304s)
[547.781345] [109/132] (475s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[547.877126] Starting subtest: psr-rgb101010-draw-pwrite
[555.599755] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.725s)
[555.716733] [110/132] (467s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[555.822080] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[555.826090] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[555.917326] [111/132] (467s left) kms_feature_discovery (display-3x)
[556.006785] Starting subtest: display-3x
[556.011645] Subtest display-3x: SKIP (0.000s)
[556.083833] [112/132] (467s left) gem_exec_whisper (basic-contexts-forked-all)
[556.147884] Starting subtest: basic-contexts-forked-all
[578.847206] Subtest basic-contexts-forked-all: SUCCESS (22.692s)
[579.026208] [113/132] (444s left) i915_hangman (gt-engine-error)
[579.099326] Starting subtest: gt-engine-error
[579.101457] Starting dynamic subtest: rcs0
[580.842511] Dynamic subtest rcs0: SUCCESS (1.741s)
[580.854894] Starting dynamic subtest: bcs0
[582.824931] Dynamic subtest bcs0: SUCCESS (1.980s)
[582.837628] Starting dynamic subtest: vcs0
[584.810358] Dynamic subtest vcs0: SUCCESS (1.981s)
[584.823660] Starting dynamic subtest: vcs1
[586.857738] Dynamic subtest vcs1: SUCCESS (2.045s)
[586.869820] Starting dynamic subtest: vecs0
[588.777782] Dynamic subtest vecs0: SUCCESS (1.917s)
[588.788725] Starting dynamic subtest: ccs0
[590.826221] Dynamic subtest ccs0: SUCCESS (2.045s)
[590.828974] Subtest gt-engine-error: SUCCESS (11.726s)
[590.907265] [114/132] (432s left) syncobj_timeline (multi-wait-submitted-signaled)
[590.963284] Starting subtest: multi-wait-submitted-signaled
[590.966019] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[591.075325] [115/132] (432s left) kms_hdr (invalid-metadata-sizes)
[591.176155] Starting subtest: invalid-metadata-sizes
[591.182492] Subtest invalid-metadata-sizes: SKIP (0.000s)
[591.262012] [116/132] (432s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[591.353491] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[591.358250] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[591.439432] [117/132] (431s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[591.504996] Starting subtest: basic-batch-kernel-default-cmd
[591.508965] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[591.685391] [118/132] (431s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[591.765948] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[591.767950] Starting dynamic subtest: pipe-A-eDP-1
[593.934013] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.168s)
[593.943657] Starting dynamic subtest: pipe-B-eDP-1
[596.175381] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.240s)
[596.186846] Starting dynamic subtest: pipe-C-eDP-1
[598.198473] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.019s)
[598.209163] Starting dynamic subtest: pipe-D-eDP-1
[600.264763] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.063s)
[600.267003] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.498s)
[601.488806] [119/132] (421s left) kms_chamelium_color (gamma)
[601.577770] Subtest gamma: SKIP (0.000s)
[601.621649] [120/132] (421s left) kms_addfb_basic (invalid-get-prop)
[601.680470] Starting subtest: invalid-get-prop
[601.682769] Subtest invalid-get-prop: SUCCESS (0.000s)
[601.841265] [121/132] (421s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[601.941213] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[601.943106] Starting dynamic subtest: pipe-A-eDP-1
[634.436539] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.843s)
[634.450221] Starting dynamic subtest: pipe-B-eDP-1
[667.386401] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.663s)
[667.397665] Starting dynamic subtest: pipe-C-eDP-1
[700.350457] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.577s)
[700.360819] Starting dynamic subtest: pipe-D-eDP-1
[733.370325] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.641s)
[733.372892] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (13.729s)
[734.604384] [122/132] (288s left) api_intel_bb (intel-bb-blit-x)
[734.678951] Starting subtest: intel-bb-blit-x
[738.442683] Subtest intel-bb-blit-x: SUCCESS (3.715s)
[738.572826] [123/132] (285s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[738.673786] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[738.677983] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[738.776451] [124/132] (284s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[738.836085] Starting subtest: y-tiled-to-vebox-y-tiled
[738.840628] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[739.027465] [125/132] (284s left) kms_flip (modeset-vs-vblank-race)
[739.125572] Starting subtest: modeset-vs-vblank-race
[739.127925] Starting dynamic subtest: A-eDP1
[743.831078] Dynamic subtest A-eDP1: SUCCESS (4.674s)
[743.846688] Starting dynamic subtest: B-eDP1
[748.580310] Dynamic subtest B-eDP1: SUCCESS (4.727s)
[748.592253] Starting dynamic subtest: C-eDP1
[753.295071] Dynamic subtest C-eDP1: SUCCESS (4.701s)
[753.306331] Starting dynamic subtest: D-eDP1
[757.852775] Dynamic subtest D-eDP1: SUCCESS (4.550s)
[757.857554] Subtest modeset-vs-vblank-race: SUCCESS (18.665s)
[759.017722] [126/132] (264s left) gem_softpin (allocator-nopin)
[759.082095] Starting subtest: allocator-nopin
[759.181861] Subtest allocator-nopin: SUCCESS (0.098s)
[759.303611] [127/132] (264s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[759.423828] Starting subtest: basic-busy-flip-before-cursor-varying-size
[759.428595] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[759.566606] [128/132] (264s left) kms_frontbuffer_tracking (fbc-tiling-y)
[759.668839] Starting subtest: fbc-tiling-y
[759.672954] Subtest fbc-tiling-y: SKIP (0.000s)
[759.768250] [129/132] (264s left) kms_addfb_basic (bad-pitch-128)
[759.824841] Starting subtest: bad-pitch-128
[759.826799] Subtest bad-pitch-128: SUCCESS (0.000s)
[759.967778] [130/132] (263s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[760.027973] Starting subtest: invalid-bsd1-flag-on-vebox
[760.030015] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[760.147447] [131/132] (263s left) gem_mmap_offset (clear)
[760.217768] Starting subtest: clear
[760.220431] Starting dynamic subtest: smem0
[785.580791] Dynamic subtest smem0: SUCCESS (25.342s)
[785.583067] Subtest clear: SUCCESS (25.344s)
[785.695224] [132/132] (238s left) i915_selftest (live)
[787.539655] Starting subtest: live
[787.542759] Starting dynamic subtest: sanitycheck
[791.893128] Dynamic subtest sanitycheck: SUCCESS (4.354s)
[791.903248] Starting dynamic subtest: uncore
[796.250715] Dynamic subtest uncore: SUCCESS (4.353s)
[796.261671] Starting dynamic subtest: workarounds
[814.246437] Dynamic subtest workarounds: FAIL (17.805s)
[814.255456] Starting dynamic subtest: gt_engines
[817.141432] Dynamic subtest gt_engines: SUCCESS (3.071s)
[817.151439] Starting dynamic subtest: gt_timelines
[831.679856] Dynamic subtest gt_timelines: SUCCESS (14.543s)
[831.690360] Starting dynamic subtest: gt_contexts
[836.319423] Dynamic subtest gt_contexts: SUCCESS (4.647s)
[836.333035] Starting dynamic subtest: gt_lrc
[840.652198] Dynamic subtest gt_lrc: SUCCESS (4.337s)
[840.662237] Starting dynamic subtest: gt_mocs
[843.599237] Dynamic subtest gt_mocs: SUCCESS (2.949s)
[843.608957] Starting dynamic subtest: gt_pm
[850.427206] Dynamic subtest gt_pm: SUCCESS (6.832s)
[850.444137] Starting dynamic subtest: gt_heartbeat
[854.700451] Dynamic subtest gt_heartbeat: SUCCESS (4.273s)
[854.711395] Starting dynamic subtest: gt_tlb
[864.587873] Dynamic subtest gt_tlb: SUCCESS (9.889s)
[864.597956] Starting dynamic subtest: requests
[876.539103] Dynamic subtest requests: SUCCESS (11.950s)
[876.549331] Starting dynamic subtest: migrate
[884.370756] Dynamic subtest migrate: SUCCESS (7.830s)
[884.378853] Starting dynamic subtest: active
[888.730481] Dynamic subtest active: SUCCESS (4.358s)
[888.745865] Starting dynamic subtest: objects
[892.106481] Dynamic subtest objects: SUCCESS (3.375s)
[892.116326] Starting dynamic subtest: mman
[895.441403] Dynamic subtest mman: SUCCESS (3.333s)
[895.451724] Starting dynamic subtest: dmabuf
[899.797452] Dynamic subtest dmabuf: SUCCESS (4.354s)
[899.807848] Starting dynamic subtest: vma
[902.454686] Dynamic subtest vma: SUCCESS (2.656s)
[902.464429] Starting dynamic subtest: coherency
[907.402845] Dynamic subtest coherency: SUCCESS (4.946s)
[907.411593] Starting dynamic subtest: gtt
[919.465204] Dynamic subtest gtt: SUCCESS (12.060s)
[919.476951] Starting dynamic subtest: gem
[927.066181] Dynamic subtest gem: SUCCESS (7.599s)
[927.081806] Starting dynamic subtest: evict
[932.533311] Dynamic subtest evict: SUCCESS (5.466s)
[932.541855] Starting dynamic subtest: hugepages
[943.753132] Dynamic subtest hugepages: SUCCESS (11.217s)
[943.772247] Starting dynamic subtest: gem_contexts
[969.257694] Dynamic subtest gem_contexts: SUCCESS (25.501s)
[969.267238] Starting dynamic subtest: client
[973.599768] Dynamic subtest client: SUCCESS (4.341s)
[973.609843] Starting dynamic subtest: gem_migrate
[977.912621] Dynamic subtest gem_migrate: SUCCESS (4.311s)
[977.922860] Starting dynamic subtest: reset
[982.233738] Dynamic subtest reset: SUCCESS (4.319s)
[982.244245] Starting dynamic subtest: memory_region
[986.878981] Dynamic subtest memory_region: SUCCESS (4.643s)
[986.892081] Starting dynamic subtest: hangcheck
[1002.418776] Dynamic subtest hangcheck: SUCCESS (15.537s)
[1002.429269] Starting dynamic subtest: execlists
[1006.708738] Dynamic subtest execlists: SUCCESS (4.288s)
[1006.726260] Starting dynamic subtest: ring_submission
[1010.971185] Dynamic subtest ring_submission: SUCCESS (4.260s)
[1010.980147] Starting dynamic subtest: perf
[1015.341144] Dynamic subtest perf: SUCCESS (4.367s)
[1015.351637] Starting dynamic subtest: slpc
[1029.811665] Dynamic subtest slpc: SUCCESS (14.469s)
[1029.825252] Starting dynamic subtest: guc
[1033.748446] Dynamic subtest guc: SUCCESS (3.935s)
[1033.758014] Starting dynamic subtest: guc_multi_lrc
[1038.084712] Dynamic subtest guc_multi_lrc: SUCCESS (4.334s)
[1038.095466] Starting dynamic subtest: guc_hang
[1042.972295] Dynamic subtest guc_hang: SUCCESS (4.886s)
[1042.981593] Starting dynamic subtest: late_gt_pm
[1045.723912] Dynamic subtest late_gt_pm: SUCCESS (2.750s)
[1045.726542] Subtest live: FAIL (258.205s)
[1045.747857] Overall timeout time exceeded, stopping.
[1045.757013] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[1045.757721] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[1045.757861] Closing watchdogs
results: parsing output: 0/ for test: kms_flip
results: parsing output: 1/ for test: perf
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_chamelium_hpd
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: gem_userptr_blits
results: parsing output: 7/ for test: api_intel_allocator
results: parsing output: 8/ for test: kms_addfb_basic
results: parsing output: 9/ for test: syncobj_wait
results: parsing output: 10/ for test: gem_userptr_blits
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: vgem_basic
results: parsing output: 13/ for test: gem_mmap_gtt
results: parsing output: 14/ for test: kms_plane_alpha_blend
results: parsing output: 15/ for test: dmabuf_sync_file
results: parsing output: 16/ for test: gem_userptr_blits
results: parsing output: 17/ for test: kms_vblank
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_flip
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: gem_ccs
results: parsing output: 23/ for test: gem_exec_create
results: parsing output: 24/ for test: perf
results: parsing output: 25/ for test: sysfs_timeslice_duration
results: parsing output: 26/ for test: gem_eio
results: parsing output: 27/ for test: kms_pm_rpm
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_exec_fence
results: parsing output: 30/ for test: kms_plane
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_cursor_legacy
results: parsing output: 33/ for test: kms_flip
results: parsing output: 34/ for test: sriov_basic
results: parsing output: 35/ for test: gem_ctx_shared
results: parsing output: 36/ for test: gem_exec_params
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_plane_cursor
results: parsing output: 42/ for test: gem_ctx_persistence
results: parsing output: 43/ for test: kms_cursor_legacy
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_balancer
results: parsing output: 46/ for test: kms_rotation_crc
results: parsing output: 47/ for test: i915_drm_fdinfo
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: gem_ctx_persistence
results: parsing output: 50/ for test: syncobj_wait
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: kms_plane
results: parsing output: 53/ for test: gem_ctx_exec
results: parsing output: 54/ for test: gem_flink_basic
results: parsing output: 55/ for test: gen9_exec_parse
results: parsing output: 56/ for test: gem_exec_whisper
results: parsing output: 57/ for test: i915_pm_rpm
results: parsing output: 58/ for test: kms_async_flips
results: parsing output: 59/ for test: gem_exec_fence
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_rotation_crc
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: perf_pmu
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_plane_scaling
results: parsing output: 68/ for test: kms_lease
results: parsing output: 69/ for test: kms_psr2_sf
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: kms_color
results: parsing output: 72/ for test: syncobj_wait
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: syncobj_wait
results: parsing output: 75/ for test: kms_flip_event_leak
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_addfb_basic
results: parsing output: 79/ for test: kms_hdr
results: parsing output: 80/ for test: kms_psr
results: parsing output: 81/ for test: gem_pxp
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_flip_scaled_crc
results: parsing output: 84/ for test: gem_exec_params
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: gem_exec_fence
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: kms_rotation_crc
results: parsing output: 92/ for test: kms_cursor_crc
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: kms_pm_dc
results: parsing output: 96/ for test: gem_exec_schedule
results: parsing output: 97/ for test: gem_eio
results: parsing output: 98/ for test: syncobj_wait
results: parsing output: 99/ for test: template
results: parsing output: 100/ for test: gem_mmap_gtt
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: kms_content_protection
results: parsing output: 105/ for test: kms_psr2_sf
results: parsing output: 106/ for test: gem_pxp
results: parsing output: 107/ for test: kms_universal_plane
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_feature_discovery
results: parsing output: 111/ for test: gem_exec_whisper
results: parsing output: 112/ for test: i915_hangman
results: parsing output: 113/ for test: syncobj_timeline
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_flush
results: parsing output: 117/ for test: kms_ccs
results: parsing output: 118/ for test: kms_chamelium_color
results: parsing output: 119/ for test: kms_addfb_basic
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: api_intel_bb
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_render_copy
results: parsing output: 124/ for test: kms_flip
results: parsing output: 125/ for test: gem_softpin
results: parsing output: 126/ for test: kms_cursor_legacy
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_addfb_basic
results: parsing output: 129/ for test: gem_exec_params
results: parsing output: 130/ for test: gem_mmap_offset
results: parsing output: 131/ for test: i915_selftest
Done.