[74.834589] Initializing watchdogs
[74.834634]   /dev/watchdog0
[74.849395] [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]
[74.853801] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[74.856063] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[74.859500] [001/156] (960s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[75.273322] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[76.100631] [002/156] (959s left) i915_query (query-topology-kernel-writes)
[76.154821] Starting subtest: query-topology-kernel-writes
[76.157257] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[76.260352] [003/156] (959s left) gem_pxp (fail-invalid-protected-context)
[76.384731] Starting subtest: fail-invalid-protected-context
[76.387219] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[76.499758] [004/156] (958s left) gem_busy (busy)
[76.554795] Starting subtest: busy
[76.556753] Starting dynamic subtest: all-engines
[76.585587] Dynamic subtest all-engines: SUCCESS (0.033s)
[76.593762] Starting dynamic subtest: rcs0
[78.283420] Dynamic subtest rcs0: SUCCESS (1.697s)
[78.291738] Starting dynamic subtest: bcs0
[78.330513] Dynamic subtest bcs0: SUCCESS (0.045s)
[78.338795] Starting dynamic subtest: vcs0
[78.369097] Dynamic subtest vcs0: SUCCESS (0.039s)
[78.376411] Starting dynamic subtest: vcs1
[80.329273] Dynamic subtest vcs1: SUCCESS (1.957s)
[80.338139] Starting dynamic subtest: vecs0
[82.312925] Dynamic subtest vecs0: SUCCESS (1.983s)
[82.321202] Starting dynamic subtest: ccs0
[84.296053] Dynamic subtest ccs0: SUCCESS (1.983s)
[84.298362] Subtest busy: SUCCESS (7.742s)
[84.647535] [005/156] (950s left) i915_pm_rc6_residency (rc6-accuracy)
[84.701989] Starting subtest: rc6-accuracy
[84.703949] Starting dynamic subtest: gt0
[87.875756] Dynamic subtest gt0: SUCCESS (3.169s)
[87.883887] Starting dynamic subtest: gt1
[91.046366] Dynamic subtest gt1: SUCCESS (3.170s)
[91.049086] Subtest rc6-accuracy: SUCCESS (6.342s)
[91.117270] [006/156] (944s left) kms_prop_blob (invalid-get-prop-any)
[91.173575] Starting subtest: invalid-get-prop-any
[91.175984] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[91.282675] [007/156] (944s left) gem_ctx_persistence (hang)
[91.338746] Starting subtest: hang
[91.343519] Subtest hang: SKIP (0.000s)
[91.459967] [008/156] (944s left) kms_psr (fbc-psr-primary-render)
[91.558518] Starting subtest: fbc-psr-primary-render
[91.560694] Starting dynamic subtest: eDP-1
[91.565069] Dynamic subtest eDP-1: SKIP (0.000s)
[91.569533] Subtest fbc-psr-primary-render: SKIP (0.001s)
[91.626808] [009/156] (944s left) syncobj_wait (wait-delayed-signal)
[91.679829] Starting subtest: wait-delayed-signal
[91.784792] Subtest wait-delayed-signal: SUCCESS (0.103s)
[91.857944] [010/156] (943s left) gem_userptr_blits (relocations)
[91.917667] Starting subtest: relocations
[91.921670] Subtest relocations: SKIP (0.001s)
[92.028166] [011/156] (943s left) syncobj_wait (multi-wait-for-submit-submitted)
[92.071935] Starting subtest: multi-wait-for-submit-submitted
[92.175562] Subtest multi-wait-for-submit-submitted: SUCCESS (0.103s)
[92.245250] [012/156] (943s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[92.353286] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[99.064775] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.717s)
[99.183879] [013/156] (936s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[99.288882] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[99.293272] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[99.379389] [014/156] (936s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[99.775093] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[100.577714] [015/156] (935s left) gem_exec_schedule (smoketest-all)
[100.647222] Starting subtest: smoketest-all
[133.013568] Subtest smoketest-all: SUCCESS (32.324s)
[134.376520] [016/156] (901s left) syncobj_timeline (wait-any-interrupted)
[134.430757] Starting subtest: wait-any-interrupted
[134.536810] Subtest wait-any-interrupted: SUCCESS (0.105s)
[134.609980] [017/156] (901s left) kms_atomic (plane-cursor-legacy)
[134.713628] Starting subtest: plane-cursor-legacy
[134.715862] Starting dynamic subtest: pipe-A-eDP-1
[135.119613] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.406s)
[135.121885] Subtest plane-cursor-legacy: SUCCESS (0.407s)
[135.926946] [018/156] (900s left) kms_vblank (wait-forked-busy)
[136.025999] Starting subtest: wait-forked-busy
[136.027964] Starting dynamic subtest: pipe-A-eDP-1
[137.905621] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.880s)
[137.918777] Starting dynamic subtest: pipe-D-eDP-1
[140.500787] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.593s)
[140.503249] Subtest wait-forked-busy: SUCCESS (4.475s)
[141.329198] [019/156] (894s left) kms_cursor_crc (cursor-offscreen-64x64)
[141.504122] Starting subtest: cursor-offscreen-64x64
[141.508644] Starting dynamic subtest: pipe-A-eDP-1
[143.449641] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.942s)
[143.475272] Starting dynamic subtest: pipe-D-eDP-1
[146.128340] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.657s)
[146.130212] Subtest cursor-offscreen-64x64: SUCCESS (4.622s)
[146.963670] [020/156] (889s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-msflip-blt)
[147.077981] Starting subtest: hdr-2p-scndscrn-shrfb-msflip-blt
[147.082166] Subtest hdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[147.174816] [021/156] (888s left) sw_sync (alloc_timeline)
[147.209629] Starting subtest: alloc_timeline
[147.211865] Subtest alloc_timeline: SUCCESS (0.000s)
[147.261948] [022/156] (888s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[147.359209] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[147.363326] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[147.470048] [023/156] (888s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[147.594870] Starting subtest: cursorA-vs-flipA-varying-size
[158.730830] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.135s)
[159.592532] [024/156] (876s left) kms_flip (2x-flip-vs-suspend-interruptible)
[159.696496] Starting subtest: 2x-flip-vs-suspend-interruptible
[159.701049] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.003s)
[159.773794] [025/156] (876s left) gem_madvise (dontneed-before-exec)
[159.814076] Starting subtest: dontneed-before-exec
[159.838505] Subtest dontneed-before-exec: SKIP (0.023s)
[159.893447] [026/156] (876s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[159.993076] Starting subtest: 2x-flip-vs-rmfb-interruptible
[160.004696] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.011s)
[160.077158] [027/156] (876s left) i915_pm_rps (thresholds-idle-park)
[160.135793] Starting subtest: thresholds-idle-park
[160.140315] Subtest thresholds-idle-park: SKIP (0.001s)
[160.227026] [028/156] (876s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-onoff)
[160.323037] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-onoff
[160.327565] Subtest psrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[160.413201] [029/156] (875s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[160.475821] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[160.584038] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.109s)
[160.668321] [030/156] (875s left) gem_exec_params (invalid-bsd2-flag-on-render)
[160.742640] Starting subtest: invalid-bsd2-flag-on-render
[160.744469] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.001s)
[160.841524] [031/156] (875s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-move)
[160.945163] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-move
[160.949205] Subtest fbchdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[161.043268] [032/156] (875s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[161.144063] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[161.146352] Starting dynamic subtest: pipe-A-eDP-1
[177.462739] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.539s)
[177.471589] Starting dynamic subtest: pipe-B-eDP-1
[194.418405] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.426s)
[194.430440] Starting dynamic subtest: pipe-C-eDP-1
[211.411917] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.352s)
[211.421883] Starting dynamic subtest: pipe-D-eDP-1
[228.403849] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.350s)
[228.406158] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.671s)
[229.655103] [033/156] (806s left) gem_tiled_partial_pwrite_pread (writes)
[229.726773] Subtest writes: SKIP (0.000s)
[229.781990] [034/156] (806s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[229.834375] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[229.836805] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[229.930915] [035/156] (806s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[230.020422] Starting subtest: pr-overlay-primary-update-sf-dmg-area
[230.024958] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[230.086402] [036/156] (806s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[230.190332] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[230.194790] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[230.283622] [037/156] (806s left) gem_partial_pwrite_pread (reads)
[230.339747] Subtest reads: SKIP (0.000s)
[230.425404] [038/156] (806s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[230.518689] Starting subtest: plane-upscale-20x20-with-modifiers
[230.521222] Starting dynamic subtest: pipe-A
[230.856097] Dynamic subtest pipe-A: SUCCESS (0.338s)
[230.865371] Starting dynamic subtest: pipe-B
[232.267603] Dynamic subtest pipe-B: SUCCESS (1.406s)
[232.277106] Starting dynamic subtest: pipe-C
[233.694399] Dynamic subtest pipe-C: SUCCESS (1.422s)
[233.703241] Starting dynamic subtest: pipe-D
[235.122699] Dynamic subtest pipe-D: SUCCESS (1.424s)
[235.125007] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.592s)
[236.314448] [039/156] (800s left) gem_exec_schedule (preempt-engines)
[236.386078] Starting subtest: preempt-engines
[236.388060] Starting dynamic subtest: rcs0
[238.630797] Dynamic subtest rcs0: SUCCESS (2.248s)
[238.644008] Starting dynamic subtest: bcs0
[240.861720] Dynamic subtest bcs0: SUCCESS (2.227s)
[240.872045] Starting dynamic subtest: vcs0
[243.135883] Dynamic subtest vcs0: SUCCESS (2.268s)
[243.145348] Starting dynamic subtest: vcs1
[245.424611] Dynamic subtest vcs1: SUCCESS (2.285s)
[245.436058] Starting dynamic subtest: vecs0
[247.697274] Dynamic subtest vecs0: SUCCESS (2.269s)
[247.707109] Starting dynamic subtest: ccs0
[250.000803] Dynamic subtest ccs0: SUCCESS (2.300s)
[250.003265] Subtest preempt-engines: SUCCESS (13.607s)
[250.315366] [040/156] (786s left) kms_frontbuffer_tracking (fbchdr-1p-shrfb-fliptrack-mmap-gtt)
[250.419326] Starting subtest: fbchdr-1p-shrfb-fliptrack-mmap-gtt
[250.423441] Subtest fbchdr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[250.516043] [041/156] (786s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[250.613471] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[250.615770] Starting dynamic subtest: pipe-A
[250.633532] Dynamic subtest pipe-A: SKIP (0.015s)
[250.643591] Starting dynamic subtest: pipe-B
[250.661262] Dynamic subtest pipe-B: SKIP (0.029s)
[250.670501] Starting dynamic subtest: pipe-C
[250.690860] Dynamic subtest pipe-C: SKIP (0.028s)
[250.700058] Starting dynamic subtest: pipe-D
[250.719649] Dynamic subtest pipe-D: SKIP (0.029s)
[250.723322] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.104s)
[250.831711] [042/156] (786s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[251.129982] Starting subtest: cursor-rapid-movement-max-size
[251.136680] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[251.265621] [043/156] (785s left) gem_exec_schedule (preempt-user)
[251.324245] Starting subtest: preempt-user
[251.326061] Starting dynamic subtest: rcs0
[251.348914] Dynamic subtest rcs0: SUCCESS (0.028s)
[251.358523] Starting dynamic subtest: bcs0
[251.371105] Dynamic subtest bcs0: SUCCESS (0.022s)
[251.379850] Starting dynamic subtest: vcs0
[251.390079] Dynamic subtest vcs0: SUCCESS (0.018s)
[251.398557] Starting dynamic subtest: vcs1
[251.411440] Dynamic subtest vcs1: SUCCESS (0.021s)
[251.420121] Starting dynamic subtest: vecs0
[251.431255] Dynamic subtest vecs0: SUCCESS (0.019s)
[251.439821] Starting dynamic subtest: ccs0
[251.453350] Dynamic subtest ccs0: SUCCESS (0.021s)
[251.455267] Subtest preempt-user: SUCCESS (0.133s)
[252.959260] [044/156] (784s left) kms_atomic_interruptible (atomic-setmode)
[253.048978] Starting subtest: atomic-setmode
[253.051190] Starting dynamic subtest: pipe-A-eDP-1
[259.989757] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.937s)
[259.991886] Subtest atomic-setmode: SUCCESS (6.939s)
[260.861989] [045/156] (776s left) gem_mmap_offset (partial-mmap)
[260.910725] Starting subtest: partial-mmap
[260.912861] Starting dynamic subtest: smem0
[260.915259] Dynamic subtest smem0: SUCCESS (0.004s)
[260.917645] Subtest partial-mmap: SUCCESS (0.005s)
[261.029325] [046/156] (776s left) kms_plane_multiple (tiling-y)
[261.113750] Starting subtest: tiling-y
[261.118115] Subtest tiling-y: SKIP (0.000s)
[261.199269] [047/156] (775s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[261.245996] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[261.248259] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[261.347718] [048/156] (775s left) gem_exec_schedule (implicit-boths)
[261.415356] Starting subtest: implicit-boths
[261.417234] Starting dynamic subtest: rcs0
[261.530118] Dynamic subtest rcs0: SUCCESS (0.118s)
[261.543061] Starting dynamic subtest: vcs0
[261.620496] Dynamic subtest vcs0: SUCCESS (0.089s)
[261.633306] Starting dynamic subtest: vcs1
[261.700189] Dynamic subtest vcs1: SUCCESS (0.077s)
[261.712035] Starting dynamic subtest: vecs0
[261.793012] Dynamic subtest vecs0: SUCCESS (0.092s)
[261.804655] Starting dynamic subtest: ccs0
[261.876740] Dynamic subtest ccs0: SUCCESS (0.083s)
[261.879621] Subtest implicit-boths: SUCCESS (0.466s)
[262.947208] [049/156] (774s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[263.037341] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[263.039467] Starting dynamic subtest: pipe-A-default-mode
[265.782262] Dynamic subtest pipe-A-default-mode: SUCCESS (2.746s)
[265.784612] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.747s)
[266.631586] [050/156] (770s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[266.731387] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[266.735330] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[266.832491] [051/156] (770s left) gem_exec_balancer (sequential)
[266.901961] Starting subtest: sequential
[269.993909] Subtest sequential: SUCCESS (3.092s)
[270.325808] [052/156] (766s left) kms_force_connector_basic (force-edid)
[270.398383] Starting subtest: force-edid
[270.713643] Subtest force-edid: SUCCESS (0.317s)
[270.861878] [053/156] (766s left) api_intel_bb (intel-bb-blit-none)
[270.913413] Starting subtest: intel-bb-blit-none
[274.902209] Subtest intel-bb-blit-none: SUCCESS (3.989s)
[275.012011] [054/156] (762s left) gem_eio (wait-wedge-1us)
[275.247653] Starting subtest: wait-wedge-1us
[276.963693] Subtest wait-wedge-1us: SUCCESS (1.715s)
[277.098053] [055/156] (760s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[277.197757] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[277.200134] Starting dynamic subtest: pipe-A-eDP-1
[277.615514] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.392s)
[277.628793] Starting dynamic subtest: pipe-B-eDP-1
[279.026785] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.406s)
[279.036720] Starting dynamic subtest: pipe-C-eDP-1
[280.415976] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.390s)
[280.427472] Starting dynamic subtest: pipe-D-eDP-1
[281.803983] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.386s)
[281.806300] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.578s)
[282.961597] [056/156] (754s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[283.060390] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[286.701681] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.647s)
[287.873374] [057/156] (749s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-pgflip-blt)
[287.977233] Starting subtest: psrhdr-2p-primscrn-shrfb-pgflip-blt
[287.981640] Subtest psrhdr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[288.081460] [058/156] (749s left) kms_plane (pixel-format-linear-modifier)
[288.168607] Starting subtest: pixel-format-linear-modifier
[288.194309] Starting dynamic subtest: pipe-A-plane-0
[288.760407] Dynamic subtest pipe-A-plane-0: SUCCESS (0.566s)
[288.771147] Starting dynamic subtest: pipe-A-plane-3
[289.193639] Dynamic subtest pipe-A-plane-3: SUCCESS (0.433s)
[289.205986] Starting dynamic subtest: pipe-A-plane-5
[289.404669] Dynamic subtest pipe-A-plane-5: SUCCESS (0.210s)
[290.514497] Starting dynamic subtest: pipe-B-plane-0
[291.086112] Dynamic subtest pipe-B-plane-0: SUCCESS (0.577s)
[291.098382] Starting dynamic subtest: pipe-B-plane-3
[291.517325] Dynamic subtest pipe-B-plane-3: SUCCESS (0.432s)
[291.527050] Starting dynamic subtest: pipe-B-plane-5
[291.716995] Dynamic subtest pipe-B-plane-5: SUCCESS (0.198s)
[292.036540] Subtest pixel-format-linear-modifier: SUCCESS (3.867s)
[292.858807] [059/156] (744s left) kms_cursor_edge_walk (128x128-left-edge)
[292.946516] Starting subtest: 128x128-left-edge
[292.948906] Starting dynamic subtest: pipe-A-eDP-1
[296.414802] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.466s)
[296.424293] Starting dynamic subtest: pipe-D-eDP-1
[299.574470] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.158s)
[299.576672] Subtest 128x128-left-edge: SUCCESS (6.627s)
[300.459176] [060/156] (737s left) kms_dp_link_training (non-uhbr-mst)
[300.556585] Starting subtest: non-uhbr-mst
[300.565371] Subtest non-uhbr-mst: SKIP (0.000s)
[300.631514] [061/156] (737s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[300.736973] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf
[300.740913] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[300.806844] [062/156] (736s left) kms_frontbuffer_tracking (psr-2p-rte)
[300.902433] Starting subtest: psr-2p-rte
[300.906332] Subtest psr-2p-rte: SKIP (0.000s)
[300.995111] [063/156] (736s left) sysfs_heartbeat_interval (idempotent)
[301.052561] Starting subtest: idempotent
[301.054876] Starting dynamic subtest: vecs0
[301.057224] Dynamic subtest vecs0: SUCCESS (0.001s)
[301.067079] Starting dynamic subtest: ccs0
[301.069031] Dynamic subtest ccs0: SUCCESS (0.001s)
[301.077616] Starting dynamic subtest: vcs0
[301.079539] Dynamic subtest vcs0: SUCCESS (0.000s)
[301.088293] Starting dynamic subtest: bcs0
[301.090235] Dynamic subtest bcs0: SUCCESS (0.001s)
[301.099374] Starting dynamic subtest: rcs0
[301.101544] Dynamic subtest rcs0: SUCCESS (0.000s)
[301.110681] Starting dynamic subtest: vcs1
[301.112627] Dynamic subtest vcs1: SUCCESS (0.000s)
[301.114543] Subtest idempotent: SUCCESS (0.007s)
[301.174041] [064/156] (736s left) syncobj_eventfd (binary-wait-before-signal)
[301.232624] Starting subtest: binary-wait-before-signal
[301.263498] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[301.349572] [065/156] (736s left) gem_tiled_partial_pwrite_pread (reads)
[301.392293] Subtest reads: SKIP (0.000s)
[301.454691] [066/156] (736s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[301.546503] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[301.548451] Starting dynamic subtest: pipe-A-eDP-1
[301.567200] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[301.579233] Starting dynamic subtest: pipe-B-eDP-1
[301.596875] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[301.605435] Starting dynamic subtest: pipe-C-eDP-1
[301.623487] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[301.632238] Starting dynamic subtest: pipe-D-eDP-1
[301.650382] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[301.654079] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[303.757938] [067/156] (734s left) gem_vm_create (invalid-destroy)
[303.818091] Starting subtest: invalid-destroy
[303.820367] Subtest invalid-destroy: SUCCESS (0.002s)
[303.940784] [068/156] (734s left) gem_reg_read (bad-register)
[303.988712] Starting subtest: bad-register
[303.990704] Subtest bad-register: SUCCESS (0.000s)
[304.103586] [069/156] (733s left) vgem_basic (create)
[304.216248] Starting subtest: create
[304.218039] Subtest create: SUCCESS (0.000s)
[304.267239] [FACT vgem_basic (create)] new: kernel.kmod_is_loaded.vgem: true
[304.270632] [070/156] (733s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-shrfb-plflip-blt)
[304.360760] Starting subtest: fbchdr-2p-primscrn-shrfb-plflip-blt
[304.365148] Subtest fbchdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[304.464284] [071/156] (733s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[304.562340] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[304.566456] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[304.643170] [072/156] (733s left) kms_psr2_su (frontbuffer-xrgb8888)
[304.692729] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[304.751728] [073/156] (733s left) kms_color (ctm-0-50)
[304.844669] Starting subtest: ctm-0-50
[304.846268] Starting dynamic subtest: pipe-A-eDP-1
[308.396595] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.550s)
[308.406607] Starting dynamic subtest: pipe-B-eDP-1
[312.628956] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.229s)
[312.640708] Starting dynamic subtest: pipe-C-eDP-1
[316.770339] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.142s)
[316.782071] Starting dynamic subtest: pipe-D-eDP-1
[320.971539] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.196s)
[320.973839] Subtest ctm-0-50: SUCCESS (16.120s)
[321.784525] [074/156] (716s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite)
[321.879435] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite
[321.883663] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[321.978079] [075/156] (716s left) kms_psr (pr-sprite-blt)
[322.064233] Starting subtest: pr-sprite-blt
[322.068808] Subtest pr-sprite-blt: SKIP (0.000s)
[322.152902] [076/156] (716s left) perf_pmu (render-node-busy-idle)
[322.218249] Starting subtest: render-node-busy-idle
[322.220962] Starting dynamic subtest: rcs0
[322.898378] Dynamic subtest rcs0: SUCCESS (0.679s)
[322.911487] Starting dynamic subtest: bcs0
[323.560439] Dynamic subtest bcs0: SUCCESS (0.660s)
[323.572740] Starting dynamic subtest: vcs0
[324.874929] Dynamic subtest vcs0: SUCCESS (1.311s)
[324.887601] Starting dynamic subtest: vcs1
[326.858543] Dynamic subtest vcs1: SUCCESS (1.981s)
[326.872391] Starting dynamic subtest: vecs0
[328.842507] Dynamic subtest vecs0: SUCCESS (1.982s)
[328.855122] Starting dynamic subtest: ccs0
[329.503028] Dynamic subtest ccs0: SUCCESS (0.660s)
[329.505963] Subtest render-node-busy-idle: SUCCESS (7.287s)
[329.585128] [077/156] (708s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[329.678039] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[329.682519] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[329.764066] [078/156] (708s left) gem_pxp (verify-pxp-stale-buf-execution)
[329.877825] Starting subtest: verify-pxp-stale-buf-execution
[330.944957] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.069s)
[331.074430] [079/156] (707s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[331.128471] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[331.132561] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[331.325619] [080/156] (707s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[331.432835] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[338.613628] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.187s)
[338.749257] [081/156] (699s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[338.846348] Starting subtest: compare-crc-sanitycheck-nv12
[338.848636] Starting dynamic subtest: pipe-A-eDP-1
[339.500195] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.653s)
[339.510396] Starting dynamic subtest: pipe-B-eDP-1
[340.840933] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.341s)
[340.852549] Starting dynamic subtest: pipe-C-eDP-1
[342.024154] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.181s)
[342.035873] Starting dynamic subtest: pipe-D-eDP-1
[343.260867] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.237s)
[343.263229] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.417s)
[344.062664] [082/156] (694s left) gem_eio (in-flight-contexts-1us)
[344.263236] Starting subtest: in-flight-contexts-1us
[355.883703] Subtest in-flight-contexts-1us: SUCCESS (11.620s)
[356.002144] [083/156] (682s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[356.101253] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-pwrite
[356.105436] Subtest psrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[356.195336] [084/156] (682s left) kms_psr (psr-sprite-render)
[356.290459] Starting subtest: psr-sprite-render
[356.292773] Starting dynamic subtest: eDP-1
[358.020837] Dynamic subtest eDP-1: SUCCESS (1.732s)
[358.023220] Subtest psr-sprite-render: SUCCESS (1.733s)
[358.848103] [085/156] (679s left) gem_exec_whisper (basic-contexts-priority-all)
[358.908970] Starting subtest: basic-contexts-priority-all
[382.018381] Subtest basic-contexts-priority-all: SUCCESS (23.101s)
[382.212532] [086/156] (656s left) kms_content_protection (uevent-hdcp14)
[382.359694] Starting subtest: uevent-hdcp14
[382.364924] Subtest uevent-hdcp14: SKIP (0.000s)
[382.485123] [087/156] (656s left) syncobj_timeline (multi-wait-available-submitted)
[382.547231] Starting subtest: multi-wait-available-submitted
[382.549691] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[382.654043] [088/156] (656s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[382.751274] Starting subtest: x-tiled-64bpp-rotate-90
[382.770966] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[382.884878] [089/156] (655s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[382.966419] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[382.968637] Starting dynamic subtest: pipe-A-eDP-1
[382.989473] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[383.002390] Starting dynamic subtest: pipe-B-eDP-1
[383.021899] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[383.032109] Starting dynamic subtest: pipe-C-eDP-1
[383.051878] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[383.063299] Starting dynamic subtest: pipe-D-eDP-1
[383.083368] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[383.085299] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[385.253721] [090/156] (653s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[385.357110] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[385.361434] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[385.452889] [091/156] (653s left) gen9_exec_parse (cmd-crossing-page)
[385.515623] Subtest cmd-crossing-page: SKIP (0.000s)
[385.587974] [092/156] (653s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[385.683028] Starting subtest: fbc-psr2-sprite-mmap-cpu
[385.685251] Starting dynamic subtest: eDP-1
[385.689339] Dynamic subtest eDP-1: SKIP (0.000s)
[385.693474] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[385.757192] [093/156] (653s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[385.851069] Starting subtest: psr-rgb565-draw-pwrite
[392.775480] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.928s)
[392.898202] [094/156] (646s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[393.004102] Starting subtest: fbcpsr-rgb565-draw-blt
[400.054538] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.054s)
[400.194338] [095/156] (638s left) gem_exec_params (rsvd2-dirt)
[400.249094] Starting subtest: rsvd2-dirt
[400.254536] Subtest rsvd2-dirt: SKIP (0.000s)
[400.353532] [096/156] (638s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[400.444130] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[400.448519] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[400.544860] [097/156] (638s left) gem_tiled_wb
[400.660318] [098/156] (638s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite)
[400.763157] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite
[400.767554] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[400.862870] [099/156] (638s left) kms_addfb_basic (invalid-set-prop)
[400.919373] Starting subtest: invalid-set-prop
[400.921780] Subtest invalid-set-prop: SUCCESS (0.000s)
[401.078617] [100/156] (638s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-render)
[401.180091] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-render
[401.183459] Subtest hdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[401.284277] [101/156] (638s left) gem_ctx_engines (none)
[401.340176] Starting subtest: none
[401.342149] Subtest none: SUCCESS (0.001s)
[401.526626] [102/156] (637s left) gem_mmap_gtt (cpuset-medium-copy)
[401.585986] Subtest cpuset-medium-copy: SKIP (0.000s)
[401.643351] [103/156] (637s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[401.739228] Starting subtest: 4-tiled-32bpp-rotate-90
[401.758038] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.019s)
[401.872269] [104/156] (637s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[401.972337] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc
[401.976338] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[402.061574] [105/156] (637s left) gem_reg_read (timestamp-moving)
[402.114688] Starting subtest: timestamp-moving
[403.115358] Subtest timestamp-moving: SUCCESS (1.001s)
[403.218556] [106/156] (636s left) kms_dsc (dsc-with-bpc-formats)
[403.315234] Starting subtest: dsc-with-bpc-formats
[403.427198] Subtest dsc-with-bpc-formats: SKIP (0.002s)
[403.469582] [107/156] (636s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff)
[403.573955] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff
[403.578393] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[403.671048] [108/156] (635s left) core_setmaster (master-drop-set-shared-fd)
[403.752624] Starting subtest: master-drop-set-shared-fd
[403.774227] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[403.812824] [109/156] (635s left) api_intel_allocator (two-level-inception)
[403.865340] Starting subtest: two-level-inception
[409.265839] Subtest two-level-inception: SUCCESS (5.400s)
[409.395756] [110/156] (630s left) gem_exec_balancer (bonded-false-hang)
[409.665271] Starting subtest: bonded-false-hang
[409.669774] Subtest bonded-false-hang: SKIP (0.000s)
[409.786178] [111/156] (629s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[409.884740] Starting subtest: fbc-shrfb-scaledprimary
[412.840486] Subtest fbc-shrfb-scaledprimary: SUCCESS (2.961s)
[414.004021] [112/156] (625s left) core_auth (many-magics)
[414.058726] Starting subtest: many-magics
[414.956847] Subtest many-magics: SUCCESS (0.900s)
[415.150544] [113/156] (624s left) gem_exec_fence (syncobj-import)
[415.622510] Starting subtest: syncobj-import
[415.628406] Subtest syncobj-import: SUCCESS (0.005s)
[416.953608] [114/156] (622s left) kms_chamelium_color (ctm-red-to-blue)
[417.056370] Subtest ctm-red-to-blue: SKIP (0.000s)
[417.103654] [115/156] (622s left) kms_addfb_basic (unused-handle)
[417.158623] Starting subtest: unused-handle
[417.160905] Subtest unused-handle: SUCCESS (0.000s)
[417.285242] [116/156] (622s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-blt)
[417.381955] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-blt
[417.386542] Subtest fbchdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[417.478646] [117/156] (622s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[417.583081] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[424.849106] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.269s)
[424.989094] [118/156] (615s left) gem_exec_reloc (basic-cpu-read-noreloc)
[425.047151] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[425.123315] [119/156] (614s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[425.222748] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[429.491037] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.272s)
[430.688108] [120/156] (609s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[430.744410] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[430.817474] [121/156] (609s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[430.868836] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[430.871083] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[430.980250] [122/156] (609s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-pwrite)
[431.079970] Starting subtest: fbchdr-rgb565-draw-pwrite
[431.084239] Subtest fbchdr-rgb565-draw-pwrite: SKIP (0.000s)
[431.173122] [123/156] (609s left) kms_flip (flip-vs-panning)
[431.255619] Starting subtest: flip-vs-panning
[431.257921] Starting dynamic subtest: A-eDP1
[439.968104] Dynamic subtest A-eDP1: SUCCESS (8.712s)
[439.978995] Starting dynamic subtest: B-eDP1
[448.654327] Dynamic subtest B-eDP1: SUCCESS (8.681s)
[448.666575] Starting dynamic subtest: C-eDP1
[457.333739] Dynamic subtest C-eDP1: SUCCESS (8.679s)
[457.345530] Starting dynamic subtest: D-eDP1
[466.035740] Dynamic subtest D-eDP1: SUCCESS (8.702s)
[466.040493] Subtest flip-vs-panning: SUCCESS (34.785s)
[467.269306] [124/156] (573s left) gem_ctx_param (vm)
[467.328993] Starting subtest: vm
[467.376849] Subtest vm: SUCCESS (0.047s)
[467.519898] [125/156] (572s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[467.629060] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[467.633358] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[467.751991] [126/156] (572s left) gem_linear_blits (interruptible)
[468.196252] Starting subtest: interruptible
[472.398011] Subtest interruptible: SUCCESS (4.201s)
[472.547927] [127/156] (567s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[472.703837] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[479.896140] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.198s)
[480.030994] [128/156] (560s left) kms_frontbuffer_tracking (psrhdr-suspend)
[480.126855] Starting subtest: psrhdr-suspend
[480.130941] Subtest psrhdr-suspend: SKIP (0.000s)
[480.210592] [129/156] (560s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-msflip-blt)
[480.308223] Starting subtest: hdr-1p-primscrn-shrfb-msflip-blt
[480.312401] Subtest hdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[480.406254] [130/156] (560s left) gem_spin_batch (resubmit-new)
[480.466586] Starting subtest: resubmit-new
[480.468919] Starting dynamic subtest: rcs0
[480.474977] Dynamic subtest rcs0: SUCCESS (0.008s)
[480.488433] Starting dynamic subtest: bcs0
[480.490814] Dynamic subtest bcs0: SUCCESS (0.004s)
[480.502289] Starting dynamic subtest: vcs0
[480.504531] Dynamic subtest vcs0: SUCCESS (0.004s)
[480.515391] Starting dynamic subtest: vcs1
[480.517499] Dynamic subtest vcs1: SUCCESS (0.005s)
[480.527966] Starting dynamic subtest: vecs0
[480.530226] Dynamic subtest vecs0: SUCCESS (0.004s)
[480.540250] Starting dynamic subtest: ccs0
[480.542383] Dynamic subtest ccs0: SUCCESS (0.004s)
[480.544534] Subtest resubmit-new: SUCCESS (0.034s)
[481.906867] [131/156] (558s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt)
[482.004138] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt
[482.008393] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[482.102874] [132/156] (558s left) kms_vblank (query-forked-busy)
[482.201105] Starting subtest: query-forked-busy
[482.203065] Starting dynamic subtest: pipe-A-eDP-1
[484.083546] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.882s)
[484.100306] Starting dynamic subtest: pipe-D-eDP-1
[486.698862] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.613s)
[486.701296] Subtest query-forked-busy: SUCCESS (4.500s)
[487.552155] [133/156] (553s left) kms_frontbuffer_tracking (fbc-tiling-4)
[487.663570] Starting subtest: fbc-tiling-4
[491.831413] Subtest fbc-tiling-4: SUCCESS (4.174s)
[492.963214] [134/156] (547s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[493.071984] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[493.076372] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[493.170631] [135/156] (547s left) kms_plane_cursor (primary)
[493.271347] Starting subtest: primary
[493.275806] Starting dynamic subtest: pipe-A-eDP-1-size-64
[495.825347] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.555s)
[495.851366] Starting dynamic subtest: pipe-A-eDP-1-size-128
[498.404358] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.561s)
[498.430240] Starting dynamic subtest: pipe-A-eDP-1-size-256
[500.983231] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.561s)
[501.309406] Starting dynamic subtest: pipe-B-eDP-1-size-64
[504.581307] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.288s)
[504.607315] Starting dynamic subtest: pipe-B-eDP-1-size-128
[507.160708] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.561s)
[507.186437] Starting dynamic subtest: pipe-B-eDP-1-size-256
[509.739230] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.561s)
[510.060574] Starting dynamic subtest: pipe-C-eDP-1-size-64
[512.940384] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.892s)
[512.969128] Starting dynamic subtest: pipe-C-eDP-1-size-128
[515.151686] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.192s)
[515.180742] Starting dynamic subtest: pipe-C-eDP-1-size-256
[517.329846] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.158s)
[517.647787] Starting dynamic subtest: pipe-D-eDP-1-size-64
[520.555488] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.921s)
[520.585057] Starting dynamic subtest: pipe-D-eDP-1-size-128
[522.777432] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.203s)
[522.807008] Starting dynamic subtest: pipe-D-eDP-1-size-256
[524.989423] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.192s)
[525.296112] Subtest primary: SUCCESS (32.025s)
[526.148981] [136/156] (514s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[526.255680] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[526.259693] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[526.352762] [137/156] (514s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-gtt)
[526.444282] Starting subtest: hdr-rgb101010-draw-mmap-gtt
[526.448726] Subtest hdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[526.567480] [138/156] (514s left) kms_frontbuffer_tracking (fbcpsrhdr-indfb-scaledprimary)
[526.664339] Starting subtest: fbcpsrhdr-indfb-scaledprimary
[526.668525] Subtest fbcpsrhdr-indfb-scaledprimary: SKIP (0.000s)
[526.762690] [139/156] (514s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[526.856372] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[526.862081] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[526.950870] [140/156] (514s left) gem_ctx_shared (q-promotion)
[527.005409] Starting subtest: Q-promotion
[527.007907] Starting dynamic subtest: rcs0
[527.124109] Dynamic subtest rcs0: SUCCESS (0.117s)
[527.139071] Starting dynamic subtest: vcs0
[527.220968] Dynamic subtest vcs0: SUCCESS (0.096s)
[527.240007] Starting dynamic subtest: vcs1
[527.309243] Dynamic subtest vcs1: SUCCESS (0.087s)
[527.329066] Starting dynamic subtest: vecs0
[527.392252] Dynamic subtest vecs0: SUCCESS (0.082s)
[527.408759] Starting dynamic subtest: ccs0
[527.490277] Dynamic subtest ccs0: SUCCESS (0.096s)
[527.493010] Subtest Q-promotion: SUCCESS (0.485s)
[528.947626] [141/156] (512s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[529.053606] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[529.058204] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[529.158996] [142/156] (511s left) gem_exec_whisper (basic-queues-forked)
[529.235302] Starting subtest: basic-queues-forked
[552.159910] Subtest basic-queues-forked: SUCCESS (22.921s)
[552.349657] [143/156] (488s left) intel_hwmon (hwmon-write)
[552.400605] Subtest hwmon-write: SKIP (0.000s)
[552.479246] [144/156] (488s left) kms_psr (psr2-sprite-mmap-cpu)
[552.585225] Starting subtest: psr2-sprite-mmap-cpu
[552.587469] Starting dynamic subtest: eDP-1
[554.308038] Dynamic subtest eDP-1: SUCCESS (1.722s)
[554.310751] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.723s)
[555.157747] [145/156] (486s left) kms_flip (basic-plain-flip)
[555.267374] Starting subtest: basic-plain-flip
[555.269536] Starting dynamic subtest: A-eDP1
[557.022016] Dynamic subtest A-eDP1: SUCCESS (1.756s)
[557.032888] Starting dynamic subtest: B-eDP1
[558.716069] Dynamic subtest B-eDP1: SUCCESS (1.692s)
[558.728409] Starting dynamic subtest: C-eDP1
[560.409386] Dynamic subtest C-eDP1: SUCCESS (1.692s)
[560.421625] Starting dynamic subtest: D-eDP1
[562.109279] Dynamic subtest D-eDP1: SUCCESS (1.699s)
[562.114368] Subtest basic-plain-flip: SUCCESS (6.849s)
[563.354889] [146/156] (477s left) perf_pmu (busy-accuracy-50)
[563.406385] Starting subtest: busy-accuracy-50
[563.408162] Starting dynamic subtest: rcs0
[564.936203] Dynamic subtest rcs0: SUCCESS (1.527s)
[564.947848] Starting dynamic subtest: bcs0
[566.478977] Dynamic subtest bcs0: SUCCESS (1.541s)
[566.490026] Starting dynamic subtest: vcs0
[568.022753] Dynamic subtest vcs0: SUCCESS (1.541s)
[568.036034] Starting dynamic subtest: vcs1
[569.565127] Dynamic subtest vcs1: SUCCESS (1.538s)
[569.580438] Starting dynamic subtest: vecs0
[571.110029] Dynamic subtest vecs0: SUCCESS (1.541s)
[571.124213] Starting dynamic subtest: ccs0
[572.654523] Dynamic subtest ccs0: SUCCESS (1.543s)
[572.656984] Subtest busy-accuracy-50: SUCCESS (9.245s)
[572.885385] [147/156] (468s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-pwrite)
[572.984458] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-pwrite
[572.988758] Subtest hdr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[573.081917] [148/156] (468s left) i915_drm_fdinfo (virtual-busy-idle-all)
[573.126293] Starting subtest: virtual-busy-idle-all
[573.130535] Subtest virtual-busy-idle-all: SKIP (0.000s)
[573.194636] [149/156] (468s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[573.290240] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[573.294627] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[573.410842] [150/156] (468s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[573.498465] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[573.500714] Starting dynamic subtest: A-eDP1
[577.175367] Dynamic subtest A-eDP1: SUCCESS (3.674s)
[577.188035] Starting dynamic subtest: B-eDP1
[580.485547] Dynamic subtest B-eDP1: SUCCESS (3.309s)
[580.498101] Starting dynamic subtest: C-eDP1
[583.794735] Dynamic subtest C-eDP1: SUCCESS (3.307s)
[583.807483] Starting dynamic subtest: D-eDP1
[587.074631] Dynamic subtest D-eDP1: SUCCESS (3.277s)
[587.076859] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.575s)
[587.953015] [151/156] (453s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[588.059285] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[589.060042] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.001s)
[589.183768] [152/156] (452s left) kms_big_fb (linear-32bpp-rotate-270)
[589.278056] Starting subtest: linear-32bpp-rotate-270
[589.297521] Subtest linear-32bpp-rotate-270: SKIP (0.019s)
[589.426258] [153/156] (452s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[589.529750] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[589.533357] Subtest fbc-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[589.641307] [154/156] (451s left) i915_drm_fdinfo (all-busy-check-all)
[589.678235] Starting subtest: all-busy-check-all
[589.684641] Subtest all-busy-check-all: SKIP (0.003s)
[589.755559] [155/156] (451s left) gem_eio (execbuf)
[589.966060] Starting subtest: execbuf
[590.252680] Subtest execbuf: SUCCESS (0.285s)
[590.365517] [156/156] (451s left) kms_pm_rpm (i2c)
[592.080705] Starting subtest: i2c
[597.334957] Subtest i2c: SUCCESS (5.254s)
[597.431446] Closing watchdogs
results: parsing output: 0/ for test: kms_chamelium_hpd
results: parsing output: 1/ for test: i915_query
results: parsing output: 2/ for test: gem_pxp
results: parsing output: 3/ for test: gem_busy
results: parsing output: 4/ for test: i915_pm_rc6_residency
results: parsing output: 5/ for test: kms_prop_blob
results: parsing output: 6/ for test: gem_ctx_persistence
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: syncobj_wait
results: parsing output: 9/ for test: gem_userptr_blits
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_chamelium_edid
results: parsing output: 14/ for test: gem_exec_schedule
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: kms_atomic
results: parsing output: 17/ for test: kms_vblank
results: parsing output: 18/ for test: kms_cursor_crc
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: sw_sync
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_cursor_legacy
results: parsing output: 23/ for test: kms_flip
results: parsing output: 24/ for test: gem_madvise
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: i915_pm_rps
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 33/ for test: syncobj_timeline
results: parsing output: 34/ for test: kms_psr2_sf
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: gem_partial_pwrite_pread
results: parsing output: 37/ for test: kms_plane_scaling
results: parsing output: 38/ for test: gem_exec_schedule
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_plane_scaling
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: kms_atomic_interruptible
results: parsing output: 44/ for test: gem_mmap_offset
results: parsing output: 45/ for test: kms_plane_multiple
results: parsing output: 46/ for test: syncobj_wait
results: parsing output: 47/ for test: gem_exec_schedule
results: parsing output: 48/ for test: kms_flip_scaled_crc
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: gem_exec_balancer
results: parsing output: 51/ for test: kms_force_connector_basic
results: parsing output: 52/ for test: api_intel_bb
results: parsing output: 53/ for test: gem_eio
results: parsing output: 54/ for test: kms_ccs
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_plane
results: parsing output: 58/ for test: kms_cursor_edge_walk
results: parsing output: 59/ for test: kms_dp_link_training
results: parsing output: 60/ for test: kms_psr2_sf
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: sysfs_heartbeat_interval
results: parsing output: 63/ for test: syncobj_eventfd
results: parsing output: 64/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 65/ for test: kms_ccs
results: parsing output: 66/ for test: gem_vm_create
results: parsing output: 67/ for test: gem_reg_read
results: parsing output: 68/ for test: vgem_basic
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_joiner
results: parsing output: 71/ for test: kms_psr2_su
results: parsing output: 72/ for test: kms_color
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: perf_pmu
results: parsing output: 76/ for test: kms_flip_scaled_crc
results: parsing output: 77/ for test: gem_pxp
results: parsing output: 78/ for test: gem_render_copy
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_pipe_crc_basic
results: parsing output: 81/ for test: gem_eio
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: gem_exec_whisper
results: parsing output: 85/ for test: kms_content_protection
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_ccs
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: gen9_exec_parse
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_exec_params
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_tiled_wb
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_addfb_basic
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_ctx_engines
results: parsing output: 101/ for test: gem_mmap_gtt
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_reg_read
results: parsing output: 105/ for test: kms_dsc
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: core_setmaster
results: parsing output: 108/ for test: api_intel_allocator
results: parsing output: 109/ for test: gem_exec_balancer
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: core_auth
results: parsing output: 112/ for test: gem_exec_fence
results: parsing output: 113/ for test: kms_chamelium_color
results: parsing output: 114/ for test: kms_addfb_basic
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_exec_reloc
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_pipe_b_c_ivb
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_flip
results: parsing output: 123/ for test: gem_ctx_param
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_linear_blits
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_spin_batch
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_vblank
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_plane_cursor
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: gem_ctx_shared
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: gem_exec_whisper
results: parsing output: 142/ for test: intel_hwmon
results: parsing output: 143/ for test: kms_psr
results: parsing output: 144/ for test: kms_flip
results: parsing output: 145/ for test: perf_pmu
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: i915_drm_fdinfo
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_flip
results: parsing output: 150/ for test: kms_big_fb
results: parsing output: 151/ for test: kms_big_fb
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: i915_drm_fdinfo
results: parsing output: 154/ for test: gem_eio
results: parsing output: 155/ for test: kms_pm_rpm
Done.