[55.947114] Initializing watchdogs [55.947201] /dev/watchdog0 [55.961921] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [55.966385] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [55.968434] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [55.970611] [001/131] (960s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size) [56.087895] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size [57.092042] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (1.010s) [57.917546] [002/131] (958s left) kms_feature_discovery (display) [58.015921] Starting subtest: display [58.016952] Subtest display: SUCCESS (0.000s) [58.092881] [003/131] (958s left) gem_userptr_blits (relocations) [58.153025] Starting subtest: relocations [58.155805] Subtest relocations: SKIP (0.000s) [58.301831] [004/131] (958s left) syncobj_basic (bad-pad-handle-to-fd) [58.355300] Starting subtest: bad-pad-handle-to-fd [58.356182] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s) [58.464073] [005/131] (958s left) kms_flip (2x-flip-vs-expired-vblank) [58.544902] Starting subtest: 2x-flip-vs-expired-vblank [58.555447] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s) [58.650239] [006/131] (957s left) perf (non-zero-reason) [58.772946] Starting subtest: non-zero-reason [58.773860] Starting dynamic subtest: 0-rcs0 [64.111104] Dynamic subtest 0-rcs0: SUCCESS (5.350s) [64.119845] Starting dynamic subtest: 1-vecs0 [67.987780] Dynamic subtest 1-vecs0: SUCCESS (3.882s) [67.989552] Subtest non-zero-reason: SUCCESS (9.232s) [68.024509] [007/131] (948s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180) [68.132872] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180 [68.134523] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s) [68.246074] [008/131] (948s left) kms_dp_aux_dev [68.600510] [009/131] (948s left) kms_pipe_stress (stress-xrgb8888-untiled) [68.686558] Starting subtest: stress-xrgb8888-untiled [74.601322] Subtest stress-xrgb8888-untiled: SUCCESS (5.918s) [74.660516] [010/131] (942s left) gem_ctx_persistence (engines-hostile-preempt) [74.801620] Starting subtest: engines-hostile-preempt [74.802737] Starting dynamic subtest: rcs0 [75.018468] Dynamic subtest rcs0: SUCCESS (0.217s) [75.027431] Starting dynamic subtest: bcs0 [75.083713] Dynamic subtest bcs0: SUCCESS (0.063s) [75.092543] Starting dynamic subtest: vcs0 [76.918562] Dynamic subtest vcs0: SUCCESS (1.833s) [76.925208] Starting dynamic subtest: vcs1 [78.958075] Dynamic subtest vcs1: SUCCESS (2.041s) [78.967123] Starting dynamic subtest: vecs0 [80.926043] Dynamic subtest vecs0: SUCCESS (1.968s) [80.935034] Starting dynamic subtest: ccs0 [80.997984] Dynamic subtest ccs0: SUCCESS (0.074s) [80.999864] Subtest engines-hostile-preempt: SUCCESS (6.198s) [81.081992] [011/131] (935s left) kms_addfb_basic (addfb25-bad-modifier) [81.172585] Starting subtest: addfb25-bad-modifier [81.173516] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [81.276516] [012/131] (935s left) gem_ctx_exec (basic-norecovery) [81.334377] Starting subtest: basic-norecovery [81.670039] Subtest basic-norecovery: SUCCESS (0.336s) [81.835119] [013/131] (935s left) gem_exec_async (forked-writes) [81.895121] Starting subtest: forked-writes [81.896110] Starting dynamic subtest: rcs0 [81.922095] Dynamic subtest rcs0: SUCCESS (0.027s) [81.930069] Starting dynamic subtest: bcs0 [81.943907] Dynamic subtest bcs0: SUCCESS (0.022s) [81.950497] Starting dynamic subtest: vcs0 [81.965077] Dynamic subtest vcs0: SUCCESS (0.021s) [81.974748] Starting dynamic subtest: vcs1 [81.987251] Dynamic subtest vcs1: SUCCESS (0.022s) [81.993583] Starting dynamic subtest: vecs0 [82.009048] Dynamic subtest vecs0: SUCCESS (0.022s) [82.015276] Starting dynamic subtest: ccs0 [82.029737] Dynamic subtest ccs0: SUCCESS (0.020s) [82.030607] Subtest forked-writes: SUCCESS (0.135s) [82.273921] [014/131] (934s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode) [82.640267] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s) [83.478508] [015/131] (933s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area) [88.493783] Starting subtest: fbc-psr2-plane-move-sf-dmg-area [88.496365] Starting dynamic subtest: pipe-A-eDP-1 [89.293147] Dynamic subtest pipe-A-eDP-1: SKIP (0.796s) [89.299772] Starting dynamic subtest: pipe-B-eDP-1 [90.425036] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s) [90.426944] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.930s) [91.623078] [016/131] (925s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu) [91.706067] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu [98.665832] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (6.961s) [98.795243] [017/131] (918s left) gem_exec_fence (submit) [98.970802] Starting subtest: submit [98.972949] Subtest submit: SKIP (0.000s) [100.994536] [FACT gem_exec_fence (submit)] new: kernel.kmod_is_loaded.vgem: true [100.996341] [018/131] (915s left) gem_fenced_exec_thrash (2-spare-fences) [101.041544] Subtest 2-spare-fences: SKIP (0.000s) [101.095383] [019/131] (915s left) gem_mmap_offset (open-flood) [101.149630] Starting subtest: open-flood [122.639846] Subtest open-flood: SUCCESS (21.490s) [122.786682] [020/131] (894s left) kms_psr (fbc-psr-sprite-mmap-cpu) [122.888912] Starting subtest: fbc-psr-sprite-mmap-cpu [122.890044] Starting dynamic subtest: eDP-1 [122.891937] Dynamic subtest eDP-1: SKIP (0.000s) [122.893661] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s) [122.968218] [021/131] (894s left) kms_pwrite_crc [124.588168] [022/131] (892s left) kms_vblank (query-idle) [124.680014] Starting subtest: query-idle [124.681060] Starting dynamic subtest: pipe-A-eDP-1 [126.444281] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.763s) [126.453680] Starting dynamic subtest: pipe-D-eDP-1 [128.916858] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.470s) [128.917910] Subtest query-idle: SUCCESS (4.237s) [129.751786] [023/131] (887s left) kms_pm_dc (dc5-psr) [130.851858] Starting subtest: dc5-psr [133.828079] Subtest dc5-psr: SUCCESS (2.976s) [134.691963] [024/131] (882s left) kms_cursor_crc (cursor-offscreen-512x512) [134.972587] Starting subtest: cursor-offscreen-512x512 [134.974659] Subtest cursor-offscreen-512x512: SKIP (0.001s) [135.125353] [025/131] (882s left) kms_joiner (basic-force-big-joiner) [135.212975] Starting subtest: basic-force-big-joiner [135.213914] Starting dynamic subtest: single [138.506744] Dynamic subtest single: SUCCESS (3.298s) [138.507845] Subtest basic-force-big-joiner: SUCCESS (3.298s) [139.695155] [026/131] (877s left) core_hotunplug (unbind-rebind) [139.779897] Starting subtest: unbind-rebind [145.839853] Subtest unbind-rebind: SUCCESS (6.060s) [145.878298] [027/131] (871s left) gem_exec_nop (basic-parallel) [146.885192] Starting subtest: basic-parallel [162.843352] Subtest basic-parallel: SUCCESS (15.958s) [163.094644] [028/131] (854s left) kms_flip (flip-vs-rmfb) [163.186047] Starting subtest: flip-vs-rmfb [163.186971] Starting dynamic subtest: A-eDP1 [171.894300] Dynamic subtest A-eDP1: SUCCESS (8.706s) [171.903624] Starting dynamic subtest: B-eDP1 [180.525439] Dynamic subtest B-eDP1: SUCCESS (8.629s) [180.534284] Starting dynamic subtest: C-eDP1 [189.145988] Dynamic subtest C-eDP1: SUCCESS (8.619s) [189.154184] Starting dynamic subtest: D-eDP1 [197.777660] Dynamic subtest D-eDP1: SUCCESS (8.630s) [197.780581] Subtest flip-vs-rmfb: SUCCESS (34.594s) [198.962966] [029/131] (818s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip) [199.058223] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip [200.044320] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.987s) [200.202641] [030/131] (817s left) api_intel_allocator (fork-simple-stress-signal) [200.247574] Starting subtest: fork-simple-stress-signal [205.652550] Subtest fork-simple-stress-signal: SUCCESS (5.403s) [205.788658] [031/131] (811s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs) [205.885159] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs [205.886060] Starting dynamic subtest: pipe-A-eDP-1 [207.953530] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.068s) [207.961949] Starting dynamic subtest: pipe-B-eDP-1 [209.994395] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.040s) [210.002905] Starting dynamic subtest: pipe-C-eDP-1 [211.985225] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.990s) [211.993792] Starting dynamic subtest: pipe-D-eDP-1 [214.032836] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.046s) [214.033958] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.148s) [215.282958] [032/131] (802s left) kms_async_flips (basic-modeset-with-all-modifiers-formats) [215.379549] Starting subtest: basic-modeset-with-all-modifiers-formats [215.755618] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888 [216.557051] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.801s) [216.566222] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010 [216.579079] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.022s) [216.585966] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F [216.601501] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s) [216.608540] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565 [216.757023] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s) [216.765291] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888 [216.779196] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s) [216.788286] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010 [216.801349] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s) [216.808500] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F [216.824977] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.023s) [216.836320] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV [216.845909] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.019s) [216.852662] Starting dynamic subtest: pipe-A-eDP-1-4-Y210 [216.868080] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s) [216.874696] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888 [216.890206] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s) [216.897061] Starting dynamic subtest: pipe-A-eDP-1-4-XV30 [216.912545] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s) [216.919221] Starting dynamic subtest: pipe-A-eDP-1-4-XV36 [216.934807] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s) [216.941409] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565 [217.090246] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s) [217.097435] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888 [217.112610] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s) [217.119576] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010 [217.134793] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s) [217.141818] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F [217.157128] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s) [217.166276] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV [217.179175] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s) [217.186200] Starting dynamic subtest: pipe-A-eDP-1-x-Y210 [217.201438] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s) [217.210291] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888 [217.223815] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s) [217.231811] Starting dynamic subtest: pipe-A-eDP-1-x-XV30 [217.245875] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.020s) [217.252625] Starting dynamic subtest: pipe-A-eDP-1-x-XV36 [217.267956] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s) [217.274749] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565 [217.424023] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.155s) [217.432067] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888 [217.446161] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s) [217.454563] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010 [217.468860] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s) [217.480871] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F [217.490737] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s) [217.497479] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV [217.512938] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s) [217.519408] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210 [217.535773] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s) [217.545036] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888 [217.557364] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s) [217.564426] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30 [217.579641] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s) [217.586631] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36 [217.601958] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s) [217.982730] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.604s) [218.835269] [033/131] (798s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [218.929025] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu [218.930871] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [219.067498] [034/131] (798s left) kms_psr (psr2-primary-blt) [219.156495] Starting subtest: psr2-primary-blt [219.157441] Starting dynamic subtest: eDP-1 [220.910032] Dynamic subtest eDP-1: SUCCESS (1.751s) [220.911133] Subtest psr2-primary-blt: SUCCESS (1.753s) [221.788914] [035/131] (795s left) gem_ccs (suspend-resume) [221.842972] Starting subtest: suspend-resume [221.845058] Subtest suspend-resume: SKIP (0.000s) [221.952466] [036/131] (795s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation) [222.037892] Starting subtest: plane-scaler-unity-scaling-with-rotation [222.038911] Starting dynamic subtest: pipe-A [222.267876] Dynamic subtest pipe-A: SUCCESS (0.230s) [222.275045] Starting dynamic subtest: pipe-B [223.567232] Dynamic subtest pipe-B: SUCCESS (1.299s) [223.574340] Starting dynamic subtest: pipe-C [224.899944] Dynamic subtest pipe-C: SUCCESS (1.332s) [224.907406] Starting dynamic subtest: pipe-D [226.236181] Dynamic subtest pipe-D: SUCCESS (1.335s) [226.237191] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.198s) [227.428984] [037/131] (790s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c) [227.486685] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s) [227.544919] [038/131] (790s left) kms_sequence (get-busy) [227.633713] Starting subtest: get-busy [227.634731] Starting dynamic subtest: pipe-A-eDP-1 [229.545513] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.910s) [229.552530] Starting dynamic subtest: pipe-B-eDP-1 [232.099553] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.553s) [232.106858] Starting dynamic subtest: pipe-C-eDP-1 [234.726050] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.626s) [234.734290] Starting dynamic subtest: pipe-D-eDP-1 [237.268808] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.541s) [237.270095] Subtest get-busy: SUCCESS (9.634s) [238.077794] [039/131] (779s left) perf (invalid-open-flags) [238.186955] Starting subtest: invalid-open-flags [238.187951] Subtest invalid-open-flags: SUCCESS (0.000s) [238.222469] [040/131] (779s left) kms_cursor_crc (cursor-size-hints) [238.352461] Starting subtest: cursor-size-hints [238.353520] Starting dynamic subtest: pipe-A-eDP-1 [238.971823] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.618s) [238.981768] Starting dynamic subtest: pipe-D-eDP-1 [240.234648] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.261s) [240.235880] Subtest cursor-size-hints: SUCCESS (1.882s) [241.059472] [041/131] (776s left) gem_ctx_param (set-priority-not-supported) [241.118419] Starting subtest: set-priority-not-supported [241.120434] Subtest set-priority-not-supported: SKIP (0.000s) [241.213333] [042/131] (776s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy) [241.328630] Starting subtest: 2x-flip-vs-cursor-legacy [241.331609] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s) [241.492329] [043/131] (776s left) syncobj_timeline (reset-during-wait-for-submit) [241.543054] Starting subtest: reset-during-wait-for-submit [241.648644] Subtest reset-during-wait-for-submit: SUCCESS (0.104s) [241.733081] [044/131] (776s left) kms_chamelium_color (ctm-max) [241.822522] Subtest ctm-max: SKIP (0.000s) [241.861312] [045/131] (776s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [241.963393] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip [242.766628] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.803s) [242.943992] [046/131] (774s left) syncobj_wait (wait-zero-handles) [242.992260] Starting subtest: wait-zero-handles [242.993296] Subtest wait-zero-handles: SUCCESS (0.000s) [243.105225] [047/131] (774s left) gem_exec_schedule (pi-common) [243.293046] Starting subtest: pi-common [243.294297] Starting dynamic subtest: rcs0 [243.374475] Dynamic subtest rcs0: SUCCESS (0.082s) [243.384076] Starting dynamic subtest: bcs0 [243.455060] Dynamic subtest bcs0: SUCCESS (0.079s) [243.464795] Starting dynamic subtest: vcs0 [243.532867] Dynamic subtest vcs0: SUCCESS (0.076s) [243.547406] Starting dynamic subtest: vcs1 [243.611569] Dynamic subtest vcs1: SUCCESS (0.077s) [243.626065] Starting dynamic subtest: vecs0 [243.687483] Dynamic subtest vecs0: SUCCESS (0.074s) [243.701925] Starting dynamic subtest: ccs0 [243.762792] Dynamic subtest ccs0: SUCCESS (0.074s) [243.764495] Subtest pi-common: SUCCESS (0.472s) [243.993576] [048/131] (773s left) i915_module_load (reload) [244.023614] Starting subtest: reload [247.142602] Subtest reload: SUCCESS (3.120s) [247.174060] [049/131] (770s left) kms_big_fb (linear-32bpp-rotate-90) [248.937506] Starting subtest: linear-32bpp-rotate-90 [248.965511] Subtest linear-32bpp-rotate-90: SKIP (0.028s) [249.082431] [050/131] (768s left) gem_lmem_swapping (heavy-verify-multi) [249.118547] Subtest heavy-verify-multi: SKIP (0.000s) [249.157642] [051/131] (768s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs) [249.243546] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs [249.244436] Starting dynamic subtest: pipe-A-eDP-1 [249.253815] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [249.260594] Starting dynamic subtest: pipe-B-eDP-1 [249.269547] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [249.276246] Starting dynamic subtest: pipe-C-eDP-1 [249.285117] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [249.290955] Starting dynamic subtest: pipe-D-eDP-1 [249.300067] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [249.301826] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.004s) [251.465211] [052/131] (766s left) vgem_basic (second-client) [251.504180] Starting subtest: second-client [251.526052] Subtest second-client: SUCCESS (0.022s) [251.589818] [053/131] (766s left) perf_pmu (init-sema) [251.619296] Starting subtest: init-sema [251.620113] Starting dynamic subtest: rcs0 [251.621967] Dynamic subtest rcs0: SUCCESS (0.003s) [251.628061] Starting dynamic subtest: bcs0 [251.629030] Dynamic subtest bcs0: SUCCESS (0.000s) [251.636794] Starting dynamic subtest: vcs0 [251.637750] Dynamic subtest vcs0: SUCCESS (0.001s) [251.643728] Starting dynamic subtest: vcs1 [251.644573] Dynamic subtest vcs1: SUCCESS (0.001s) [251.650110] Starting dynamic subtest: vecs0 [251.650906] Dynamic subtest vecs0: SUCCESS (0.001s) [251.656890] Starting dynamic subtest: ccs0 [251.657710] Dynamic subtest ccs0: SUCCESS (0.000s) [251.658546] Subtest init-sema: SUCCESS (0.010s) [251.744888] [054/131] (766s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render) [251.839946] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render [251.841632] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [251.950495] [055/131] (766s left) kms_atomic_transition (modeset-transition-fencing) [252.042647] Starting subtest: modeset-transition-fencing [252.043581] Starting dynamic subtest: 1x-outputs [262.560850] Dynamic subtest 1x-outputs: SUCCESS (10.516s) [262.836662] Subtest modeset-transition-fencing: SUCCESS (10.794s) [263.723084] [056/131] (754s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt) [263.812070] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt [263.813938] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s) [263.924741] [057/131] (754s left) gem_flink_basic (double-flink) [263.978672] Starting subtest: double-flink [263.979728] Subtest double-flink: SUCCESS (0.000s) [264.093449] [058/131] (754s left) gem_exec_params (rsvd2-dirt) [264.139188] Starting subtest: rsvd2-dirt [264.141448] Subtest rsvd2-dirt: SKIP (0.000s) [264.256403] [059/131] (754s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75) [264.347583] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75 [264.348542] Starting dynamic subtest: pipe-A [264.363604] Dynamic subtest pipe-A: SKIP (0.015s) [264.370382] Starting dynamic subtest: pipe-B [264.389730] Dynamic subtest pipe-B: SKIP (0.026s) [264.395955] Starting dynamic subtest: pipe-C [264.416493] Dynamic subtest pipe-C: SKIP (0.026s) [264.422577] Starting dynamic subtest: pipe-D [264.442831] Dynamic subtest pipe-D: SKIP (0.026s) [264.444625] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.094s) [264.574845] [060/131] (753s left) gem_mmap_gtt (fault-concurrent-x) [264.629896] Subtest fault-concurrent-X: SKIP (0.000s) [264.698637] [061/131] (753s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt) [264.787622] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt [272.103608] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.317s) [272.212270] [062/131] (746s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc) [272.304761] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc [272.306536] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [272.408611] [063/131] (746s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [272.500388] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip [272.501974] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [272.612881] [064/131] (745s left) gem_readwrite (beyond-eob) [272.661865] Subtest beyond-EOB: SKIP (0.000s) [272.723215] [065/131] (745s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt) [272.805248] Starting subtest: fbc-rgb565-draw-mmap-gtt [272.806967] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s) [272.935399] [066/131] (745s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt) [273.023878] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt [273.025737] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [273.148060] [067/131] (745s left) gem_exec_fence (syncobj-invalid-wait) [273.548488] Starting subtest: syncobj-invalid-wait [273.549726] Subtest syncobj-invalid-wait: SUCCESS (0.000s) [274.956603] [068/131] (743s left) api_intel_bb (object-noreloc-keep-cache-simple) [275.006800] Starting subtest: object-noreloc-keep-cache-simple [275.014350] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.008s) [275.168204] [069/131] (743s left) syncobj_timeline (single-wait-all-for-submit-available-signaled) [275.214080] Starting subtest: single-wait-all-for-submit-available-signaled [275.215229] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s) [275.331921] [070/131] (743s left) kms_addfb_basic (unused-handle) [275.388143] Starting subtest: unused-handle [275.389120] Subtest unused-handle: SUCCESS (0.000s) [275.558304] [071/131] (743s left) kms_psr (psr2-primary-render) [275.646200] Starting subtest: psr2-primary-render [275.647053] Starting dynamic subtest: eDP-1 [277.321380] Dynamic subtest eDP-1: SUCCESS (1.675s) [277.322273] Subtest psr2-primary-render: SUCCESS (1.676s) [278.210647] [072/131] (740s left) kms_color (ctm-red-to-blue) [278.305870] Starting subtest: ctm-red-to-blue [278.306764] Starting dynamic subtest: pipe-A-eDP-1 [278.994239] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.686s) [279.000602] Starting dynamic subtest: pipe-B-eDP-1 [280.389042] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.395s) [280.395516] Starting dynamic subtest: pipe-C-eDP-1 [281.744119] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.355s) [281.753020] Starting dynamic subtest: pipe-D-eDP-1 [283.073280] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.327s) [283.074502] Subtest ctm-red-to-blue: SUCCESS (4.767s) [283.929676] [073/131] (734s left) kms_frontbuffer_tracking (fbc-tiling-y) [284.026523] Starting subtest: fbc-tiling-y [284.028390] Subtest fbc-tiling-y: SKIP (0.000s) [284.153478] [074/131] (734s left) gem_exec_balancer (nop) [284.208833] Starting subtest: nop [330.781604] Subtest nop: SUCCESS (46.570s) [331.107916] [075/131] (687s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu) [331.201703] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu [331.203406] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [331.304588] [076/131] (687s left) kms_plane_multiple (2x-tiling-none) [331.382580] Starting subtest: 2x-tiling-none [331.384253] Subtest 2x-tiling-none: SKIP (0.000s) [331.470799] [077/131] (687s left) kms_prop_blob (invalid-get-prop-any) [331.524344] Starting subtest: invalid-get-prop-any [331.525204] Subtest invalid-get-prop-any: SUCCESS (0.000s) [331.632243] [078/131] (687s left) gem_exec_schedule (preempt-self) [331.688979] Starting subtest: preempt-self [331.690040] Starting dynamic subtest: rcs0 [331.715870] Dynamic subtest rcs0: SUCCESS (0.026s) [331.744569] Starting dynamic subtest: bcs0 [331.745460] Dynamic subtest bcs0: SUCCESS (0.027s) [331.752453] Starting dynamic subtest: vcs0 [331.761489] Dynamic subtest vcs0: SUCCESS (0.018s) [331.769963] Starting dynamic subtest: vcs1 [331.787999] Dynamic subtest vcs1: SUCCESS (0.026s) [331.797317] Starting dynamic subtest: vecs0 [331.804609] Dynamic subtest vecs0: SUCCESS (0.016s) [331.811961] Starting dynamic subtest: ccs0 [331.820805] Dynamic subtest ccs0: SUCCESS (0.016s) [331.821890] Subtest preempt-self: SUCCESS (0.132s) [332.983174] [079/131] (685s left) prime_busy (before-wait) [333.042707] Starting subtest: before-wait [333.045962] Starting dynamic subtest: rcs0 [333.093761] Dynamic subtest rcs0: SUCCESS (0.047s) [333.102734] Starting dynamic subtest: bcs0 [333.154403] Dynamic subtest bcs0: SUCCESS (0.060s) [333.162828] Starting dynamic subtest: vcs0 [333.197033] Dynamic subtest vcs0: SUCCESS (0.042s) [333.204699] Starting dynamic subtest: vcs1 [333.237375] Dynamic subtest vcs1: SUCCESS (0.040s) [333.244569] Starting dynamic subtest: vecs0 [333.278656] Dynamic subtest vecs0: SUCCESS (0.040s) [333.287498] Starting dynamic subtest: ccs0 [333.320407] Dynamic subtest ccs0: SUCCESS (0.041s) [333.430547] Subtest before-wait: SUCCESS (0.385s) [333.521156] [080/131] (685s left) kms_cursor_crc (cursor-random-32x32) [333.654155] Starting subtest: cursor-random-32x32 [333.688613] Subtest cursor-random-32x32: SKIP (0.033s) [333.989253] [081/131] (685s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-blt) [334.076045] Starting subtest: fbc-1p-offscren-pri-indfb-draw-blt [338.215324] Subtest fbc-1p-offscren-pri-indfb-draw-blt: SUCCESS (4.140s) [339.352420] [082/131] (679s left) kms_busy (extended-modeset-hang-oldfb-with-reset) [339.449385] Starting subtest: extended-modeset-hang-oldfb-with-reset [339.450256] Starting dynamic subtest: pipe-A [351.222943] Dynamic subtest pipe-A: SUCCESS (11.771s) [351.230491] Starting dynamic subtest: pipe-D [363.503411] Dynamic subtest pipe-D: SUCCESS (12.279s) [363.504387] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.054s) [364.307122] [083/131] (654s left) syncobj_timeline (etime-multi-wait-available-unsubmitted) [364.361890] Starting subtest: etime-multi-wait-available-unsubmitted [364.467672] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.103s) [364.558108] [084/131] (654s left) i915_hangman (gt-engine-hang) [364.620849] Starting subtest: gt-engine-hang [364.621933] Starting dynamic subtest: rcs0 [365.876317] Dynamic subtest rcs0: SUCCESS (1.253s) [365.886510] Starting dynamic subtest: bcs0 [367.861672] Dynamic subtest bcs0: SUCCESS (1.983s) [367.871030] Starting dynamic subtest: vcs0 [369.845530] Dynamic subtest vcs0: SUCCESS (1.981s) [369.856327] Starting dynamic subtest: vcs1 [371.895757] Dynamic subtest vcs1: SUCCESS (2.048s) [371.906209] Starting dynamic subtest: vecs0 [373.875863] Dynamic subtest vecs0: SUCCESS (1.979s) [373.886441] Starting dynamic subtest: ccs0 [375.861622] Dynamic subtest ccs0: SUCCESS (1.984s) [375.863097] Subtest gt-engine-hang: SUCCESS (11.239s) [375.943626] [085/131] (643s left) kms_flip_tiling (flip-change-tiling) [376.031237] Starting subtest: flip-change-tiling [376.032096] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4-mc-ccs [376.198805] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (0.166s) [376.208100] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs [376.344766] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.143s) [376.354831] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs-cc [376.489191] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.142s) [376.498188] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4 [376.673030] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4: SUCCESS (0.182s) [376.684116] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-x [376.842308] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-x: SUCCESS (0.170s) [376.853862] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-linear [376.998128] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.154s) [377.012992] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4-mc-ccs [377.143475] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.142s) [377.154511] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs [377.265407] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.120s) [377.276200] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs-cc [377.400470] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.132s) [377.410575] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4 [377.543490] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4: SUCCESS (0.142s) [377.554097] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-x [377.686915] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-x: SUCCESS (0.143s) [377.698361] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-linear [377.832596] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.142s) [377.844860] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4-mc-ccs [377.976822] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.143s) [377.986626] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs [378.111302] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.131s) [378.121977] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc [378.232076] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.120s) [378.242531] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4 [378.376834] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.143s) [378.386970] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-x [378.520596] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.142s) [378.532205] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-linear [378.664577] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.142s) [378.675996] Starting dynamic subtest: pipe-A-eDP-1-4-to-4-mc-ccs [378.810075] Dynamic subtest pipe-A-eDP-1-4-to-4-mc-ccs: SUCCESS (0.143s) [378.820633] Starting dynamic subtest: pipe-A-eDP-1-4-to-4-rc-ccs [378.954571] Dynamic subtest pipe-A-eDP-1-4-to-4-rc-ccs: SUCCESS (0.143s) [378.965270] Starting dynamic subtest: pipe-A-eDP-1-4-to-4-rc-ccs-cc [379.098594] Dynamic subtest pipe-A-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.143s) [379.110109] Starting dynamic subtest: pipe-A-eDP-1-4-to-4 [379.232796] Dynamic subtest pipe-A-eDP-1-4-to-4: SUCCESS (0.131s) [379.240393] Starting dynamic subtest: pipe-A-eDP-1-4-to-x [379.375785] Dynamic subtest pipe-A-eDP-1-4-to-x: SUCCESS (0.143s) [379.386961] Starting dynamic subtest: pipe-A-eDP-1-4-to-linear [379.520265] Dynamic subtest pipe-A-eDP-1-4-to-linear: SUCCESS (0.143s) [379.531755] Starting dynamic subtest: pipe-A-eDP-1-x-to-4-mc-ccs [379.664704] Dynamic subtest pipe-A-eDP-1-x-to-4-mc-ccs: SUCCESS (0.143s) [379.676216] Starting dynamic subtest: pipe-A-eDP-1-x-to-4-rc-ccs [379.809117] Dynamic subtest pipe-A-eDP-1-x-to-4-rc-ccs: SUCCESS (0.143s) [379.821556] Starting dynamic subtest: pipe-A-eDP-1-x-to-4-rc-ccs-cc [379.953833] Dynamic subtest pipe-A-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.143s) [379.965494] Starting dynamic subtest: pipe-A-eDP-1-x-to-4 [380.109196] Dynamic subtest pipe-A-eDP-1-x-to-4: SUCCESS (0.154s) [380.120871] Starting dynamic subtest: pipe-A-eDP-1-x-to-x [380.231393] Dynamic subtest pipe-A-eDP-1-x-to-x: SUCCESS (0.120s) [380.242772] Starting dynamic subtest: pipe-A-eDP-1-x-to-linear [380.376554] Dynamic subtest pipe-A-eDP-1-x-to-linear: SUCCESS (0.142s) [380.386418] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4-mc-ccs [380.520751] Dynamic subtest pipe-A-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.143s) [380.530912] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4-rc-ccs [380.664749] Dynamic subtest pipe-A-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.143s) [380.676221] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4-rc-ccs-cc [380.809452] Dynamic subtest pipe-A-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.143s) [380.820278] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4 [380.964690] Dynamic subtest pipe-A-eDP-1-linear-to-4: SUCCESS (0.154s) [380.976529] Starting dynamic subtest: pipe-A-eDP-1-linear-to-x [381.110041] Dynamic subtest pipe-A-eDP-1-linear-to-x: SUCCESS (0.142s) [381.120881] Starting dynamic subtest: pipe-A-eDP-1-linear-to-linear [381.232209] Dynamic subtest pipe-A-eDP-1-linear-to-linear: SUCCESS (0.120s) [381.272949] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4-mc-ccs [382.475241] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (1.207s) [382.485755] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs [382.610252] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.132s) [382.621045] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs-cc [382.754612] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.143s) [382.764100] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4 [382.897671] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4: SUCCESS (0.142s) [382.909710] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-x [383.040944] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-x: SUCCESS (0.141s) [383.053242] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-linear [383.187396] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.142s) [383.197435] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4-mc-ccs [383.319541] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.132s) [383.335357] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs [383.441940] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.119s) [383.452550] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs-cc [383.576807] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.132s) [383.586530] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4 [383.719705] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4: SUCCESS (0.143s) [383.730465] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-x [383.890881] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-x: SUCCESS (0.170s) [383.902762] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-linear [384.035358] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.142s) [384.046938] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4-mc-ccs [384.169215] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.132s) [384.181519] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs [384.326575] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.153s) [384.336353] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc [384.436039] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.109s) [384.446469] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4 [384.569468] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.132s) [384.580117] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-x [384.713039] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.143s) [384.724811] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-linear [384.857580] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.143s) [384.869337] Starting dynamic subtest: pipe-B-eDP-1-4-to-4-mc-ccs [385.002284] Dynamic subtest pipe-B-eDP-1-4-to-4-mc-ccs: SUCCESS (0.142s) [385.013618] Starting dynamic subtest: pipe-B-eDP-1-4-to-4-rc-ccs [385.148180] Dynamic subtest pipe-B-eDP-1-4-to-4-rc-ccs: SUCCESS (0.142s) [385.159412] Starting dynamic subtest: pipe-B-eDP-1-4-to-4-rc-ccs-cc [385.291794] Dynamic subtest pipe-B-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.142s) [385.302597] Starting dynamic subtest: pipe-B-eDP-1-4-to-4 [385.413801] Dynamic subtest pipe-B-eDP-1-4-to-4: SUCCESS (0.120s) [385.424135] Starting dynamic subtest: pipe-B-eDP-1-4-to-x [385.557843] Dynamic subtest pipe-B-eDP-1-4-to-x: SUCCESS (0.143s) [385.569300] Starting dynamic subtest: pipe-B-eDP-1-4-to-linear [385.702109] Dynamic subtest pipe-B-eDP-1-4-to-linear: SUCCESS (0.142s) [385.713297] Starting dynamic subtest: pipe-B-eDP-1-x-to-4-mc-ccs [385.846420] Dynamic subtest pipe-B-eDP-1-x-to-4-mc-ccs: SUCCESS (0.142s) [385.858586] Starting dynamic subtest: pipe-B-eDP-1-x-to-4-rc-ccs [385.990887] Dynamic subtest pipe-B-eDP-1-x-to-4-rc-ccs: SUCCESS (0.143s) [386.003028] Starting dynamic subtest: pipe-B-eDP-1-x-to-4-rc-ccs-cc [386.135473] Dynamic subtest pipe-B-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.142s) [386.147139] Starting dynamic subtest: pipe-B-eDP-1-x-to-4 [386.279779] Dynamic subtest pipe-B-eDP-1-x-to-4: SUCCESS (0.143s) [386.292293] Starting dynamic subtest: pipe-B-eDP-1-x-to-x [386.402436] Dynamic subtest pipe-B-eDP-1-x-to-x: SUCCESS (0.120s) [386.414951] Starting dynamic subtest: pipe-B-eDP-1-x-to-linear [386.546989] Dynamic subtest pipe-B-eDP-1-x-to-linear: SUCCESS (0.143s) [386.558442] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4-mc-ccs [386.690994] Dynamic subtest pipe-B-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.143s) [386.702520] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4-rc-ccs [386.835725] Dynamic subtest pipe-B-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.143s) [386.846849] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4-rc-ccs-cc [386.979641] Dynamic subtest pipe-B-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.143s) [386.991202] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4 [387.124485] Dynamic subtest pipe-B-eDP-1-linear-to-4: SUCCESS (0.143s) [387.136263] Starting dynamic subtest: pipe-B-eDP-1-linear-to-x [387.269507] Dynamic subtest pipe-B-eDP-1-linear-to-x: SUCCESS (0.143s) [387.278731] Starting dynamic subtest: pipe-B-eDP-1-linear-to-linear [387.391260] Dynamic subtest pipe-B-eDP-1-linear-to-linear: SUCCESS (0.121s) [387.443250] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4-mc-ccs [388.710307] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (1.273s) [388.720591] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs [388.810379] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.098s) [388.821481] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs-cc [388.910323] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.098s) [388.920518] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4 [389.020844] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4: SUCCESS (0.109s) [389.030741] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-x [389.131539] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-x: SUCCESS (0.110s) [389.142563] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-linear [389.242682] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.110s) [389.252645] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4-mc-ccs [389.342963] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.099s) [389.353545] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs [389.443599] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.099s) [389.453681] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs-cc [389.543664] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.099s) [389.554408] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4 [389.654155] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4: SUCCESS (0.110s) [389.665095] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-x [389.765113] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-x: SUCCESS (0.109s) [389.776554] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-linear [389.876344] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.109s) [389.888583] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4-mc-ccs [389.976729] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.098s) [389.987398] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs [390.076941] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.099s) [390.087131] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc [390.176776] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.098s) [390.187094] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4 [390.299058] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.121s) [390.309309] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-x [390.409312] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.109s) [390.419256] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-linear [390.509405] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.099s) [390.519198] Starting dynamic subtest: pipe-C-eDP-1-4-to-4-mc-ccs [390.620883] Dynamic subtest pipe-C-eDP-1-4-to-4-mc-ccs: SUCCESS (0.110s) [390.632779] Starting dynamic subtest: pipe-C-eDP-1-4-to-4-rc-ccs [390.732734] Dynamic subtest pipe-C-eDP-1-4-to-4-rc-ccs: SUCCESS (0.109s) [390.743556] Starting dynamic subtest: pipe-C-eDP-1-4-to-4-rc-ccs-cc [390.843611] Dynamic subtest pipe-C-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.110s) [390.856873] Starting dynamic subtest: pipe-C-eDP-1-4-to-4 [390.954218] Dynamic subtest pipe-C-eDP-1-4-to-4: SUCCESS (0.109s) [390.965962] Starting dynamic subtest: pipe-C-eDP-1-4-to-x [391.065085] Dynamic subtest pipe-C-eDP-1-4-to-x: SUCCESS (0.109s) [391.075829] Starting dynamic subtest: pipe-C-eDP-1-4-to-linear [391.217401] Dynamic subtest pipe-C-eDP-1-4-to-linear: SUCCESS (0.151s) [391.228845] Starting dynamic subtest: pipe-C-eDP-1-x-to-4-mc-ccs [391.328519] Dynamic subtest pipe-C-eDP-1-x-to-4-mc-ccs: SUCCESS (0.110s) [391.339488] Starting dynamic subtest: pipe-C-eDP-1-x-to-4-rc-ccs [391.439605] Dynamic subtest pipe-C-eDP-1-x-to-4-rc-ccs: SUCCESS (0.109s) [391.449400] Starting dynamic subtest: pipe-C-eDP-1-x-to-4-rc-ccs-cc [391.550834] Dynamic subtest pipe-C-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.110s) [391.560846] Starting dynamic subtest: pipe-C-eDP-1-x-to-4 [391.662068] Dynamic subtest pipe-C-eDP-1-x-to-4: SUCCESS (0.110s) [391.672292] Starting dynamic subtest: pipe-C-eDP-1-x-to-x [391.773222] Dynamic subtest pipe-C-eDP-1-x-to-x: SUCCESS (0.109s) [391.781189] Starting dynamic subtest: pipe-C-eDP-1-x-to-linear [391.884524] Dynamic subtest pipe-C-eDP-1-x-to-linear: SUCCESS (0.111s) [391.895430] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4-mc-ccs [391.995213] Dynamic subtest pipe-C-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.110s) [392.010145] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4-rc-ccs [392.106328] Dynamic subtest pipe-C-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.110s) [392.117399] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4-rc-ccs-cc [392.217383] Dynamic subtest pipe-C-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.109s) [392.227635] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4 [392.328461] Dynamic subtest pipe-C-eDP-1-linear-to-4: SUCCESS (0.110s) [392.338638] Starting dynamic subtest: pipe-C-eDP-1-linear-to-x [392.439941] Dynamic subtest pipe-C-eDP-1-linear-to-x: SUCCESS (0.110s) [392.451607] Starting dynamic subtest: pipe-C-eDP-1-linear-to-linear [392.550808] Dynamic subtest pipe-C-eDP-1-linear-to-linear: SUCCESS (0.109s) [392.591383] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4-mc-ccs [393.846292] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (1.261s) [393.856320] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs [393.946285] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.098s) [393.957100] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs-cc [394.057175] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.110s) [394.068823] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4 [394.167970] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4: SUCCESS (0.109s) [394.179050] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-x [394.317415] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-x: SUCCESS (0.147s) [394.328323] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-linear [394.428469] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.109s) [394.439772] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4-mc-ccs [394.528914] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.099s) [394.539911] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs [394.629178] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.098s) [394.638550] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs-cc [394.729523] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.099s) [394.739470] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4 [394.840320] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4: SUCCESS (0.110s) [394.853469] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-x [394.950857] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-x: SUCCESS (0.109s) [394.960849] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-linear [395.061964] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.110s) [395.072781] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4-mc-ccs [395.162343] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.098s) [395.171238] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs [395.263057] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.099s) [395.272989] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc [395.362570] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.099s) [395.372620] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4 [395.473711] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.110s) [395.484400] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-x [395.584159] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.110s) [395.597829] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-linear [395.695282] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.109s) [395.707469] Starting dynamic subtest: pipe-D-eDP-1-4-to-4-mc-ccs [395.806940] Dynamic subtest pipe-D-eDP-1-4-to-4-mc-ccs: SUCCESS (0.109s) [395.816951] Starting dynamic subtest: pipe-D-eDP-1-4-to-4-rc-ccs [395.918437] Dynamic subtest pipe-D-eDP-1-4-to-4-rc-ccs: SUCCESS (0.109s) [395.928592] Starting dynamic subtest: pipe-D-eDP-1-4-to-4-rc-ccs-cc [396.029786] Dynamic subtest pipe-D-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.108s) [396.040542] Starting dynamic subtest: pipe-D-eDP-1-4-to-4 [396.140077] Dynamic subtest pipe-D-eDP-1-4-to-4: SUCCESS (0.109s) [396.152424] Starting dynamic subtest: pipe-D-eDP-1-4-to-x [396.261999] Dynamic subtest pipe-D-eDP-1-4-to-x: SUCCESS (0.120s) [396.272333] Starting dynamic subtest: pipe-D-eDP-1-4-to-linear [396.373166] Dynamic subtest pipe-D-eDP-1-4-to-linear: SUCCESS (0.110s) [396.383436] Starting dynamic subtest: pipe-D-eDP-1-x-to-4-mc-ccs [396.484260] Dynamic subtest pipe-D-eDP-1-x-to-4-mc-ccs: SUCCESS (0.110s) [396.494822] Starting dynamic subtest: pipe-D-eDP-1-x-to-4-rc-ccs [396.595338] Dynamic subtest pipe-D-eDP-1-x-to-4-rc-ccs: SUCCESS (0.110s) [396.605249] Starting dynamic subtest: pipe-D-eDP-1-x-to-4-rc-ccs-cc [396.706396] Dynamic subtest pipe-D-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.110s) [396.716634] Starting dynamic subtest: pipe-D-eDP-1-x-to-4 [396.817566] Dynamic subtest pipe-D-eDP-1-x-to-4: SUCCESS (0.110s) [396.828212] Starting dynamic subtest: pipe-D-eDP-1-x-to-x [396.928891] Dynamic subtest pipe-D-eDP-1-x-to-x: SUCCESS (0.110s) [396.940180] Starting dynamic subtest: pipe-D-eDP-1-x-to-linear [397.040073] Dynamic subtest pipe-D-eDP-1-x-to-linear: SUCCESS (0.109s) [397.050662] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4-mc-ccs [397.151073] Dynamic subtest pipe-D-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.110s) [397.161763] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4-rc-ccs [397.262533] Dynamic subtest pipe-D-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.109s) [397.272283] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4-rc-ccs-cc [397.373123] Dynamic subtest pipe-D-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.109s) [397.384544] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4 [397.484172] Dynamic subtest pipe-D-eDP-1-linear-to-4: SUCCESS (0.110s) [397.493896] Starting dynamic subtest: pipe-D-eDP-1-linear-to-x [397.634876] Dynamic subtest pipe-D-eDP-1-linear-to-x: SUCCESS (0.149s) [397.645072] Starting dynamic subtest: pipe-D-eDP-1-linear-to-linear [397.745789] Dynamic subtest pipe-D-eDP-1-linear-to-linear: SUCCESS (0.109s) [397.778506] Subtest flip-change-tiling: SUCCESS (21.747s) [398.969713] [086/131] (620s left) prime_vgem (coherency-gtt) [399.056548] Starting subtest: coherency-gtt [399.058306] Subtest coherency-gtt: SKIP (0.000s) [399.188434] [087/131] (620s left) perf_pmu (interrupts-sync) [399.222506] Starting subtest: interrupts-sync [400.318916] Subtest interrupts-sync: SUCCESS (1.094s) [400.453949] [088/131] (618s left) kms_flip (nonexisting-fb-interruptible) [400.553134] Starting subtest: nonexisting-fb-interruptible [400.555376] Starting dynamic subtest: A-eDP1 [401.752867] Dynamic subtest A-eDP1: SUCCESS (1.197s) [401.760880] Starting dynamic subtest: B-eDP1 [402.888691] Dynamic subtest B-eDP1: SUCCESS (1.135s) [402.896566] Starting dynamic subtest: C-eDP1 [404.017225] Dynamic subtest C-eDP1: SUCCESS (1.128s) [404.026732] Starting dynamic subtest: D-eDP1 [405.151223] Dynamic subtest D-eDP1: SUCCESS (1.133s) [405.154816] Subtest nonexisting-fb-interruptible: SUCCESS (4.600s) [406.328862] [089/131] (612s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [406.424480] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [406.426229] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [406.536813] [090/131] (612s left) gem_render_copy (yf-tiled-ccs-to-y-tiled) [406.589248] Starting subtest: yf-tiled-ccs-to-y-tiled [406.591053] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s) [406.790492] [091/131] (612s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling) [406.879556] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling [406.880625] Starting dynamic subtest: pipe-A-default-mode [409.560401] Dynamic subtest pipe-A-default-mode: SUCCESS (2.680s) [409.561397] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.681s) [410.410793] [092/131] (608s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area) [415.403166] Starting subtest: psr2-plane-move-sf-dmg-area [415.404077] Starting dynamic subtest: pipe-A-eDP-1 [420.695723] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.292s) [420.705081] Starting dynamic subtest: pipe-B-eDP-1 [425.859594] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.162s) [425.860503] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.458s) [426.752950] [093/131] (592s left) kms_psr (fbc-psr-cursor-plane-move) [426.843974] Starting subtest: fbc-psr-cursor-plane-move [426.845079] Starting dynamic subtest: eDP-1 [426.846923] Dynamic subtest eDP-1: SKIP (0.000s) [426.848823] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s) [426.933871] [094/131] (592s left) gem_spin_batch (legacy) [426.989825] Starting subtest: legacy [426.990825] Starting dynamic subtest: default [430.233673] Dynamic subtest default: SUCCESS (3.238s) [430.244130] Starting dynamic subtest: render [433.470670] Dynamic subtest render: SUCCESS (3.234s) [433.481290] Starting dynamic subtest: bsd [436.708875] Dynamic subtest bsd: SUCCESS (3.235s) [436.717069] Starting dynamic subtest: bsd1 [439.944527] Dynamic subtest bsd1: SUCCESS (3.235s) [439.955377] Starting dynamic subtest: bsd2 [443.179765] Dynamic subtest bsd2: SUCCESS (3.232s) [443.189853] Starting dynamic subtest: blt [446.420657] Dynamic subtest blt: SUCCESS (3.237s) [446.430806] Starting dynamic subtest: vebox [449.656373] Dynamic subtest vebox: SUCCESS (3.234s) [449.657359] Subtest legacy: SUCCESS (22.663s) [450.015639] [095/131] (569s left) gem_barrier_race (remote-request) [450.074049] Starting subtest: remote-request [450.075042] Starting dynamic subtest: rcs0 [455.260620] Dynamic subtest rcs0: SUCCESS (5.187s) [455.261554] Subtest remote-request: SUCCESS (5.188s) [455.419980] [096/131] (564s left) kms_lease (lessee-list) [455.513022] Starting subtest: lessee-list [455.513896] Starting dynamic subtest: pipe-A-eDP-1 [455.516978] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s) [455.525814] Starting dynamic subtest: pipe-B-eDP-1 [455.526846] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [455.534882] Starting dynamic subtest: pipe-C-eDP-1 [455.535839] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [455.543471] Starting dynamic subtest: pipe-D-eDP-1 [455.544429] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [455.545308] Subtest lessee-list: SUCCESS (0.009s) [455.652348] [097/131] (563s left) kms_flip (2x-modeset-vs-vblank-race-interruptible) [455.740794] Starting subtest: 2x-modeset-vs-vblank-race-interruptible [455.752431] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.011s) [455.831922] [098/131] (563s left) gem_ctx_create (basic-files) [455.881996] Starting subtest: basic-files [457.953553] Subtest basic-files: SUCCESS (2.071s) [459.984675] [099/131] (559s left) gen7_exec_parse (basic-allocation) [460.052050] Subtest basic-allocation: SKIP (0.000s) [460.130102] [100/131] (559s left) gem_pxp (display-protected-crc) [460.257544] Starting subtest: display-protected-crc [462.237364] Subtest display-protected-crc: SUCCESS (1.983s) [462.372238] [101/131] (557s left) syncobj_timeline (multi-wait-available-signaled) [462.422431] Starting subtest: multi-wait-available-signaled [462.423351] Subtest multi-wait-available-signaled: SUCCESS (0.000s) [462.550395] [102/131] (557s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff) [462.641455] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff [462.643234] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [462.769366] [103/131] (556s left) gem_exec_schedule (u-semaphore-codependency) [462.824983] Starting subtest: u-semaphore-codependency [462.836847] Subtest u-semaphore-codependency: SUCCESS (0.012s) [463.196412] [104/131] (556s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render) [463.289319] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render [467.707670] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.418s) [468.863934] [105/131] (550s left) kms_psr (fbc-pr-cursor-render) [468.963390] Starting subtest: fbc-pr-cursor-render [468.965163] Subtest fbc-pr-cursor-render: SKIP (0.000s) [469.055036] [106/131] (550s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt) [469.137144] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt [469.138812] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [469.257779] [107/131] (550s left) gem_exec_reloc (basic-gtt-noreloc) [469.312773] Subtest basic-gtt-noreloc: SKIP (0.000s) [469.384742] [108/131] (550s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled) [469.444379] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled [469.445282] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s) [469.555538] [109/131] (550s left) gem_mmap_wc (write-read-distinct) [469.603114] Subtest write-read-distinct: SKIP (0.000s) [469.655899] [110/131] (550s left) syncobj_wait (multi-wait-all-for-submit-submitted) [469.708979] Starting subtest: multi-wait-all-for-submit-submitted [469.819254] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.107s) [469.902707] [111/131] (550s left) kms_flip (wf_vblank-ts-check) [469.990902] Starting subtest: wf_vblank-ts-check [469.992919] Starting dynamic subtest: A-eDP1 [478.890208] Dynamic subtest A-eDP1: SUCCESS (8.898s) [478.901262] Starting dynamic subtest: B-eDP1 [487.815329] Dynamic subtest B-eDP1: SUCCESS (8.923s) [487.825565] Starting dynamic subtest: C-eDP1 [496.741244] Dynamic subtest C-eDP1: SUCCESS (8.921s) [496.753986] Starting dynamic subtest: D-eDP1 [505.674383] Dynamic subtest D-eDP1: SUCCESS (8.933s) [505.678392] Subtest wf_vblank-ts-check: SUCCESS (35.686s) [506.911786] [112/131] (513s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip) [506.999098] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip [507.000932] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s) [507.093961] [113/131] (512s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing) [507.177543] Starting subtest: plane-all-transition-nonblocking-fencing [507.178488] Starting dynamic subtest: pipe-A-eDP-1 [509.679156] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.501s) [509.994545] Starting dynamic subtest: pipe-B-eDP-1 [513.230307] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.244s) [513.538106] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.359s) [514.362103] [114/131] (505s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move) [514.460566] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move [521.618042] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.158s) [521.780611] [115/131] (498s left) gem_exec_whisper (basic-fds) [521.847154] Starting subtest: basic-fds [528.135584] Subtest basic-fds: SUCCESS (6.287s) [528.327041] [116/131] (491s left) gen9_exec_parse (bb-start-out) [528.388074] Subtest bb-start-out: SKIP (0.000s) [528.466515] [117/131] (491s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render) [528.562064] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render [528.563842] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [528.670645] [118/131] (491s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs) [528.761904] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs [528.762891] Starting dynamic subtest: pipe-A-eDP-1 [528.771590] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [528.780332] Starting dynamic subtest: pipe-B-eDP-1 [528.788873] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [528.797158] Starting dynamic subtest: pipe-C-eDP-1 [528.805377] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [528.813431] Starting dynamic subtest: pipe-D-eDP-1 [528.821573] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [528.823173] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s) [530.972853] [119/131] (489s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy) [531.082893] Starting subtest: 2x-long-cursor-vs-flip-legacy [531.085907] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s) [531.248482] [120/131] (489s left) syncobj_wait (wait-all-for-submit-complex) [531.300770] Starting subtest: wait-all-for-submit-complex [531.517359] Subtest wait-all-for-submit-complex: SUCCESS (0.214s) [531.608533] [121/131] (488s left) i915_selftest (mock) [535.054941] Starting subtest: mock [535.056052] Starting dynamic subtest: sanitycheck [535.672449] Dynamic subtest sanitycheck: SUCCESS (0.617s) [535.680409] Starting dynamic subtest: shmem [536.293085] Dynamic subtest shmem: SUCCESS (0.618s) [536.300629] Starting dynamic subtest: fence [544.035319] Dynamic subtest fence: SUCCESS (7.740s) [544.043158] Starting dynamic subtest: scatterlist [545.641813] Dynamic subtest scatterlist: SUCCESS (1.604s) [545.650338] Starting dynamic subtest: syncmap [548.846083] Dynamic subtest syncmap: SUCCESS (3.202s) [548.854770] Starting dynamic subtest: uncore [549.445250] Dynamic subtest uncore: SUCCESS (0.598s) [549.453374] Starting dynamic subtest: ring [550.043066] Dynamic subtest ring: SUCCESS (0.596s) [550.051236] Starting dynamic subtest: engine [550.686421] Dynamic subtest engine: SUCCESS (0.641s) [550.694736] Starting dynamic subtest: timelines [554.475899] Dynamic subtest timelines: SUCCESS (3.787s) [554.483793] Starting dynamic subtest: requests [558.075971] Dynamic subtest requests: SUCCESS (3.598s) [558.084325] Starting dynamic subtest: objects [558.864546] Dynamic subtest objects: SUCCESS (0.787s) [558.872689] Starting dynamic subtest: phys [559.593753] Dynamic subtest phys: SUCCESS (0.728s) [559.601045] Starting dynamic subtest: dmabuf [560.277036] Dynamic subtest dmabuf: SUCCESS (0.682s) [560.285362] Starting dynamic subtest: vma [565.831034] Dynamic subtest vma: SUCCESS (5.551s) [565.839235] Starting dynamic subtest: evict [567.227581] Dynamic subtest evict: SUCCESS (1.395s) [567.234515] Starting dynamic subtest: gtt [570.481773] Dynamic subtest gtt: SUCCESS (3.252s) [570.489919] Starting dynamic subtest: hugepages [571.294051] Dynamic subtest hugepages: SUCCESS (0.811s) [571.302315] Starting dynamic subtest: memory_region [575.397785] Dynamic subtest memory_region: SUCCESS (4.102s) [575.399429] Subtest mock: SUCCESS (40.341s) [575.444568] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [575.445376] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true [575.447671] [122/131] (444s left) kms_vblank (ts-continuation-suspend) [576.852212] Starting subtest: ts-continuation-suspend [576.853431] Starting dynamic subtest: pipe-A-eDP-1 [593.467992] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.968s) [593.476907] Starting dynamic subtest: pipe-D-eDP-1 [611.422279] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.515s) [611.423245] Subtest ts-continuation-suspend: SUCCESS (4.486s) [618.321446] [FACT kms_vblank (ts-continuation-suspend)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [618.322826] [FACT kms_vblank (ts-continuation-suspend)] new: kernel.kmod_is_loaded.i915: true [618.326663] [123/131] (408s left) kms_ccs (crc-primary-suspend-y-tiled-ccs) [618.416129] Starting subtest: crc-primary-suspend-y-tiled-ccs [618.417077] Starting dynamic subtest: pipe-A-eDP-1 [618.420859] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [618.430387] Starting dynamic subtest: pipe-B-eDP-1 [618.434199] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [618.441596] Starting dynamic subtest: pipe-C-eDP-1 [618.445252] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [618.452346] Starting dynamic subtest: pipe-D-eDP-1 [618.455913] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [618.457610] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.004s) [620.556419] [124/131] (405s left) gem_ctx_shared (q-smoketest-all) [620.612724] Starting subtest: Q-smoketest-all [652.902678] Subtest Q-smoketest-all: SUCCESS (32.338s) [654.030959] [125/131] (372s left) kms_debugfs (display-off-read-all) [654.129248] Starting subtest: display-off-read-all [654.437371] Subtest display-off-read-all: SUCCESS (0.310s) [655.240908] [126/131] (371s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move) [655.334465] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move [655.336432] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [655.445650] [127/131] (371s left) i915_query (engine-info) [655.499737] Starting subtest: engine-info [655.503271] Subtest engine-info: SUCCESS (0.005s) [655.658582] [128/131] (370s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite) [655.737182] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite [655.739005] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [655.836290] [129/131] (370s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc) [655.932839] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc [655.934748] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [656.030395] [130/131] (370s left) syncobj_wait (invalid-reset-one-illegal-handle) [656.082120] Starting subtest: invalid-reset-one-illegal-handle [656.083253] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s) [656.184644] [131/131] (370s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled) [656.246866] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled [656.248880] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s) [656.434653] Closing watchdogs results: parsing output: 0/ for test: kms_cursor_legacy results: parsing output: 1/ for test: kms_feature_discovery results: parsing output: 2/ for test: gem_userptr_blits results: parsing output: 3/ for test: syncobj_basic results: parsing output: 4/ for test: kms_flip results: parsing output: 5/ for test: perf results: parsing output: 6/ for test: kms_big_fb results: parsing output: 7/ for test: kms_dp_aux_dev results: parsing output: 8/ for test: kms_pipe_stress results: parsing output: 9/ for test: gem_ctx_persistence results: parsing output: 10/ for test: kms_addfb_basic results: parsing output: 11/ for test: gem_ctx_exec results: parsing output: 12/ for test: gem_exec_async results: parsing output: 13/ for test: kms_chamelium_hpd results: parsing output: 14/ for test: kms_psr2_sf results: parsing output: 15/ for test: kms_frontbuffer_tracking results: parsing output: 16/ for test: gem_exec_fence results: parsing output: 17/ for test: gem_fenced_exec_thrash results: parsing output: 18/ for test: gem_mmap_offset results: parsing output: 19/ for test: kms_psr results: parsing output: 20/ for test: kms_pwrite_crc results: parsing output: 21/ for test: kms_vblank results: parsing output: 22/ for test: kms_pm_dc results: parsing output: 23/ for test: kms_cursor_crc results: parsing output: 24/ for test: kms_joiner results: parsing output: 25/ for test: core_hotunplug results: parsing output: 26/ for test: gem_exec_nop results: parsing output: 27/ for test: kms_flip results: parsing output: 28/ for test: kms_big_fb results: parsing output: 29/ for test: api_intel_allocator results: parsing output: 30/ for test: kms_ccs results: parsing output: 31/ for test: kms_async_flips results: parsing output: 32/ for test: kms_frontbuffer_tracking results: parsing output: 33/ for test: kms_psr results: parsing output: 34/ for test: gem_ccs results: parsing output: 35/ for test: kms_plane_scaling results: parsing output: 36/ for test: kms_pipe_b_c_ivb results: parsing output: 37/ for test: kms_sequence results: parsing output: 38/ for test: perf results: parsing output: 39/ for test: kms_cursor_crc results: parsing output: 40/ for test: gem_ctx_param results: parsing output: 41/ for test: kms_cursor_legacy results: parsing output: 42/ for test: syncobj_timeline results: parsing output: 43/ for test: kms_chamelium_color results: parsing output: 44/ for test: kms_big_fb results: parsing output: 45/ for test: syncobj_wait results: parsing output: 46/ for test: gem_exec_schedule results: parsing output: 47/ for test: i915_module_load results: parsing output: 48/ for test: kms_big_fb results: parsing output: 49/ for test: gem_lmem_swapping results: parsing output: 50/ for test: kms_ccs results: parsing output: 51/ for test: vgem_basic results: parsing output: 52/ for test: perf_pmu results: parsing output: 53/ for test: kms_frontbuffer_tracking results: parsing output: 54/ for test: kms_atomic_transition results: parsing output: 55/ for test: kms_frontbuffer_tracking results: parsing output: 56/ for test: gem_flink_basic results: parsing output: 57/ for test: gem_exec_params results: parsing output: 58/ for test: kms_plane_scaling results: parsing output: 59/ for test: gem_mmap_gtt results: parsing output: 60/ for test: kms_frontbuffer_tracking results: parsing output: 61/ for test: kms_frontbuffer_tracking results: parsing output: 62/ for test: kms_big_fb results: parsing output: 63/ for test: gem_readwrite results: parsing output: 64/ for test: kms_frontbuffer_tracking results: parsing output: 65/ for test: kms_frontbuffer_tracking results: parsing output: 66/ for test: gem_exec_fence results: parsing output: 67/ for test: api_intel_bb results: parsing output: 68/ for test: syncobj_timeline results: parsing output: 69/ for test: kms_addfb_basic results: parsing output: 70/ for test: kms_psr results: parsing output: 71/ for test: kms_color results: parsing output: 72/ for test: kms_frontbuffer_tracking results: parsing output: 73/ for test: gem_exec_balancer results: parsing output: 74/ for test: kms_frontbuffer_tracking results: parsing output: 75/ for test: kms_plane_multiple results: parsing output: 76/ for test: kms_prop_blob results: parsing output: 77/ for test: gem_exec_schedule results: parsing output: 78/ for test: prime_busy results: parsing output: 79/ for test: kms_cursor_crc results: parsing output: 80/ for test: kms_frontbuffer_tracking results: parsing output: 81/ for test: kms_busy results: parsing output: 82/ for test: syncobj_timeline results: parsing output: 83/ for test: i915_hangman results: parsing output: 84/ for test: kms_flip_tiling results: parsing output: 85/ for test: prime_vgem results: parsing output: 86/ for test: perf_pmu results: parsing output: 87/ for test: kms_flip results: parsing output: 88/ for test: kms_frontbuffer_tracking results: parsing output: 89/ for test: gem_render_copy results: parsing output: 90/ for test: kms_flip_scaled_crc results: parsing output: 91/ for test: kms_psr2_sf results: parsing output: 92/ for test: kms_psr results: parsing output: 93/ for test: gem_spin_batch results: parsing output: 94/ for test: gem_barrier_race results: parsing output: 95/ for test: kms_lease results: parsing output: 96/ for test: kms_flip results: parsing output: 97/ for test: gem_ctx_create results: parsing output: 98/ for test: gen7_exec_parse results: parsing output: 99/ for test: gem_pxp results: parsing output: 100/ for test: syncobj_timeline results: parsing output: 101/ for test: kms_frontbuffer_tracking results: parsing output: 102/ for test: gem_exec_schedule results: parsing output: 103/ for test: kms_frontbuffer_tracking results: parsing output: 104/ for test: kms_psr results: parsing output: 105/ for test: kms_frontbuffer_tracking results: parsing output: 106/ for test: gem_exec_reloc results: parsing output: 107/ for test: syncobj_timeline results: parsing output: 108/ for test: gem_mmap_wc results: parsing output: 109/ for test: syncobj_wait results: parsing output: 110/ for test: kms_flip results: parsing output: 111/ for test: kms_big_fb results: parsing output: 112/ for test: kms_atomic_transition results: parsing output: 113/ for test: kms_frontbuffer_tracking results: parsing output: 114/ for test: gem_exec_whisper results: parsing output: 115/ for test: gen9_exec_parse results: parsing output: 116/ for test: kms_frontbuffer_tracking results: parsing output: 117/ for test: kms_ccs results: parsing output: 118/ for test: kms_cursor_legacy results: parsing output: 119/ for test: syncobj_wait results: parsing output: 120/ for test: i915_selftest results: parsing output: 121/ for test: kms_vblank results: parsing output: 122/ for test: kms_ccs results: parsing output: 123/ for test: gem_ctx_shared results: parsing output: 124/ for test: kms_debugfs results: parsing output: 125/ for test: kms_frontbuffer_tracking results: parsing output: 126/ for test: i915_query results: parsing output: 127/ for test: kms_frontbuffer_tracking results: parsing output: 128/ for test: kms_frontbuffer_tracking results: parsing output: 129/ for test: syncobj_wait results: parsing output: 130/ for test: gem_render_copy Done.