[55.372369] Initializing watchdogs
[55.372419]   /dev/watchdog0
[55.386820] [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.391375] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.393576] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.396939] [001/133] (960s left) gem_wait (invalid-buf)
[55.487141] Starting subtest: invalid-buf
[55.489351] Subtest invalid-buf: SUCCESS (0.000s)
[55.731751] [002/133] (960s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[55.839593] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[55.843578] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[55.947399] [003/133] (960s left) prime_mmap (test_invalid_sync_flags)
[55.998719] Starting subtest: test_invalid_sync_flags
[56.001130] Starting dynamic subtest: test_invalid_sync_flags-smem
[56.003411] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[56.005600] Subtest test_invalid_sync_flags: SUCCESS (0.000s)
[56.093210] [004/133] (959s left) gem_exec_schedule (smoketest-all)
[56.163353] Starting subtest: smoketest-all
[88.448258] Subtest smoketest-all: SUCCESS (32.322s)
[89.379180] [005/133] (926s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[89.484228] Starting subtest: psr-2p-primscrn-spr-indfb-move
[89.488145] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[89.577039] [006/133] (926s left) gem_exec_reloc (basic-gtt-cpu-active)
[89.643154] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[89.700463] [007/133] (926s left) gem_ctx_engines (none)
[89.748622] Starting subtest: none
[89.751323] Subtest none: SUCCESS (0.001s)
[89.922227] [008/133] (926s left) syncobj_timeline (invalid-wait-illegal-handle)
[89.973647] Starting subtest: invalid-wait-illegal-handle
[89.975572] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[90.081108] [009/133] (926s left) kms_chamelium_color (ctm-green-to-red)
[90.175316] Subtest ctm-green-to-red: SKIP (0.000s)
[90.222688] [010/133] (926s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[90.324135] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[90.328368] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[90.416154] [011/133] (925s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[90.496069] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[90.539433] Starting dynamic subtest: pipe-A-plane-0
[90.851297] Dynamic subtest pipe-A-plane-0: SUCCESS (0.311s)
[90.859598] Starting dynamic subtest: pipe-A-plane-3
[91.083647] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[92.192198] Starting dynamic subtest: pipe-B-plane-0
[92.496882] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[92.504920] Starting dynamic subtest: pipe-B-plane-3
[92.730326] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[93.044798] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.548s)
[93.863931] [012/133] (922s left) perf (whitelisted-registers-userspace-config)
[93.988632] Starting subtest: whitelisted-registers-userspace-config
[93.990967] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[94.030461] [013/133] (922s left) kms_atomic (crtc-invalid-params-fence)
[94.115252] Starting subtest: crtc-invalid-params-fence
[94.117607] Starting dynamic subtest: pipe-A-eDP-1
[94.539913] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[94.542273] Subtest crtc-invalid-params-fence: SUCCESS (0.423s)
[95.393359] [014/133] (920s left) gem_exec_params (rs-invalid)
[95.450122] Starting subtest: rs-invalid
[95.455200] Subtest rs-invalid: SUCCESS (0.003s)
[95.575075] [015/133] (920s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[95.667265] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[95.671490] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[95.748672] [016/133] (920s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[95.846293] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[96.647547] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.803s)
[96.808540] [017/133] (919s left) kms_psr (fbc-psr-dpms)
[96.908891] Starting subtest: fbc-psr-dpms
[96.911313] Starting dynamic subtest: eDP-1
[96.915736] Dynamic subtest eDP-1: SKIP (0.000s)
[96.920224] Subtest fbc-psr-dpms: SKIP (0.001s)
[96.978944] [018/133] (919s left) gem_mmap_gtt (basic-small-copy-xy)
[97.032495] Subtest basic-small-copy-XY: SKIP (0.000s)
[97.091222] [019/133] (919s left) gem_exec_schedule (preempt-self)
[97.159683] Starting subtest: preempt-self
[97.161582] Starting dynamic subtest: rcs0
[97.173334] Dynamic subtest rcs0: SUCCESS (0.021s)
[97.181305] Starting dynamic subtest: bcs0
[97.189117] Dynamic subtest bcs0: SUCCESS (0.015s)
[97.196818] Starting dynamic subtest: vcs0
[97.211594] Dynamic subtest vcs0: SUCCESS (0.022s)
[97.219265] Starting dynamic subtest: vcs1
[97.233345] Dynamic subtest vcs1: SUCCESS (0.022s)
[97.244473] Starting dynamic subtest: vecs0
[97.257762] Dynamic subtest vecs0: SUCCESS (0.024s)
[97.265775] Starting dynamic subtest: ccs0
[97.279736] Dynamic subtest ccs0: SUCCESS (0.021s)
[97.282071] Subtest preempt-self: SUCCESS (0.128s)
[98.404022] [020/133] (918s left) sysfs_heartbeat_interval (idempotent)
[98.466973] Starting subtest: idempotent
[98.469292] Starting dynamic subtest: vecs0
[98.471676] Dynamic subtest vecs0: SUCCESS (0.001s)
[98.480838] Starting dynamic subtest: ccs0
[98.483078] Dynamic subtest ccs0: SUCCESS (0.001s)
[98.490671] Starting dynamic subtest: vcs0
[98.492895] Dynamic subtest vcs0: SUCCESS (0.000s)
[98.500659] Starting dynamic subtest: bcs0
[98.502876] Dynamic subtest bcs0: SUCCESS (0.001s)
[98.510360] Starting dynamic subtest: rcs0
[98.512569] Dynamic subtest rcs0: SUCCESS (0.000s)
[98.520401] Starting dynamic subtest: vcs1
[98.522262] Dynamic subtest vcs1: SUCCESS (0.000s)
[98.524098] Subtest idempotent: SUCCESS (0.006s)
[98.572784] [021/133] (918s left) gem_ctx_bad_destroy (invalid-pad)
[98.624606] Starting subtest: invalid-pad
[98.627068] Subtest invalid-pad: SUCCESS (0.000s)
[98.685313] [022/133] (918s left) kms_pm_rpm (i2c)
[100.414437] Starting subtest: i2c
[105.918757] Subtest i2c: SUCCESS (5.506s)
[106.013250] [023/133] (910s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[106.106748] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[106.109146] Starting dynamic subtest: pipe-A-eDP-1
[106.604662] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.470s)
[106.614905] Starting dynamic subtest: pipe-B-eDP-1
[108.071850] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.467s)
[108.082205] Starting dynamic subtest: pipe-C-eDP-1
[109.434190] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.360s)
[109.442884] Starting dynamic subtest: pipe-D-eDP-1
[110.842880] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.409s)
[110.845185] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.709s)
[112.026262] [024/133] (904s left) gem_exec_whisper (basic-queues-priority)
[112.107075] Starting subtest: basic-queues-priority
[136.385122] Subtest basic-queues-priority: SUCCESS (24.277s)
[136.566840] [025/133] (880s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[136.684424] Starting subtest: x-tiled-addfb-size-offset-overflow
[136.686719] Subtest x-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[136.787079] [026/133] (880s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[136.890577] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[136.895043] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[136.983919] [027/133] (879s left) api_intel_allocator (open-vm)
[137.042986] Starting subtest: open-vm
[137.045397] Subtest open-vm: SUCCESS (0.004s)
[137.147681] [028/133] (879s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[137.249325] Starting subtest: y-tiled-64bpp-rotate-90
[137.253518] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[137.336928] [029/133] (879s left) kms_dsc (dsc-fractional-bpp)
[137.433394] Starting subtest: dsc-fractional-bpp
[137.446676] Subtest dsc-fractional-bpp: SKIP (0.000s)
[137.506332] [030/133] (879s left) gem_ctx_freq (sysfs)
[137.567249] Starting subtest: sysfs
[137.569344] Starting dynamic subtest: gt0
[143.081776] Dynamic subtest gt0: SUCCESS (5.516s)
[143.092800] Starting dynamic subtest: gt1
[184.316890] Dynamic subtest gt1: SUCCESS (41.231s)
[184.319675] Subtest sysfs: SUCCESS (46.748s)
[184.458967] [031/133] (832s left) kms_flip (bo-too-big)
[184.554883] Starting subtest: bo-too-big
[184.557261] Starting dynamic subtest: A-eDP1
[185.865704] Dynamic subtest A-eDP1: SUCCESS (1.310s)
[185.876127] Starting dynamic subtest: B-eDP1
[187.200387] Dynamic subtest B-eDP1: SUCCESS (1.334s)
[187.210363] Starting dynamic subtest: C-eDP1
[188.532405] Dynamic subtest C-eDP1: SUCCESS (1.331s)
[188.543848] Starting dynamic subtest: D-eDP1
[189.856894] Dynamic subtest D-eDP1: SUCCESS (1.324s)
[189.863236] Subtest bo-too-big: SUCCESS (5.310s)
[191.069391] [032/133] (826s left) kms_psr (pr-sprite-render)
[191.164843] Starting subtest: pr-sprite-render
[191.169352] Subtest pr-sprite-render: SKIP (0.000s)
[191.252577] [033/133] (825s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[191.349368] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[195.672422] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.326s)
[196.821038] [034/133] (820s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[196.922312] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[196.926746] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[197.027743] [035/133] (820s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[197.149841] Starting subtest: cursorA-vs-flipB-atomic-transitions
[197.154234] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[197.293458] [036/133] (819s left) kms_plane (plane-position-hole)
[197.392898] Starting subtest: plane-position-hole
[197.894670] Starting dynamic subtest: pipe-A-plane-1
[199.157972] Dynamic subtest pipe-A-plane-1: SUCCESS (1.264s)
[199.168844] Starting dynamic subtest: pipe-A-plane-2
[200.405576] Dynamic subtest pipe-A-plane-2: SUCCESS (1.247s)
[200.416029] Starting dynamic subtest: pipe-A-plane-3
[201.749685] Dynamic subtest pipe-A-plane-3: SUCCESS (1.344s)
[201.759863] Starting dynamic subtest: pipe-A-plane-4
[203.061049] Dynamic subtest pipe-A-plane-4: SUCCESS (1.310s)
[203.070730] Starting dynamic subtest: pipe-A-plane-5
[203.086163] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[204.233847] Starting dynamic subtest: pipe-B-plane-1
[205.555521] Dynamic subtest pipe-B-plane-1: SUCCESS (1.331s)
[205.566102] Starting dynamic subtest: pipe-B-plane-2
[206.877941] Dynamic subtest pipe-B-plane-2: SUCCESS (1.321s)
[206.888667] Starting dynamic subtest: pipe-B-plane-3
[208.193893] Dynamic subtest pipe-B-plane-3: SUCCESS (1.314s)
[208.203933] Starting dynamic subtest: pipe-B-plane-4
[209.439404] Dynamic subtest pipe-B-plane-4: SUCCESS (1.247s)
[209.449836] Starting dynamic subtest: pipe-B-plane-5
[209.464146] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[209.466418] Subtest plane-position-hole: SUCCESS (12.071s)
[210.321324] [037/133] (806s left) gem_ctx_param (set-priority-range)
[210.380031] Starting subtest: set-priority-range
[210.392626] Subtest set-priority-range: SUCCESS (0.015s)
[210.517109] [038/133] (806s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[210.629306] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[210.633419] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[210.699689] [039/133] (806s left) kms_plane_multiple (2x-tiling-x)
[210.791539] Starting subtest: 2x-tiling-x
[210.795882] Subtest 2x-tiling-x: SKIP (0.000s)
[210.858760] [040/133] (806s left) kms_psr (psr-sprite-plane-move)
[210.945393] Starting subtest: psr-sprite-plane-move
[210.947683] Starting dynamic subtest: eDP-1
[212.676974] Dynamic subtest eDP-1: SUCCESS (1.731s)
[212.679309] Subtest psr-sprite-plane-move: SUCCESS (1.731s)
[213.506348] [041/133] (803s left) kms_psr (psr2-sprite-plane-move)
[213.609293] Starting subtest: psr2-sprite-plane-move
[213.611718] Starting dynamic subtest: eDP-1
[215.326299] Dynamic subtest eDP-1: SUCCESS (1.717s)
[215.328200] Subtest psr2-sprite-plane-move: SUCCESS (1.718s)
[216.186425] [042/133] (801s left) kms_properties (plane-properties-atomic)
[216.288267] Starting subtest: plane-properties-atomic
[216.290611] Starting dynamic subtest: pipe-A-eDP-1
[216.851554] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.562s)
[216.861370] Starting dynamic subtest: pipe-B-eDP-1
[218.172579] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.321s)
[218.182236] Starting dynamic subtest: pipe-C-eDP-1
[219.492218] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.318s)
[219.501514] Starting dynamic subtest: pipe-D-eDP-1
[220.729614] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.237s)
[220.731949] Subtest plane-properties-atomic: SUCCESS (4.442s)
[221.529271] [043/133] (796s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[221.631315] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[221.635644] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[221.727409] [044/133] (795s left) gem_ctx_param (invalid-ctx-get)
[221.788125] Starting subtest: invalid-ctx-get
[221.790503] Subtest invalid-ctx-get: SUCCESS (0.000s)
[221.859444] [045/133] (795s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[221.962416] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[229.331907] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.373s)
[229.498662] [046/133] (788s left) i915_pm_freq_api (freq-reset-multiple)
[229.571580] Starting subtest: freq-reset-multiple
[229.574104] Starting dynamic subtest: gt0
[239.203059] Dynamic subtest gt0: SUCCESS (9.629s)
[239.211877] Starting dynamic subtest: gt1
[247.459385] Dynamic subtest gt1: SUCCESS (8.256s)
[247.462051] Subtest freq-reset-multiple: SUCCESS (17.887s)
[247.555098] [047/133] (770s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[247.669136] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[255.135412] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.470s)
[255.251226] [048/133] (762s left) gem_userptr_blits (stress-purge)
[255.331733] Starting subtest: stress-purge
[260.736828] Subtest stress-purge: SUCCESS (5.408s)
[260.860547] [049/133] (756s left) kms_cursor_crc (cursor-random-64x21)
[261.110077] Starting subtest: cursor-random-64x21
[261.159108] Subtest cursor-random-64x21: SKIP (0.045s)
[261.410545] [050/133] (756s left) kms_cursor_crc (cursor-offscreen-32x10)
[261.564279] Starting subtest: cursor-offscreen-32x10
[261.610634] Subtest cursor-offscreen-32x10: SKIP (0.042s)
[261.872414] [051/133] (756s left) dmabuf (all-tests)
[261.916826] Starting subtest: all-tests
[261.920847] Subtest all-tests: SKIP (0.000s)
[261.999270] [052/133] (755s left) gem_eio (wait-wedge-1us)
[262.229191] Starting subtest: wait-wedge-1us
[263.365528] Subtest wait-wedge-1us: SUCCESS (1.134s)
[263.530803] [053/133] (754s left) kms_flip (modeset-vs-vblank-race)
[263.618163] Starting subtest: modeset-vs-vblank-race
[263.620012] Starting dynamic subtest: A-eDP1
[268.339241] Dynamic subtest A-eDP1: SUCCESS (4.722s)
[268.352172] Starting dynamic subtest: B-eDP1
[273.078161] Dynamic subtest B-eDP1: SUCCESS (4.736s)
[273.092049] Starting dynamic subtest: C-eDP1
[277.665166] Dynamic subtest C-eDP1: SUCCESS (4.584s)
[277.675224] Starting dynamic subtest: D-eDP1
[282.312622] Dynamic subtest D-eDP1: SUCCESS (4.646s)
[282.317390] Subtest modeset-vs-vblank-race: SUCCESS (18.702s)
[283.488250] [054/133] (734s left) gem_mmap_wc (bad-object)
[283.544195] Subtest bad-object: SKIP (0.000s)
[283.600403] [055/133] (734s left) gem_exec_fence (expired-history)
[284.094878] Starting subtest: expired-history
[291.036550] Subtest expired-history: SUCCESS (6.942s)
[293.348162] [FACT gem_exec_fence (expired-history)] new: kernel.kmod_is_loaded.vgem: true
[293.351451] [056/133] (724s left) syncobj_basic (bad-pad-handle-to-fd)
[293.406482] Starting subtest: bad-pad-handle-to-fd
[293.408900] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[293.515499] [057/133] (724s left) gem_spin_batch (spin-all-new)
[293.578049] Starting subtest: spin-all-new
[293.599883] Subtest spin-all-new: SUCCESS (0.022s)
[295.352773] [058/133] (722s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[295.419211] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[295.522487] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.104s)
[295.620816] [059/133] (722s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[295.753609] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[327.815754] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (32.059s)
[328.615047] [060/133] (689s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[328.734117] Starting subtest: fbcpsr-tiling-linear
[335.371305] Subtest fbcpsr-tiling-linear: SUCCESS (6.640s)
[335.498682] [061/133] (682s left) gem_unref_active_buffers
[335.721957] [062/133] (682s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[335.829791] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[342.470496] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.644s)
[342.589466] [063/133] (675s left) i915_query (engine-info-invalid)
[342.650050] Starting subtest: engine-info-invalid
[342.651961] Subtest engine-info-invalid: SUCCESS (0.000s)
[342.745226] [064/133] (675s left) kms_cursor_legacy (forked-move)
[342.867141] Starting subtest: forked-move
[342.869468] Starting dynamic subtest: pipe-A
[348.335962] Dynamic subtest pipe-A: SUCCESS (5.464s)
[348.348593] Starting dynamic subtest: pipe-B
[353.821469] Dynamic subtest pipe-B: SUCCESS (5.483s)
[353.839078] Starting dynamic subtest: pipe-C
[359.304928] Dynamic subtest pipe-C: SUCCESS (5.482s)
[359.315701] Starting dynamic subtest: pipe-D
[364.794033] Dynamic subtest pipe-D: SUCCESS (5.486s)
[364.806600] Starting dynamic subtest: all-pipes
[370.314158] Dynamic subtest all-pipes: SUCCESS (5.518s)
[370.316456] Subtest forked-move: SUCCESS (27.444s)
[370.463687] [065/133] (648s left) gem_exec_fence (submit-chain)
[370.657434] Starting subtest: submit-chain
[370.677897] Subtest submit-chain: SUCCESS (0.021s)
[372.367152] [066/133] (646s left) gem_workarounds (basic-read-context)
[372.437497] Starting subtest: basic-read-context
[372.446445] Subtest basic-read-context: SUCCESS (0.010s)
[372.548533] [067/133] (646s left) kms_pipe_crc_basic (suspend-read-crc)
[372.643189] Starting subtest: suspend-read-crc
[372.645539] Starting dynamic subtest: pipe-A-eDP-1
[390.452960] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.639s)
[390.464613] Starting dynamic subtest: pipe-B-eDP-1
[408.543420] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.240s)
[408.555627] Starting dynamic subtest: pipe-C-eDP-1
[426.431985] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.070s)
[426.444285] Starting dynamic subtest: pipe-D-eDP-1
[444.427304] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.096s)
[444.430300] Subtest suspend-read-crc: SUCCESS (12.052s)
[445.242105] [068/133] (573s left) perf (sysctl-defaults)
[445.335260] Starting subtest: sysctl-defaults
[445.338176] Subtest sysctl-defaults: SUCCESS (0.000s)
[445.402630] [069/133] (573s left) kms_legacy_colorkey (invalid-plane)
[445.502830] Starting subtest: invalid-plane
[445.505459] Starting dynamic subtest: zero-id
[445.507940] Dynamic subtest zero-id: SUCCESS (0.000s)
[445.521714] Starting dynamic subtest: outrange-id
[445.526269] Dynamic subtest outrange-id: SUCCESS (0.000s)
[445.528798] Subtest invalid-plane: SUCCESS (0.001s)
[445.576393] [070/133] (573s left) gem_exec_balancer (parallel-balancer)
[445.835576] Starting subtest: parallel-balancer
[446.800096] Subtest parallel-balancer: SUCCESS (0.977s)
[446.904173] [071/133] (571s left) kms_psr (fbc-pr-sprite-plane-onoff)
[447.000133] Starting subtest: fbc-pr-sprite-plane-onoff
[447.005181] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[447.072818] [072/133] (571s left) gem_lmem_swapping (verify-random-ccs)
[447.123997] Subtest verify-random-ccs: SKIP (0.000s)
[447.175034] [073/133] (571s left) gem_ctx_shared (exec-single-timeline)
[447.243074] Starting subtest: exec-single-timeline
[447.245947] Starting dynamic subtest: rcs0
[448.319118] Dynamic subtest rcs0: SUCCESS (1.089s)
[448.331983] Starting dynamic subtest: bcs0
[449.407904] Dynamic subtest bcs0: SUCCESS (1.094s)
[449.422037] Starting dynamic subtest: vcs0
[450.496978] Dynamic subtest vcs0: SUCCESS (1.094s)
[450.511221] Starting dynamic subtest: vcs1
[451.586120] Dynamic subtest vcs1: SUCCESS (1.092s)
[451.598546] Starting dynamic subtest: vecs0
[452.671854] Dynamic subtest vecs0: SUCCESS (1.094s)
[452.686010] Starting dynamic subtest: ccs0
[453.760195] Dynamic subtest ccs0: SUCCESS (1.091s)
[453.763224] Subtest exec-single-timeline: SUCCESS (6.571s)
[453.954028] [074/133] (564s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[454.056108] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[460.867186] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.837s)
[460.993612] [075/133] (557s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[461.092239] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[461.097364] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[461.205738] [076/133] (557s left) gem_ctx_persistence (legacy-engines-hang)
[461.272340] Starting subtest: legacy-engines-hang
[461.274714] Starting dynamic subtest: render
[461.329914] Dynamic subtest render: SUCCESS (0.061s)
[461.342927] Starting dynamic subtest: bsd1
[461.419871] Dynamic subtest bsd1: SUCCESS (0.087s)
[461.432651] Starting dynamic subtest: bsd2
[461.507840] Dynamic subtest bsd2: SUCCESS (0.085s)
[461.521468] Starting dynamic subtest: blt
[461.597478] Dynamic subtest blt: SUCCESS (0.088s)
[461.615081] Starting dynamic subtest: vebox
[461.695486] Dynamic subtest vebox: SUCCESS (0.094s)
[461.698310] Subtest legacy-engines-hang: SUCCESS (0.429s)
[461.808135] [077/133] (557s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[461.902329] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[461.905295] Starting dynamic subtest: pipe-A-eDP-1
[462.730181] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.821s)
[462.741340] Starting dynamic subtest: pipe-B-eDP-1
[464.353899] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.623s)
[464.367832] Starting dynamic subtest: pipe-C-eDP-1
[465.852205] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.501s)
[465.863458] Starting dynamic subtest: pipe-D-eDP-1
[467.423472] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.572s)
[467.426255] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.522s)
[468.562449] [078/133] (550s left) i915_drm_fdinfo (virtual-busy-all)
[468.606784] Starting subtest: virtual-busy-all
[468.612199] Subtest virtual-busy-all: SKIP (0.000s)
[468.683173] [079/133] (550s left) kms_vblank (query-busy-hang)
[468.774302] Starting subtest: query-busy-hang
[468.776699] Starting dynamic subtest: pipe-A-eDP-1
[494.944432] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.177s)
[494.961748] Starting dynamic subtest: pipe-D-eDP-1
[519.923479] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.986s)
[519.926270] Subtest query-busy-hang: SUCCESS (51.166s)
[520.712988] [080/133] (498s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[521.108193] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[521.919598] [081/133] (497s left) api_intel_bb (delta-check)
[521.978478] Starting subtest: delta-check
[521.982365] Subtest delta-check: SUCCESS (0.006s)
[522.097218] [082/133] (497s left) kms_pm_rpm (basic-pci-d3-state)
[524.328067] Starting subtest: basic-pci-d3-state
[525.818201] Subtest basic-pci-d3-state: SUCCESS (1.493s)
[525.913881] [083/133] (493s left) kms_properties (get_properties-sanity-atomic)
[526.019799] Starting subtest: get_properties-sanity-atomic
[526.022386] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[526.096251] [084/133] (493s left) syncobj_timeline (host-signal-points)
[526.155340] Starting subtest: host-signal-points
[526.157943] Subtest host-signal-points: SUCCESS (0.004s)
[526.259201] [085/133] (493s left) gem_exec_params (invalid-ring2)
[526.319604] Starting subtest: invalid-ring2
[526.322478] Subtest invalid-ring2: SUCCESS (0.000s)
[526.427683] [086/133] (493s left) perf (oa-exponents)
[526.512321] Starting subtest: oa-exponents
[526.515087] Starting dynamic subtest: 0-rcs0
[528.277216] Dynamic subtest 0-rcs0: SUCCESS (1.767s)
[528.290124] Starting dynamic subtest: 1-vcs1
[530.105461] Dynamic subtest 1-vcs1: SUCCESS (1.828s)
[530.108151] Subtest oa-exponents: SUCCESS (3.597s)
[530.158224] [087/133] (489s left) gem_mmap_offset (clear)
[530.226176] Starting subtest: clear
[530.228929] Starting dynamic subtest: smem0
[555.619215] Dynamic subtest smem0: SUCCESS (25.384s)
[555.621696] Subtest clear: SUCCESS (25.386s)
[555.753223] [088/133] (463s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[555.910604] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[562.472189] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.566s)
[562.591839] [089/133] (457s left) kms_psr (fbc-pr-primary-blt)
[562.692691] Starting subtest: fbc-pr-primary-blt
[562.697977] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[562.768739] [090/133] (456s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[562.857738] Starting subtest: plane-upscale-20x20-with-modifiers
[562.860290] Starting dynamic subtest: pipe-A
[563.194460] Dynamic subtest pipe-A: SUCCESS (0.338s)
[563.205686] Starting dynamic subtest: pipe-B
[564.592134] Dynamic subtest pipe-B: SUCCESS (1.397s)
[564.603548] Starting dynamic subtest: pipe-C
[566.022320] Dynamic subtest pipe-C: SUCCESS (1.429s)
[566.033404] Starting dynamic subtest: pipe-D
[567.450330] Dynamic subtest pipe-D: SUCCESS (1.427s)
[567.453294] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.594s)
[568.623309] [091/133] (451s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[568.718789] Starting subtest: bad-aux-stride-yf-tiled-ccs
[568.721301] Starting dynamic subtest: pipe-A-eDP-1
[568.748336] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[568.761589] Starting dynamic subtest: pipe-B-eDP-1
[568.787320] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[568.798300] Starting dynamic subtest: pipe-C-eDP-1
[568.823924] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[568.834911] Starting dynamic subtest: pipe-D-eDP-1
[568.860509] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[568.865341] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.005s)
[570.924969] [092/133] (448s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[571.304938] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[572.119719] [093/133] (447s left) kms_content_protection (dp-mst-type-0)
[572.265456] Starting subtest: dp-mst-type-0
[572.271248] Subtest dp-mst-type-0: SKIP (0.000s)
[572.392067] [094/133] (447s left) kms_vblank (query-forked)
[572.488292] Starting subtest: query-forked
[572.490743] Starting dynamic subtest: pipe-A-eDP-1
[574.334360] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.846s)
[574.350496] Starting dynamic subtest: pipe-D-eDP-1
[576.832335] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.495s)
[576.835326] Subtest query-forked: SUCCESS (4.345s)
[577.705502] [095/133] (442s left) perf_pmu (busy-check-all)
[577.759859] Starting subtest: busy-check-all
[577.762346] Starting dynamic subtest: rcs0
[578.311506] Dynamic subtest rcs0: SUCCESS (0.551s)
[578.325507] Starting dynamic subtest: bcs0
[578.862464] Dynamic subtest bcs0: SUCCESS (0.548s)
[578.876831] Starting dynamic subtest: vcs0
[580.196787] Dynamic subtest vcs0: SUCCESS (1.332s)
[580.210712] Starting dynamic subtest: vcs1
[582.179749] Dynamic subtest vcs1: SUCCESS (1.979s)
[582.195164] Starting dynamic subtest: vecs0
[584.163439] Dynamic subtest vecs0: SUCCESS (1.981s)
[584.177462] Starting dynamic subtest: ccs0
[584.721889] Dynamic subtest ccs0: SUCCESS (0.557s)
[584.724659] Subtest busy-check-all: SUCCESS (6.964s)
[584.816213] [096/133] (435s left) gem_linear_blits (basic)
[585.001837] Starting subtest: basic
[585.016104] Subtest basic: SUCCESS (0.015s)
[585.115233] [097/133] (434s left) syncobj_timeline (reset-during-wait-for-submit)
[585.219381] Starting subtest: reset-during-wait-for-submit
[585.321607] Subtest reset-during-wait-for-submit: SUCCESS (0.102s)
[585.407654] [098/133] (434s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[585.513480] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[589.270485] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.761s)
[590.409626] [099/133] (429s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[590.514766] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[590.520020] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[590.598876] [100/133] (429s left) gen9_exec_parse (bb-secure)
[590.664534] Subtest bb-secure: SKIP (0.000s)
[590.737789] [101/133] (429s left) gem_exec_schedule (preempt-contexts)
[590.824385] Starting subtest: preempt-contexts
[590.826781] Starting dynamic subtest: rcs0
[590.877067] Dynamic subtest rcs0: SUCCESS (0.061s)
[590.888287] Starting dynamic subtest: bcs0
[590.929609] Dynamic subtest bcs0: SUCCESS (0.052s)
[590.940377] Starting dynamic subtest: vcs0
[590.983003] Dynamic subtest vcs0: SUCCESS (0.052s)
[590.993996] Starting dynamic subtest: vcs1
[591.057453] Dynamic subtest vcs1: SUCCESS (0.073s)
[591.069136] Starting dynamic subtest: vecs0
[591.130183] Dynamic subtest vecs0: SUCCESS (0.070s)
[591.140946] Starting dynamic subtest: ccs0
[591.176237] Dynamic subtest ccs0: SUCCESS (0.045s)
[591.178538] Subtest preempt-contexts: SUCCESS (0.361s)
[592.259163] [102/133] (427s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[592.357460] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[592.362444] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[592.446976] [103/133] (427s left) kms_flip_event_leak (basic)
[592.546406] Starting subtest: basic
[592.548934] Starting dynamic subtest: pipe-A-eDP-1
[592.975136] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.428s)
[592.986906] Starting dynamic subtest: pipe-B-eDP-1
[594.122152] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.145s)
[594.133474] Starting dynamic subtest: pipe-C-eDP-1
[595.229479] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.108s)
[595.240403] Starting dynamic subtest: pipe-D-eDP-1
[596.356146] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[596.358732] Subtest basic: SUCCESS (3.811s)
[597.230529] [104/133] (423s left) gem_exec_schedule (wide)
[597.412447] Starting subtest: wide
[597.415280] Starting dynamic subtest: rcs0
[608.021644] Dynamic subtest rcs0: SUCCESS (10.610s)
[608.036481] Starting dynamic subtest: vcs0
[618.681534] Dynamic subtest vcs0: SUCCESS (10.660s)
[618.695489] Starting dynamic subtest: vcs1
[629.323847] Dynamic subtest vcs1: SUCCESS (10.643s)
[629.336187] Starting dynamic subtest: vecs0
[641.810914] Dynamic subtest vecs0: SUCCESS (12.490s)
[641.825229] Starting dynamic subtest: ccs0
[658.088459] Dynamic subtest ccs0: SUCCESS (16.281s)
[658.091352] Subtest wide: SUCCESS (60.690s)
[660.269978] [105/133] (360s left) kms_setmode (clone-exclusive-crtc)
[660.332345] Starting subtest: clone-exclusive-crtc
[660.339263] Subtest clone-exclusive-crtc: SKIP (0.000s)
[660.435374] [106/133] (359s left) kms_color (legacy-gamma)
[660.533897] Starting subtest: legacy-gamma
[660.536330] Starting dynamic subtest: pipe-A-eDP-1
[661.268112] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.732s)
[661.292508] Starting dynamic subtest: pipe-B-eDP-1
[662.698896] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.432s)
[662.711233] Starting dynamic subtest: pipe-C-eDP-1
[664.080564] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.380s)
[664.097261] Starting dynamic subtest: pipe-D-eDP-1
[665.413192] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[665.416005] Subtest legacy-gamma: SUCCESS (4.879s)
[666.220533] [107/133] (354s left) prime_vgem (basic-fence-blt)
[666.291156] Starting subtest: basic-fence-blt
[666.422760] Subtest basic-fence-blt: SUCCESS (0.134s)
[666.548194] [108/133] (353s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[666.646999] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[666.649438] Starting dynamic subtest: pipe-A-eDP-1
[666.779116] Dynamic subtest pipe-A-eDP-1: SKIP (0.138s)
[666.791398] Starting dynamic subtest: pipe-B-eDP-1
[668.078350] Dynamic subtest pipe-B-eDP-1: SKIP (1.294s)
[668.083723] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.438s)
[669.282729] [109/133] (351s left) gem_sync (basic-each)
[669.369881] Starting subtest: basic-each
[671.742328] Subtest basic-each: SUCCESS (2.386s)
[671.944572] [110/133] (348s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[672.047342] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[672.052250] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[672.157368] [111/133] (348s left) kms_cursor_crc (cursor-sliding-64x21)
[672.360095] Starting subtest: cursor-sliding-64x21
[672.410489] Subtest cursor-sliding-64x21: SKIP (0.045s)
[672.645930] [112/133] (348s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[672.737246] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[672.742326] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[672.833263] [113/133] (347s left) kms_invalid_mode (int-max-clock)
[672.926075] Starting subtest: int-max-clock
[672.928292] Starting dynamic subtest: pipe-A-eDP-1
[673.211380] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[673.223798] Starting dynamic subtest: pipe-B-eDP-1
[673.226342] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[673.237539] Starting dynamic subtest: pipe-C-eDP-1
[673.239933] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[673.250462] Starting dynamic subtest: pipe-D-eDP-1
[673.253215] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[673.256037] Subtest int-max-clock: SUCCESS (0.291s)
[674.086660] [114/133] (346s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[674.178951] Starting subtest: async-flip-with-page-flip-events-tiled
[674.568090] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[677.364677] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.794s)
[677.375821] Starting dynamic subtest: pipe-A-eDP-1-4
[679.538745] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.172s)
[679.553948] Starting dynamic subtest: pipe-A-eDP-1-4
[681.549329] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[681.560921] Starting dynamic subtest: pipe-A-eDP-1-x
[683.740589] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[684.137004] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[686.903394] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.775s)
[686.917716] Starting dynamic subtest: pipe-B-eDP-1-4
[689.089963] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.184s)
[689.104010] Starting dynamic subtest: pipe-B-eDP-1-4
[691.100217] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[691.114308] Starting dynamic subtest: pipe-B-eDP-1-x
[693.292059] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s)
[693.683132] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[696.426029] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.753s)
[696.441351] Starting dynamic subtest: pipe-C-eDP-1-4
[698.600988] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.173s)
[698.616116] Starting dynamic subtest: pipe-C-eDP-1-4
[700.611200] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[700.622246] Starting dynamic subtest: pipe-C-eDP-1-x
[702.801312] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.189s)
[703.164924] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[705.955368] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.796s)
[705.967223] Starting dynamic subtest: pipe-D-eDP-1-4
[708.185119] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.229s)
[708.197040] Starting dynamic subtest: pipe-D-eDP-1-4
[710.195133] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[710.206377] Starting dynamic subtest: pipe-D-eDP-1-x
[712.374926] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[712.377360] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.188s)
[713.549217] [115/133] (307s left) syncobj_timeline (wait-all-for-submit-snapshot)
[713.607077] Starting subtest: wait-all-for-submit-snapshot
[713.690234] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[713.769263] [116/133] (307s left) kms_flip (2x-nonexisting-fb-interruptible)
[713.871716] Starting subtest: 2x-nonexisting-fb-interruptible
[713.883395] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.011s)
[713.997235] [117/133] (306s left) gem_busy (busy)
[714.061821] Starting subtest: busy
[714.064404] Starting dynamic subtest: all-engines
[714.121910] Dynamic subtest all-engines: SUCCESS (0.059s)
[714.135289] Starting dynamic subtest: rcs0
[716.138335] Dynamic subtest rcs0: SUCCESS (2.015s)
[716.150320] Starting dynamic subtest: bcs0
[716.195004] Dynamic subtest bcs0: SUCCESS (0.053s)
[716.207390] Starting dynamic subtest: vcs0
[716.234781] Dynamic subtest vcs0: SUCCESS (0.039s)
[716.245349] Starting dynamic subtest: vcs1
[718.185237] Dynamic subtest vcs1: SUCCESS (1.947s)
[718.198143] Starting dynamic subtest: vecs0
[720.169476] Dynamic subtest vecs0: SUCCESS (1.982s)
[720.182455] Starting dynamic subtest: ccs0
[722.153140] Dynamic subtest ccs0: SUCCESS (1.981s)
[722.156316] Subtest busy: SUCCESS (8.089s)
[722.507883] [118/133] (298s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[722.614046] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[722.619024] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[722.705973] [119/133] (298s left) gem_exec_reloc (basic-cpu-noreloc)
[722.770112] Subtest basic-cpu-noreloc: SKIP (0.000s)
[722.829865] [120/133] (298s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[722.919770] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[722.923989] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[723.007067] [121/133] (298s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[723.100163] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[723.102827] Starting dynamic subtest: pipe-A-eDP-1
[723.126446] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[723.139768] Starting dynamic subtest: pipe-B-eDP-1
[723.162480] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[723.174156] Starting dynamic subtest: pipe-C-eDP-1
[723.197266] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[723.208284] Starting dynamic subtest: pipe-D-eDP-1
[723.230747] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[723.232848] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[725.308157] [122/133] (295s left) i915_pm_rc6_residency (rc6-fence)
[725.369712] Starting subtest: rc6-fence
[725.388931] Starting dynamic subtest: gt0
[737.711184] Dynamic subtest gt0: SUCCESS (12.321s)
[737.725813] Starting dynamic subtest: gt1
[754.147398] Dynamic subtest gt1: SUCCESS (16.433s)
[754.150160] Subtest rc6-fence: SUCCESS (28.781s)
[754.227693] [123/133] (266s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[754.334994] Starting subtest: primary-yf-tiled-reflect-x-0
[754.398210] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.060s)
[754.530829] [124/133] (266s left) gem_tiled_blits (normal)
[754.594997] Subtest normal: SKIP (0.000s)
[754.670165] [125/133] (266s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[754.816292] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[756.088273] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.270s)
[756.210764] [126/133] (265s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[756.266213] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[756.270980] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[756.466394] [127/133] (264s left) syncobj_wait (reset-signaled)
[756.528566] Starting subtest: reset-signaled
[756.531349] Subtest reset-signaled: SUCCESS (0.000s)
[756.630663] [128/133] (264s left) gem_render_copy (linear-to-vebox-yf-tiled)
[756.691482] Starting subtest: linear-to-vebox-yf-tiled
[756.696803] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[756.882824] [129/133] (264s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[757.007819] Starting subtest: short-flip-before-cursor-toggle
[757.923034] Subtest short-flip-before-cursor-toggle: SUCCESS (0.917s)
[758.726580] [130/133] (262s left) gem_mmap_wc (read)
[758.802295] Subtest read: SKIP (0.000s)
[758.873848] [131/133] (262s left) perf_pmu (idle-no-semaphores)
[758.918267] Starting subtest: idle-no-semaphores
[758.921093] Starting dynamic subtest: rcs0
[759.475579] Dynamic subtest rcs0: SUCCESS (0.557s)
[759.488504] Starting dynamic subtest: bcs0
[759.995273] Dynamic subtest bcs0: SUCCESS (0.517s)
[760.009446] Starting dynamic subtest: vcs0
[760.518134] Dynamic subtest vcs0: SUCCESS (0.520s)
[760.531633] Starting dynamic subtest: vcs1
[761.038823] Dynamic subtest vcs1: SUCCESS (0.518s)
[761.052267] Starting dynamic subtest: vecs0
[761.565097] Dynamic subtest vecs0: SUCCESS (0.523s)
[761.578919] Starting dynamic subtest: ccs0
[762.082545] Dynamic subtest ccs0: SUCCESS (0.514s)
[762.085279] Subtest idle-no-semaphores: SUCCESS (3.167s)
[762.193635] [132/133] (259s left) syncobj_wait (invalid-signal-one-illegal-handle)
[762.240466] Starting subtest: invalid-signal-one-illegal-handle
[762.242874] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[762.337126] [133/133] (259s left) gem_tiled_swapping (non-threaded)
[762.398956] Subtest non-threaded: SKIP (0.000s)
[762.469114] Closing watchdogs
results: parsing output: 0/ for test: gem_wait
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: prime_mmap
results: parsing output: 3/ for test: gem_exec_schedule
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_exec_reloc
results: parsing output: 6/ for test: gem_ctx_engines
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_chamelium_color
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: kms_plane
results: parsing output: 11/ for test: perf
results: parsing output: 12/ for test: kms_atomic
results: parsing output: 13/ for test: gem_exec_params
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gem_mmap_gtt
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: sysfs_heartbeat_interval
results: parsing output: 20/ for test: gem_ctx_bad_destroy
results: parsing output: 21/ for test: kms_pm_rpm
results: parsing output: 22/ for test: kms_ccs
results: parsing output: 23/ for test: gem_exec_whisper
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: api_intel_allocator
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_dsc
results: parsing output: 29/ for test: gem_ctx_freq
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_cursor_legacy
results: parsing output: 35/ for test: kms_plane
results: parsing output: 36/ for test: gem_ctx_param
results: parsing output: 37/ for test: kms_psr2_sf
results: parsing output: 38/ for test: kms_plane_multiple
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: kms_properties
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_ctx_param
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: i915_pm_freq_api
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: gem_userptr_blits
results: parsing output: 48/ for test: kms_cursor_crc
results: parsing output: 49/ for test: kms_cursor_crc
results: parsing output: 50/ for test: dmabuf
results: parsing output: 51/ for test: gem_eio
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: gem_mmap_wc
results: parsing output: 54/ for test: gem_exec_fence
results: parsing output: 55/ for test: syncobj_basic
results: parsing output: 56/ for test: gem_spin_batch
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_cursor_legacy
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_unref_active_buffers
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: i915_query
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: gem_exec_fence
results: parsing output: 65/ for test: gem_workarounds
results: parsing output: 66/ for test: kms_pipe_crc_basic
results: parsing output: 67/ for test: perf
results: parsing output: 68/ for test: kms_legacy_colorkey
results: parsing output: 69/ for test: gem_exec_balancer
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: gem_lmem_swapping
results: parsing output: 72/ for test: gem_ctx_shared
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_ctx_persistence
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: i915_drm_fdinfo
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: kms_chamelium_frames
results: parsing output: 80/ for test: api_intel_bb
results: parsing output: 81/ for test: kms_pm_rpm
results: parsing output: 82/ for test: kms_properties
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: gem_exec_params
results: parsing output: 85/ for test: perf
results: parsing output: 86/ for test: gem_mmap_offset
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: kms_plane_scaling
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_chamelium_hpd
results: parsing output: 92/ for test: kms_content_protection
results: parsing output: 93/ for test: kms_vblank
results: parsing output: 94/ for test: perf_pmu
results: parsing output: 95/ for test: gem_linear_blits
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: gen9_exec_parse
results: parsing output: 100/ for test: gem_exec_schedule
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_flip_event_leak
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: kms_setmode
results: parsing output: 105/ for test: kms_color
results: parsing output: 106/ for test: prime_vgem
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: gem_sync
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_cursor_crc
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_invalid_mode
results: parsing output: 113/ for test: kms_async_flips
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: gem_busy
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_exec_reloc
results: parsing output: 119/ for test: kms_flip_scaled_crc
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: i915_pm_rc6_residency
results: parsing output: 122/ for test: kms_rotation_crc
results: parsing output: 123/ for test: gem_tiled_blits
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: gem_render_copy
results: parsing output: 126/ for test: syncobj_wait
results: parsing output: 127/ for test: gem_render_copy
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: gem_mmap_wc
results: parsing output: 130/ for test: perf_pmu
results: parsing output: 131/ for test: syncobj_wait
results: parsing output: 132/ for test: gem_tiled_swapping
Done.