[61.468632] Initializing watchdogs
[61.468680]   /dev/watchdog0
[61.484768] [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]
[61.489388] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.491593] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.495054] [001/133] (960s left) gem_mmap_wc (write-read)
[61.577481] Subtest write-read: SKIP (0.000s)
[61.632185] [002/133] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[61.742543] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[61.746665] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[61.840663] [003/133] (960s left) gem_partial_pwrite_pread (write-snoop)
[61.901664] Subtest write-snoop: SKIP (0.000s)
[61.969997] [004/133] (960s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[62.032388] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[62.036924] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[62.211799] [005/133] (959s left) kms_plane (plane-position-hole-dpms)
[62.293726] Starting subtest: plane-position-hole-dpms
[62.855386] Starting dynamic subtest: pipe-A-plane-1
[65.209472] Dynamic subtest pipe-A-plane-1: SUCCESS (2.365s)
[65.218112] Starting dynamic subtest: pipe-A-plane-2
[67.563960] Dynamic subtest pipe-A-plane-2: SUCCESS (2.363s)
[67.572550] Starting dynamic subtest: pipe-A-plane-3
[69.940572] Dynamic subtest pipe-A-plane-3: SUCCESS (2.381s)
[69.949790] Starting dynamic subtest: pipe-A-plane-4
[72.297965] Dynamic subtest pipe-A-plane-4: SUCCESS (2.361s)
[72.306068] Starting dynamic subtest: pipe-A-plane-5
[72.324767] Dynamic subtest pipe-A-plane-5: SUCCESS (0.026s)
[73.464087] Starting dynamic subtest: pipe-B-plane-1
[75.909774] Dynamic subtest pipe-B-plane-1: SUCCESS (2.455s)
[75.919197] Starting dynamic subtest: pipe-B-plane-2
[78.349394] Dynamic subtest pipe-B-plane-2: SUCCESS (2.442s)
[78.358590] Starting dynamic subtest: pipe-B-plane-3
[80.713143] Dynamic subtest pipe-B-plane-3: SUCCESS (2.364s)
[80.721217] Starting dynamic subtest: pipe-B-plane-4
[83.062000] Dynamic subtest pipe-B-plane-4: SUCCESS (2.349s)
[83.070390] Starting dynamic subtest: pipe-B-plane-5
[83.088439] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[83.090581] Subtest plane-position-hole-dpms: SUCCESS (20.833s)
[83.884251] [006/133] (938s left) gem_ccs (ctrl-surf-copy-new-ctx)
[83.945242] Starting subtest: ctrl-surf-copy-new-ctx
[83.949354] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[84.045809] [007/133] (938s left) sw_sync (sync_busy)
[84.088067] Starting subtest: sync_busy
[84.090331] Subtest sync_busy: SUCCESS (0.002s)
[84.147710] [008/133] (938s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[84.210328] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[84.270754] [009/133] (938s left) gem_exec_whisper (basic-queues-forked-all)
[84.333322] Starting subtest: basic-queues-forked-all
[104.087035] Subtest basic-queues-forked-all: SUCCESS (19.749s)
[104.269413] [010/133] (918s left) gem_exec_create (madvise)
[104.340299] Starting subtest: madvise
[104.342243] Starting dynamic subtest: smem
[124.499579] Dynamic subtest smem: SUCCESS (20.158s)
[124.502054] Subtest madvise: SUCCESS (20.158s)
[125.359613] [011/133] (897s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[125.466682] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[125.468862] Starting dynamic subtest: pipe-A-eDP-1
[125.491791] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[125.504985] Starting dynamic subtest: pipe-B-eDP-1
[125.527082] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[125.535832] Starting dynamic subtest: pipe-C-eDP-1
[125.557898] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[125.566753] Starting dynamic subtest: pipe-D-eDP-1
[125.588583] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[125.592930] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[127.671907] [012/133] (894s left) kms_atomic (plane-primary-legacy)
[127.761292] Starting subtest: plane-primary-legacy
[127.763625] Starting dynamic subtest: pipe-A-eDP-1
[128.230539] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.470s)
[128.232931] Subtest plane-primary-legacy: SUCCESS (0.471s)
[129.102371] [013/133] (893s left) syncobj_timeline (device-signal-unordered)
[129.167765] Starting subtest: device-signal-unordered
[129.170159] Subtest device-signal-unordered: SUCCESS (0.001s)
[129.270074] [014/133] (893s left) gem_ctx_isolation (nonpriv-switch)
[129.319043] Starting subtest: nonpriv-switch
[129.321334] Starting dynamic subtest: rcs0
[129.606885] Dynamic subtest rcs0: SUCCESS (0.291s)
[129.617487] Starting dynamic subtest: bcs0
[129.900573] Dynamic subtest bcs0: SUCCESS (0.293s)
[129.910947] Starting dynamic subtest: vcs0
[130.204660] Dynamic subtest vcs0: SUCCESS (0.304s)
[130.213921] Starting dynamic subtest: vcs1
[132.218570] Dynamic subtest vcs1: SUCCESS (2.013s)
[132.226596] Starting dynamic subtest: vecs0
[134.212508] Dynamic subtest vecs0: SUCCESS (1.994s)
[134.221269] Starting dynamic subtest: ccs0
[136.233965] Dynamic subtest ccs0: SUCCESS (2.021s)
[136.236440] Subtest nonpriv-switch: SUCCESS (6.918s)
[136.458010] [015/133] (886s left) kms_flip (2x-flip-vs-dpms-on-nop)
[136.566051] Starting subtest: 2x-flip-vs-dpms-on-nop
[136.578392] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.011s)
[136.690455] [016/133] (885s left) gem_exec_balancer (waits)
[136.760714] Starting subtest: waits
[143.651771] Subtest waits: SUCCESS (6.837s)
[144.055269] [017/133] (878s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[144.154310] Starting subtest: x-tiled-32bpp-rotate-90
[144.173985] Subtest x-tiled-32bpp-rotate-90: SKIP (0.019s)
[144.273437] [018/133] (878s left) gem_create (create-ext-placement-all)
[144.429847] Starting subtest: create-ext-placement-all
[144.432590] Subtest create-ext-placement-all: SUCCESS (0.000s)
[144.524216] [019/133] (878s left) gem_exec_schedule (u-independent)
[144.589698] Starting subtest: u-independent
[144.591658] Starting dynamic subtest: rcs0
[145.943069] Dynamic subtest rcs0: SUCCESS (1.360s)
[145.954122] Starting dynamic subtest: vcs0
[147.934884] Dynamic subtest vcs0: SUCCESS (1.990s)
[147.948229] Starting dynamic subtest: vcs1
[149.921755] Dynamic subtest vcs1: SUCCESS (1.986s)
[149.933357] Starting dynamic subtest: vecs0
[151.903978] Dynamic subtest vecs0: SUCCESS (1.981s)
[151.914758] Starting dynamic subtest: ccs0
[153.942411] Dynamic subtest ccs0: SUCCESS (2.037s)
[153.945224] Subtest u-independent: SUCCESS (9.360s)
[154.256617] [020/133] (868s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[154.324673] Starting subtest: y-tiled-ccs-to-yf-tiled
[154.329338] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[154.509781] [021/133] (868s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[154.639347] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[154.643806] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[154.795083] [022/133] (868s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[154.862112] Starting subtest: multi-wait-all-available-submitted-signaled
[154.864071] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[154.955736] [023/133] (867s left) gem_exec_reloc (basic-cpu-read)
[155.013197] Subtest basic-cpu-read: SKIP (0.000s)
[155.086372] [024/133] (867s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[155.140693] Starting subtest: legacy-engines-hostile-preempt
[155.143147] Starting dynamic subtest: render
[155.224334] Dynamic subtest render: SUCCESS (0.083s)
[155.235282] Starting dynamic subtest: bsd1
[156.883638] Dynamic subtest bsd1: SUCCESS (1.656s)
[156.894784] Starting dynamic subtest: bsd2
[158.921430] Dynamic subtest bsd2: SUCCESS (2.036s)
[158.932857] Starting dynamic subtest: blt
[158.968318] Dynamic subtest blt: SUCCESS (0.046s)
[158.978651] Starting dynamic subtest: vebox
[160.912634] Dynamic subtest vebox: SUCCESS (1.941s)
[160.915256] Subtest legacy-engines-hostile-preempt: SUCCESS (5.771s)
[160.994777] [025/133] (861s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[161.100949] Starting subtest: pr-cursor-plane-move-continuous-sf
[161.105034] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[161.172480] [026/133] (861s left) syncobj_wait (invalid-reset-one-illegal-handle)
[161.224099] Starting subtest: invalid-reset-one-illegal-handle
[161.226403] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[161.316812] [027/133] (861s left) gem_tiled_blits (basic)
[161.376438] Subtest basic: SKIP (0.000s)
[161.442103] [028/133] (861s left) kms_debugfs (display-off-read-all)
[161.536181] Starting subtest: display-off-read-all
[161.822436] Subtest display-off-read-all: SUCCESS (0.287s)
[162.626436] [029/133] (860s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[162.725930] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[166.834027] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (4.111s)
[167.966880] [030/133] (855s left) kms_pm_dc (dc5-dpms-negative)
[169.060909] Starting subtest: dc5-dpms-negative
[169.065379] Subtest dc5-dpms-negative: SKIP (0.000s)
[169.144615] [031/133] (854s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[169.260228] Starting subtest: psr2-plane-move-sf-dmg-area
[169.262584] Starting dynamic subtest: pipe-A-eDP-1
[173.803831] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.545s)
[173.812441] Starting dynamic subtest: pipe-B-eDP-1
[179.135558] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.330s)
[179.137790] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (9.878s)
[180.021572] [032/133] (843s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[180.125255] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[180.916915] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.792s)
[181.099151] [033/133] (842s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[181.199367] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[181.203785] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[181.288505] [034/133] (841s left) gem_exec_flush (basic-wb-pro-default)
[181.345839] Starting subtest: basic-wb-pro-default
[186.833585] Subtest basic-wb-pro-default: SUCCESS (5.481s)
[187.023185] [035/133] (836s left) kms_cursor_crc (cursor-onscreen-256x256)
[187.274937] Starting subtest: cursor-onscreen-256x256
[187.277876] Starting dynamic subtest: pipe-A-eDP-1
[189.387479] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.110s)
[189.419324] Starting dynamic subtest: pipe-D-eDP-1
[192.207072] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.794s)
[192.209920] Subtest cursor-onscreen-256x256: SUCCESS (4.932s)
[193.079307] [036/133] (830s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[193.130176] Starting subtest: multi-wait-available-unsubmitted-submitted
[193.132575] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[193.232851] [037/133] (830s left) kms_sequence (queue-busy)
[193.318284] Starting subtest: queue-busy
[193.320528] Starting dynamic subtest: pipe-A-eDP-1
[195.255360] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.933s)
[195.264114] Starting dynamic subtest: pipe-B-eDP-1
[197.875994] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.620s)
[197.885409] Starting dynamic subtest: pipe-C-eDP-1
[200.486610] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.609s)
[200.496046] Starting dynamic subtest: pipe-D-eDP-1
[203.036824] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.551s)
[203.039105] Subtest queue-busy: SUCCESS (9.718s)
[203.915303] [038/133] (819s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[204.033992] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[204.038244] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[204.129039] [039/133] (819s left) kms_cursor_crc (cursor-size-change)
[204.222114] Starting subtest: cursor-size-change
[204.223970] Starting dynamic subtest: pipe-A-eDP-1
[205.017831] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.796s)
[205.028329] Starting dynamic subtest: pipe-D-eDP-1
[206.496040] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.476s)
[206.498390] Subtest cursor-size-change: SUCCESS (2.274s)
[207.298482] [040/133] (816s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[207.394954] Starting subtest: primary-yf-tiled-reflect-x-90
[207.399434] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[207.469248] [041/133] (816s left) kms_big_fb (4-tiled-addfb)
[207.571253] Starting subtest: 4-tiled-addfb
[207.573168] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[207.661725] [042/133] (815s left) kms_big_fb (linear-64bpp-rotate-180)
[207.765071] Starting subtest: linear-64bpp-rotate-180
[209.377897] Subtest linear-64bpp-rotate-180: SUCCESS (1.612s)
[209.540232] [043/133] (814s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier)
[209.642823] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier
[209.647520] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier: SKIP (0.000s)
[209.711344] [044/133] (813s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[209.812218] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[217.145261] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.335s)
[217.290826] [045/133] (806s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[219.023630] Starting subtest: modeset-lpsp-stress-no-wait
[229.015515] Subtest modeset-lpsp-stress-no-wait: SUCCESS (9.994s)
[229.113028] [046/133] (794s left) perf_pmu (most-busy-check-all)
[229.156740] Starting subtest: most-busy-check-all
[229.158943] Starting dynamic subtest: rcs0
[230.933787] Dynamic subtest rcs0: SUCCESS (1.777s)
[230.945647] Starting dynamic subtest: bcs0
[232.918078] Dynamic subtest bcs0: SUCCESS (1.982s)
[232.930101] Starting dynamic subtest: vcs0
[234.901648] Dynamic subtest vcs0: SUCCESS (1.980s)
[234.913948] Starting dynamic subtest: vcs1
[236.888640] Dynamic subtest vcs1: SUCCESS (1.984s)
[236.900618] Starting dynamic subtest: vecs0
[238.934045] Dynamic subtest vecs0: SUCCESS (2.042s)
[238.945783] Starting dynamic subtest: ccs0
[240.917363] Dynamic subtest ccs0: SUCCESS (1.981s)
[240.920057] Subtest most-busy-check-all: SUCCESS (11.762s)
[241.013846] [047/133] (782s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[241.114488] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[244.666963] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.553s)
[245.863992] [048/133] (777s left) kms_plane_cursor (primary)
[245.953544] Starting subtest: primary
[245.958141] Starting dynamic subtest: pipe-A-eDP-1-size-64
[248.516991] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.563s)
[248.540368] Starting dynamic subtest: pipe-A-eDP-1-size-128
[251.128568] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.594s)
[251.153377] Starting dynamic subtest: pipe-A-eDP-1-size-256
[253.763573] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.616s)
[254.078327] Starting dynamic subtest: pipe-B-eDP-1-size-64
[257.370721] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.303s)
[257.394529] Starting dynamic subtest: pipe-B-eDP-1-size-128
[259.949843] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.560s)
[259.975283] Starting dynamic subtest: pipe-B-eDP-1-size-256
[262.539470] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.572s)
[262.854546] Starting dynamic subtest: pipe-C-eDP-1-size-64
[265.875176] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.030s)
[265.906729] Starting dynamic subtest: pipe-C-eDP-1-size-128
[268.096436] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.203s)
[268.122919] Starting dynamic subtest: pipe-C-eDP-1-size-256
[270.296462] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.181s)
[270.618362] Starting dynamic subtest: pipe-D-eDP-1-size-64
[273.557350] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.949s)
[273.583741] Starting dynamic subtest: pipe-D-eDP-1-size-128
[275.768342] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[275.794942] Starting dynamic subtest: pipe-D-eDP-1-size-256
[278.012982] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.225s)
[278.318824] Subtest primary: SUCCESS (32.366s)
[279.181484] [049/133] (744s left) kms_psr (psr-sprite-plane-move)
[279.279166] Starting subtest: psr-sprite-plane-move
[279.281337] Starting dynamic subtest: eDP-1
[281.030875] Dynamic subtest eDP-1: SUCCESS (1.752s)
[281.033062] Subtest psr-sprite-plane-move: SUCCESS (1.753s)
[281.847587] [050/133] (742s left) kms_addfb_basic (small-bo)
[281.932797] Starting subtest: small-bo
[281.934979] Subtest small-bo: SUCCESS (0.000s)
[282.038921] [051/133] (741s left) gem_mmap_gtt (basic-wc)
[282.092591] Subtest basic-wc: SKIP (0.000s)
[282.153687] [052/133] (741s left) gem_pread (self)
[282.201803] Subtest self: SKIP (0.000s)
[282.268314] [053/133] (741s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[282.359066] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[282.363354] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[282.444471] [054/133] (741s left) kms_vblank (query-idle)
[282.553314] Starting subtest: query-idle
[282.555517] Starting dynamic subtest: pipe-A-eDP-1
[284.303489] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.749s)
[284.318268] Starting dynamic subtest: pipe-D-eDP-1
[286.789425] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.483s)
[286.791626] Subtest query-idle: SUCCESS (4.236s)
[287.630842] [055/133] (736s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[287.728049] Starting subtest: fbc-pr-sprite-mmap-cpu
[287.732474] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[287.798938] [056/133] (736s left) kms_psr (pr-cursor-blt)
[287.888068] Starting subtest: pr-cursor-blt
[287.891998] Subtest pr-cursor-blt: SKIP (0.000s)
[287.973112] [057/133] (736s left) gem_exec_whisper (basic-fds-priority-all)
[288.031401] Starting subtest: basic-fds-priority-all
[310.147334] Subtest basic-fds-priority-all: SUCCESS (22.112s)
[310.330927] [058/133] (713s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[310.424939] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[310.427306] Starting dynamic subtest: pipe-A-eDP-1
[310.450540] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[310.462129] Starting dynamic subtest: pipe-B-eDP-1
[310.483762] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[310.492884] Starting dynamic subtest: pipe-C-eDP-1
[310.514620] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[310.523135] Starting dynamic subtest: pipe-D-eDP-1
[310.545842] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[310.550240] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[312.663226] [059/133] (711s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[312.758612] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[312.762935] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[312.857981] [060/133] (711s left) kms_feature_discovery (display)
[312.948109] Starting subtest: display
[312.950610] Subtest display: SUCCESS (0.000s)
[313.019282] [061/133] (711s left) perf (global-sseu-config-invalid)
[313.140559] Starting subtest: global-sseu-config-invalid
[313.144738] Subtest global-sseu-config-invalid: SKIP (0.000s)
[313.185359] [062/133] (711s left) perf (polling-small-buf)
[313.280486] Starting subtest: polling-small-buf
[313.377539] Subtest polling-small-buf: SUCCESS (0.098s)
[313.423470] [063/133] (710s left) kms_addfb_basic (unused-handle)
[313.477197] Starting subtest: unused-handle
[313.479582] Subtest unused-handle: SUCCESS (0.000s)
[313.615912] [064/133] (710s left) gem_userptr_blits (mmap-offset-banned)
[313.692701] Starting subtest: mmap-offset-banned
[313.694564] Starting dynamic subtest: wb
[313.696507] Dynamic subtest wb: SUCCESS (0.000s)
[313.704992] Starting dynamic subtest: wc
[313.706963] Dynamic subtest wc: SUCCESS (0.000s)
[313.715100] Starting dynamic subtest: uc
[313.716846] Dynamic subtest uc: SUCCESS (0.000s)
[313.718728] Subtest mmap-offset-banned: SUCCESS (0.004s)
[313.816038] [065/133] (710s left) gem_exec_parallel (basic)
[313.876118] Starting subtest: basic
[313.878049] Starting dynamic subtest: rcs0
[315.318723] Dynamic subtest rcs0: SUCCESS (1.442s)
[315.327954] Starting dynamic subtest: bcs0
[316.803106] Dynamic subtest bcs0: SUCCESS (1.482s)
[316.812271] Starting dynamic subtest: vcs0
[318.932975] Dynamic subtest vcs0: SUCCESS (2.126s)
[318.944480] Starting dynamic subtest: vcs1
[320.853672] Dynamic subtest vcs1: SUCCESS (1.918s)
[320.865741] Starting dynamic subtest: vecs0
[322.901519] Dynamic subtest vecs0: SUCCESS (2.045s)
[322.912962] Starting dynamic subtest: ccs0
[324.363506] Dynamic subtest ccs0: SUCCESS (1.461s)
[324.365857] Subtest basic: SUCCESS (10.488s)
[324.554616] [066/133] (699s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[324.655043] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[324.659272] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[324.723575] [067/133] (699s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[324.826599] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[324.831064] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[324.924203] [068/133] (699s left) sysfs_timeslice_duration (idempotent)
[324.986751] Starting subtest: idempotent
[324.989022] Starting dynamic subtest: vecs0
[324.991323] Dynamic subtest vecs0: SUCCESS (0.000s)
[325.000983] Starting dynamic subtest: ccs0
[325.002867] Dynamic subtest ccs0: SUCCESS (0.000s)
[325.011550] Starting dynamic subtest: vcs0
[325.013581] Dynamic subtest vcs0: SUCCESS (0.000s)
[325.021826] Starting dynamic subtest: bcs0
[325.023633] Dynamic subtest bcs0: SUCCESS (0.000s)
[325.031783] Starting dynamic subtest: rcs0
[325.033669] Dynamic subtest rcs0: SUCCESS (0.000s)
[325.041688] Starting dynamic subtest: vcs1
[325.043547] Dynamic subtest vcs1: SUCCESS (0.000s)
[325.045424] Subtest idempotent: SUCCESS (0.006s)
[325.085262] [069/133] (699s left) kms_cursor_edge_walk (256x256-right-edge)
[325.183207] Starting subtest: 256x256-right-edge
[325.185544] Starting dynamic subtest: pipe-A-eDP-1
[328.631285] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.447s)
[328.643723] Starting dynamic subtest: pipe-D-eDP-1
[331.762747] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.132s)
[331.765080] Subtest 256x256-right-edge: SUCCESS (6.582s)
[332.558534] [070/133] (692s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[332.665451] Starting subtest: bad-pixel-format-y-tiled-ccs
[332.667691] Starting dynamic subtest: pipe-A-eDP-1
[332.690820] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[332.704036] Starting dynamic subtest: pipe-B-eDP-1
[332.725572] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[332.734094] Starting dynamic subtest: pipe-C-eDP-1
[332.755858] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[332.764313] Starting dynamic subtest: pipe-D-eDP-1
[332.786731] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[332.790803] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.008s)
[334.930373] [071/133] (689s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[335.029476] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[335.031844] Starting dynamic subtest: A-eDP1
[343.983058] Dynamic subtest A-eDP1: SUCCESS (8.951s)
[343.993599] Starting dynamic subtest: B-eDP1
[352.915492] Dynamic subtest B-eDP1: SUCCESS (8.932s)
[352.925730] Starting dynamic subtest: C-eDP1
[361.891685] Dynamic subtest C-eDP1: SUCCESS (8.929s)
[361.902675] Starting dynamic subtest: D-eDP1
[370.823911] Dynamic subtest D-eDP1: SUCCESS (8.927s)
[370.826296] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.751s)
[372.006692] [072/133] (652s left) kms_flip (2x-plain-flip-fb-recreate)
[372.099143] Starting subtest: 2x-plain-flip-fb-recreate
[372.111089] Subtest 2x-plain-flip-fb-recreate: SKIP (0.011s)
[372.202390] [073/133] (652s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[372.296140] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[372.298357] Starting dynamic subtest: pipe-A
[372.318362] Dynamic subtest pipe-A: SKIP (0.018s)
[372.327805] Starting dynamic subtest: pipe-B
[372.350979] Dynamic subtest pipe-B: SKIP (0.032s)
[372.359813] Starting dynamic subtest: pipe-C
[372.382765] Dynamic subtest pipe-C: SKIP (0.032s)
[372.391387] Starting dynamic subtest: pipe-D
[372.413906] Dynamic subtest pipe-D: SKIP (0.031s)
[372.417731] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.115s)
[372.519274] [074/133] (652s left) core_setmaster (master-drop-set-shared-fd)
[372.593872] Starting subtest: master-drop-set-shared-fd
[372.615956] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[372.656085] [075/133] (652s left) kms_mmap_write_crc (main)
[372.751201] Starting subtest: main
[372.753533] Starting dynamic subtest: pipe-A-eDP-1
[376.270292] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.515s)
[376.272497] Subtest main: SUCCESS (3.516s)
[377.153750] [076/133] (647s left) i915_query (query-garbage)
[377.212819] Starting subtest: query-garbage
[377.214807] Subtest query-garbage: SUCCESS (0.000s)
[377.308611] [077/133] (647s left) i915_pm_rps (min-max-config-loaded)
[377.377172] Starting subtest: min-max-config-loaded
[377.381713] Subtest min-max-config-loaded: SKIP (0.000s)
[377.502081] [078/133] (647s left) gem_mmap_offset (open-flood)
[377.562310] Starting subtest: open-flood
[399.051615] Subtest open-flood: SUCCESS (21.490s)
[399.161958] [079/133] (625s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[399.550791] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[400.357788] [080/133] (624s left) kms_concurrent (multi-plane-atomic-lowres)
[400.441259] Starting subtest: multi-plane-atomic-lowres
[400.443143] Starting dynamic subtest: pipe-A-eDP-1
[400.950763] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.506s)
[400.960932] Starting dynamic subtest: pipe-B-eDP-1
[402.207971] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.257s)
[402.217071] Starting dynamic subtest: pipe-C-eDP-1
[403.445558] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.237s)
[403.455361] Starting dynamic subtest: pipe-D-eDP-1
[404.678834] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.231s)
[404.681251] Subtest multi-plane-atomic-lowres: SUCCESS (4.236s)
[405.507275] [081/133] (619s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[405.603774] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[412.548767] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.945s)
[412.687937] [082/133] (612s left) kms_getfb (getfb-handle-closed)
[412.792345] Starting subtest: getfb-handle-closed
[412.794679] Subtest getfb-handle-closed: SUCCESS (0.000s)
[412.884749] [083/133] (612s left) kms_flip (flip-vs-panning-vs-hang)
[412.985180] Starting subtest: flip-vs-panning-vs-hang
[412.987116] Starting dynamic subtest: A-eDP1
[464.385129] Dynamic subtest A-eDP1: SUCCESS (51.400s)
[464.394228] Starting dynamic subtest: D-eDP1
[504.383639] Dynamic subtest D-eDP1: SUCCESS (39.995s)
[504.388656] Subtest flip-vs-panning-vs-hang: SUCCESS (91.402s)
[505.623397] [084/133] (519s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[505.744563] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[505.748903] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[505.890531] [085/133] (519s left) kms_flip (2x-dpms-vs-vblank-race)
[505.977077] Starting subtest: 2x-dpms-vs-vblank-race
[505.989299] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[506.066356] [086/133] (519s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[506.161217] Starting subtest: compare-crc-sanitycheck-xr24
[506.163494] Starting dynamic subtest: pipe-A-eDP-1
[506.790282] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.629s)
[506.800141] Starting dynamic subtest: pipe-B-eDP-1
[508.074507] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.281s)
[508.084206] Starting dynamic subtest: pipe-C-eDP-1
[509.276777] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.202s)
[509.286791] Starting dynamic subtest: pipe-D-eDP-1
[510.516557] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.239s)
[510.519142] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.357s)
[511.340624] [087/133] (514s left) kms_invalid_mode (clock-too-high)
[511.436499] Starting subtest: clock-too-high
[511.438850] Starting dynamic subtest: pipe-A-eDP-1
[511.443098] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[511.452388] Starting dynamic subtest: pipe-B-eDP-1
[511.456845] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[511.475619] Starting dynamic subtest: pipe-C-eDP-1
[511.480630] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[511.493700] Starting dynamic subtest: pipe-D-eDP-1
[511.497917] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[511.502103] Subtest clock-too-high: SKIP (0.005s)
[511.541035] [088/133] (513s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[511.638211] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[511.642642] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[511.733181] [089/133] (513s left) syncobj_timeline (signal-point-0)
[511.793855] Starting subtest: signal-point-0
[511.796125] Subtest signal-point-0: SUCCESS (0.000s)
[511.902978] [090/133] (513s left) gen9_exec_parse (allowed-all)
[511.958703] Subtest allowed-all: SKIP (0.000s)
[512.014535] [091/133] (513s left) gem_exec_schedule (preempt-queue-chain)
[512.098265] Subtest preempt-queue-chain: SKIP (0.000s)
[512.382951] [092/133] (513s left) gem_spin_batch (legacy-resubmit)
[512.443045] Starting subtest: legacy-resubmit
[512.445236] Starting dynamic subtest: default
[512.449724] Dynamic subtest default: SUCCESS (0.005s)
[512.458613] Starting dynamic subtest: render
[512.460920] Dynamic subtest render: SUCCESS (0.001s)
[512.469433] Starting dynamic subtest: bsd
[512.471268] Dynamic subtest bsd: SUCCESS (0.003s)
[512.479258] Starting dynamic subtest: bsd1
[512.481067] Dynamic subtest bsd1: SUCCESS (0.001s)
[512.489261] Starting dynamic subtest: bsd2
[512.491107] Dynamic subtest bsd2: SUCCESS (0.002s)
[512.499274] Starting dynamic subtest: blt
[512.501146] Dynamic subtest blt: SUCCESS (0.002s)
[512.509189] Starting dynamic subtest: vebox
[512.511059] Dynamic subtest vebox: SUCCESS (0.003s)
[512.512917] Subtest legacy-resubmit: SUCCESS (0.023s)
[512.692147] [093/133] (512s left) kms_psr (psr-basic)
[512.776582] Starting subtest: psr-basic
[512.779021] Starting dynamic subtest: eDP-1
[514.420495] Dynamic subtest eDP-1: SUCCESS (1.644s)
[514.422763] Subtest psr-basic: SUCCESS (1.645s)
[515.294330] [094/133] (510s left) api_intel_allocator (reopen-fork)
[515.349918] Starting subtest: reopen-fork
[518.769620] Subtest reopen-fork: SUCCESS (3.420s)
[518.846513] [095/133] (506s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[518.942063] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[518.946617] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[519.047883] [096/133] (506s left) i915_drm_fdinfo (virtual-busy-hang)
[519.093185] Starting subtest: virtual-busy-hang
[519.097252] Subtest virtual-busy-hang: SKIP (0.000s)
[519.158568] [097/133] (506s left) gem_ctx_bad_destroy (invalid-pad)
[519.213887] Starting subtest: invalid-pad
[519.216297] Subtest invalid-pad: SUCCESS (0.000s)
[519.303255] [098/133] (506s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[519.409475] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[519.413635] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[519.506090] [099/133] (506s left) i915_pm_rps (thresholds)
[519.566337] Starting subtest: thresholds
[519.570893] Subtest thresholds: SKIP (0.001s)
[519.676796] [100/133] (506s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[519.771187] Starting subtest: addfb25-y-tiled-small-legacy
[519.775274] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[519.859747] [101/133] (506s left) api_intel_bb (object-reloc-purge-cache)
[519.914497] Starting subtest: object-reloc-purge-cache
[519.918451] Subtest object-reloc-purge-cache: SKIP (0.003s)
[520.038919] [102/133] (505s left) gem_mmap_gtt (basic-read)
[520.092445] Subtest basic-read: SKIP (0.000s)
[520.149256] [103/133] (505s left) gem_exec_params (larger-than-life-batch)
[520.204492] Starting subtest: larger-than-life-batch
[522.974362] Subtest larger-than-life-batch: SUCCESS (2.768s)
[523.978459] [104/133] (502s left) gem_eio (create)
[524.241658] Starting subtest: create
[524.563263] Subtest create: SUCCESS (0.321s)
[524.689515] [105/133] (501s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[524.782511] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[524.787146] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[525.933310] [106/133] (500s left) gem_exec_flush (basic-wb-ro-before-default)
[525.992462] Starting subtest: basic-wb-ro-before-default
[531.445198] Subtest basic-wb-ro-before-default: SUCCESS (5.454s)
[531.631448] [107/133] (494s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[531.745817] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[538.441615] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.699s)
[538.570308] [108/133] (487s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[538.963964] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[539.790140] [109/133] (486s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[539.906283] Starting subtest: y-tiled-64bpp-rotate-270
[539.910153] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[539.992097] [110/133] (486s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[540.630679] Starting subtest: syncobj-timeline-invalid-wait
[540.633164] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[541.978984] [FACT gem_exec_fence (syncobj-timeline-invalid-wait)] new: kernel.kmod_is_loaded.vgem: true
[541.982396] [111/133] (484s left) gem_exec_balancer (nop)
[542.044101] Starting subtest: nop
[588.704240] Subtest nop: SUCCESS (46.656s)
[589.012397] [112/133] (437s left) vgem_basic (dmabuf-fence)
[589.061741] Starting subtest: dmabuf-fence
[589.063640] Subtest dmabuf-fence: SUCCESS (0.000s)
[589.121239] [113/133] (437s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[589.236720] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[589.240855] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[589.339493] [114/133] (437s left) kms_prop_blob (invalid-get-prop)
[589.403920] Starting subtest: invalid-get-prop
[589.406179] Subtest invalid-get-prop: SUCCESS (0.000s)
[589.519278] [115/133] (436s left) kms_cdclk (mode-transition-all-outputs)
[589.618540] Starting subtest: mode-transition-all-outputs
[589.909930] Subtest mode-transition-all-outputs: SKIP (0.290s)
[590.737021] [116/133] (435s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[590.808772] Starting subtest: etime-multi-wait-for-submit-submitted
[590.914590] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.103s)
[590.990253] [117/133] (435s left) gem_eio (in-flight-contexts-1us)
[591.209591] Starting subtest: in-flight-contexts-1us
[604.902805] Subtest in-flight-contexts-1us: SUCCESS (13.691s)
[605.074946] [118/133] (421s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[605.162324] Starting subtest: fbc-psr-sprite-mmap-cpu
[605.164233] Starting dynamic subtest: eDP-1
[605.168219] Dynamic subtest eDP-1: SKIP (0.000s)
[605.172272] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[605.248293] [119/133] (421s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[605.535724] Starting subtest: cursor-rapid-movement-512x512
[605.539458] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[605.706933] [120/133] (420s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[605.817316] Starting subtest: cursor-vs-flip-atomic-transitions
[635.766075] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.947s)
[636.572464] [121/133] (390s left) kms_busy (extended-pageflip-hang-newfb)
[636.679079] Starting subtest: extended-pageflip-hang-newfb
[636.681421] Starting dynamic subtest: pipe-A
[662.757869] Dynamic subtest pipe-A: SUCCESS (26.078s)
[662.768188] Starting dynamic subtest: pipe-D
[687.674911] Dynamic subtest pipe-D: SUCCESS (24.911s)
[687.677232] Subtest extended-pageflip-hang-newfb: SUCCESS (50.994s)
[688.535794] [122/133] (338s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[688.601772] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[688.661695] [123/133] (338s left) gem_userptr_blits (readonly-pwrite-unsync)
[688.733783] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[688.853426] [124/133] (338s left) sw_sync (sync_merge)
[688.893911] Starting subtest: sync_merge
[688.895857] Subtest sync_merge: SUCCESS (0.000s)
[688.947801] [125/133] (337s left) i915_drm_fdinfo (virtual-busy-idle-all)
[688.987137] Starting subtest: virtual-busy-idle-all
[688.991150] Subtest virtual-busy-idle-all: SKIP (0.000s)
[689.066679] [126/133] (337s left) prime_mmap (test_correct_cpu_write)
[689.134312] Starting subtest: test_correct_cpu_write
[689.136639] Starting dynamic subtest: test_correct_cpu_write-smem
[689.138886] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[689.141139] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[689.266712] [127/133] (337s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[689.359765] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[693.068566] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.712s)
[694.249253] [128/133] (332s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[694.461294] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[701.802548] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.344s)
[701.906793] [129/133] (325s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[702.008622] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[702.012779] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[702.104656] [130/133] (325s left) kms_color_pipeline (plane-ctm3x4)
[702.196315] Starting subtest: plane-ctm3x4
[702.331878] Starting dynamic subtest: pipe-A-plane-0
[702.686254] Dynamic subtest pipe-A-plane-0: FAIL (0.183s)
[702.697934] Starting dynamic subtest: pipe-A-plane-1
[702.873541] Dynamic subtest pipe-A-plane-1: FAIL (0.147s)
[702.883781] Starting dynamic subtest: pipe-A-plane-2
[703.079975] Dynamic subtest pipe-A-plane-2: FAIL (0.168s)
[704.083435] Starting dynamic subtest: pipe-B-plane-0
[704.483998] Dynamic subtest pipe-B-plane-0: FAIL (0.152s)
[704.495752] Starting dynamic subtest: pipe-B-plane-1
[704.793176] Dynamic subtest pipe-B-plane-1: FAIL (0.155s)
[704.804147] Starting dynamic subtest: pipe-B-plane-2
[705.100524] Dynamic subtest pipe-B-plane-2: FAIL (0.144s)
[705.807667] Starting dynamic subtest: pipe-C-plane-0
[706.301284] Dynamic subtest pipe-C-plane-0: FAIL (0.131s)
[706.311626] Starting dynamic subtest: pipe-C-plane-1
[706.687929] Dynamic subtest pipe-C-plane-1: FAIL (0.132s)
[706.697280] Starting dynamic subtest: pipe-C-plane-2
[707.042070] Dynamic subtest pipe-C-plane-2: FAIL (0.598s)
[707.840613] Starting dynamic subtest: pipe-D-plane-0
[708.386973] Dynamic subtest pipe-D-plane-0: FAIL (0.107s)
[708.398856] Starting dynamic subtest: pipe-D-plane-1
[708.841990] Dynamic subtest pipe-D-plane-1: FAIL (0.594s)
[708.852234] Starting dynamic subtest: pipe-D-plane-2
[709.260295] Dynamic subtest pipe-D-plane-2: FAIL (0.508s)
[709.341522] Subtest plane-ctm3x4: FAIL (7.145s)
[710.204204] [131/133] (316s left) gem_eio (wait-1us)
[710.418334] Starting subtest: wait-1us
[710.774928] Subtest wait-1us: SUCCESS (0.358s)
[710.924218] [132/133] (316s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[711.026829] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[711.031413] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[711.128754] [133/133] (316s left) gem_eio (throttle)
[711.352336] Starting subtest: throttle
[711.658833] Subtest throttle: SUCCESS (0.305s)
[711.797148] Closing watchdogs
results: parsing output: 0/ for test: gem_mmap_wc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_partial_pwrite_pread
results: parsing output: 3/ for test: gem_render_copy
results: parsing output: 4/ for test: kms_plane
results: parsing output: 5/ for test: gem_ccs
results: parsing output: 6/ for test: sw_sync
results: parsing output: 7/ for test: gem_mmap_gtt
results: parsing output: 8/ for test: gem_exec_whisper
results: parsing output: 9/ for test: gem_exec_create
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: kms_atomic
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: gem_ctx_isolation
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_exec_balancer
results: parsing output: 16/ for test: kms_big_fb
results: parsing output: 17/ for test: gem_create
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: gem_render_copy
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_exec_reloc
results: parsing output: 23/ for test: gem_ctx_persistence
results: parsing output: 24/ for test: kms_psr2_sf
results: parsing output: 25/ for test: syncobj_wait
results: parsing output: 26/ for test: gem_tiled_blits
results: parsing output: 27/ for test: kms_debugfs
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_pm_dc
results: parsing output: 30/ for test: kms_psr2_sf
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: gem_exec_flush
results: parsing output: 34/ for test: kms_cursor_crc
results: parsing output: 35/ for test: syncobj_timeline
results: parsing output: 36/ for test: kms_sequence
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_cursor_crc
results: parsing output: 39/ for test: kms_rotation_crc
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_plane
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_pm_rpm
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_plane_cursor
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: kms_addfb_basic
results: parsing output: 50/ for test: gem_mmap_gtt
results: parsing output: 51/ for test: gem_pread
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_vblank
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: gem_exec_whisper
results: parsing output: 57/ for test: kms_ccs
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_feature_discovery
results: parsing output: 60/ for test: perf
results: parsing output: 61/ for test: perf
results: parsing output: 62/ for test: kms_addfb_basic
results: parsing output: 63/ for test: gem_userptr_blits
results: parsing output: 64/ for test: gem_exec_parallel
results: parsing output: 65/ for test: kms_flip_scaled_crc
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: sysfs_timeslice_duration
results: parsing output: 68/ for test: kms_cursor_edge_walk
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_flip
results: parsing output: 72/ for test: kms_plane_scaling
results: parsing output: 73/ for test: core_setmaster
results: parsing output: 74/ for test: kms_mmap_write_crc
results: parsing output: 75/ for test: i915_query
results: parsing output: 76/ for test: i915_pm_rps
results: parsing output: 77/ for test: gem_mmap_offset
results: parsing output: 78/ for test: kms_chamelium_edid
results: parsing output: 79/ for test: kms_concurrent
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_getfb
results: parsing output: 82/ for test: kms_flip
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: kms_flip
results: parsing output: 85/ for test: kms_pipe_crc_basic
results: parsing output: 86/ for test: kms_invalid_mode
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: gen9_exec_parse
results: parsing output: 90/ for test: gem_exec_schedule
results: parsing output: 91/ for test: gem_spin_batch
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: api_intel_allocator
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: i915_drm_fdinfo
results: parsing output: 96/ for test: gem_ctx_bad_destroy
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: i915_pm_rps
results: parsing output: 99/ for test: kms_addfb_basic
results: parsing output: 100/ for test: api_intel_bb
results: parsing output: 101/ for test: gem_mmap_gtt
results: parsing output: 102/ for test: gem_exec_params
results: parsing output: 103/ for test: gem_eio
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: gem_exec_flush
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_chamelium_hpd
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: gem_exec_fence
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: vgem_basic
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_prop_blob
results: parsing output: 114/ for test: kms_cdclk
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: gem_eio
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_cursor_crc
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: kms_busy
results: parsing output: 121/ for test: gem_mmap_gtt
results: parsing output: 122/ for test: gem_userptr_blits
results: parsing output: 123/ for test: sw_sync
results: parsing output: 124/ for test: i915_drm_fdinfo
results: parsing output: 125/ for test: prime_mmap
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: kms_color_pipeline
results: parsing output: 130/ for test: gem_eio
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_eio
Done.