[54.115746] Initializing watchdogs
[54.116288]   /dev/watchdog0
[54.149856] [001/142] (960s left) perf_pmu (render-node-busy-idle)
[54.617591] Starting subtest: render-node-busy-idle
[54.621588] Starting dynamic subtest: rcs0
[55.335661] Dynamic subtest rcs0: SUCCESS (0.718s)
[55.367108] Starting dynamic subtest: bcs0
[56.007846] Dynamic subtest bcs0: SUCCESS (0.671s)
[56.038950] Starting dynamic subtest: vcs0
[57.440646] Dynamic subtest vcs0: SUCCESS (1.431s)
[57.471112] Starting dynamic subtest: vcs1
[59.420921] Dynamic subtest vcs1: SUCCESS (1.979s)
[59.451313] Starting dynamic subtest: vecs0
[61.468239] Dynamic subtest vecs0: SUCCESS (2.047s)
[61.498204] Starting dynamic subtest: ccs0
[62.146888] Dynamic subtest ccs0: SUCCESS (0.679s)
[62.151217] Subtest render-node-busy-idle: SUCCESS (7.530s)
[62.315193] [002/142] (952s left) prime_vgem (shrink)
[62.880705] Starting subtest: shrink
[62.930133] Subtest shrink: SUCCESS (0.047s)
[63.117481] [003/142] (951s left) kms_psr (psr2_sprite_mmap_gtt)
[63.644037] Starting subtest: psr2_sprite_mmap_gtt
[65.780513] Subtest psr2_sprite_mmap_gtt: SKIP (2.133s)
[66.076155] [004/142] (948s left) kms_psr (psr2_sprite_blt)
[66.561428] Starting subtest: psr2_sprite_blt
[69.276532] Subtest psr2_sprite_blt: SUCCESS (2.711s)
[70.220568] [005/142] (944s left) syncobj_timeline (multi-wait-available-submitted)
[70.609267] Starting subtest: multi-wait-available-submitted
[70.612448] Subtest multi-wait-available-submitted: SUCCESS (0.003s)
[70.751396] [006/142] (944s left) gem_pxp (verify-pxp-stale-buf-execution)
[73.658856] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s)
[73.812470] [007/142] (941s left) syncobj_timeline (signal-point-0)
[74.191495] Starting subtest: signal-point-0
[74.195152] Subtest signal-point-0: SUCCESS (0.001s)
[74.358961] [008/142] (940s left) gem_ctx_shared (create-shared-gtt)
[74.785108] Starting subtest: create-shared-gtt
[76.944847] Subtest create-shared-gtt: SUCCESS (2.155s)
[77.476915] [009/142] (937s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[77.858600] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[77.862503] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[78.025753] [010/142] (937s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_ccs)
[79.213060] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_ccs
[79.219696] Subtest pipe-B-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.002s)
[80.390571] [011/142] (934s left) gem_exec_params (invalid-ring)
[80.785410] Starting subtest: invalid-ring
[80.788993] Subtest invalid-ring: SUCCESS (0.001s)
[80.937124] [012/142] (934s left) gem_exec_balancer (smoke)
[81.371296] Starting subtest: smoke
[110.453713] Subtest smoke: SUCCESS (29.080s)
[112.446665] [013/142] (903s left) kms_content_protection (dp-mst-lic-type-0)
[113.151775] Starting subtest: dp-mst-lic-type-0
[113.159278] Subtest dp-mst-lic-type-0: SKIP (0.002s)
[113.337483] [014/142] (902s left) gem_eio (in-flight-suspend)
[115.447130] Starting subtest: in-flight-suspend
[163.796698] Subtest in-flight-suspend: SUCCESS (48.351s)
[165.609507] [015/142] (850s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[166.097605] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[166.104735] Starting dynamic subtest: pipe-A-eDP-1
[166.355903] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.254s)
[166.769429] Starting dynamic subtest: pipe-B-eDP-1
[167.592482] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.854s)
[167.925293] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.825s)
[168.854637] [016/142] (846s left) kms_chamelium_color (ctm-red-to-blue)
[169.276880] Subtest ctm-red-to-blue: SKIP (0.000s)
[169.354089] [017/142] (846s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[169.628672] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[173.008505] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (3.381s)
[173.343521] [018/142] (842s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_gen12_rc_ccs)
[174.224420] Starting subtest: pipe-A-bad-rotation-90-y_tiled_gen12_rc_ccs
[174.231430] Subtest pipe-A-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[175.692692] [019/142] (840s left) gem_lmem_evict (dontneed-evict-race)
[176.107673] Subtest dontneed-evict-race: SKIP (0.000s)
[176.217360] [020/142] (839s left) dumb_buffer (map-valid)
[176.597342] Starting subtest: map-valid
[176.601161] Subtest map-valid: SUCCESS (0.001s)
[176.765162] [021/142] (839s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[177.307854] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[177.314913] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[177.476934] [022/142] (838s left) kms_invalid_mode (zero-hdisplay)
[177.948815] Starting subtest: zero-hdisplay
[177.952655] Starting dynamic subtest: eDP-1-pipe-A
[178.371520] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.423s)
[178.420922] Starting dynamic subtest: eDP-1-pipe-B
[178.424847] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.039s)
[178.471202] Starting dynamic subtest: eDP-1-pipe-C
[178.474930] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.039s)
[178.519786] Starting dynamic subtest: eDP-1-pipe-D
[178.523329] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.040s)
[178.526827] Subtest zero-hdisplay: SUCCESS (0.566s)
[179.289096] [023/142] (837s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_gen12_mc_ccs)
[180.270053] Starting subtest: pipe-A-random-ccs-data-y_tiled_gen12_mc_ccs
[180.280471] Subtest pipe-A-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[181.657428] [024/142] (834s left) v3d/v3d_submit_cl (bad-extension)
[182.144548] Subtest bad-extension: SKIP (0.000s)
[182.259853] [025/142] (834s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_mc_ccs)
[183.525157] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_mc_ccs
[183.703707] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_mc_ccs: SUCCESS (0.177s)
[184.809893] [026/142] (831s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[185.271959] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[185.278145] Starting dynamic subtest: pipe-A-default-mode
[185.682658] Dynamic subtest pipe-A-default-mode: SKIP (0.397s)
[185.689674] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.408s)
[186.619662] [027/142] (830s left) gem_mmap_gtt (basic-small-copy-xy)
[186.997270] Subtest basic-small-copy-XY: SKIP (0.000s)
[187.102284] [028/142] (829s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[187.589876] Starting subtest: primary-yf-tiled-reflect-x-0
[187.737944] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.148s)
[187.999278] [029/142] (828s left) sysfs_preempt_timeout (timeout)
[188.400425] Starting subtest: timeout
[188.404865] Starting dynamic subtest: vecs0
[308.985232] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[310.559351] Closing watchdogs
[310.574739] Initializing watchdogs
[310.575084]   /dev/watchdog0
[310.629261] [030/142] (707s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[311.169914] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[311.176309] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[311.343612] [031/142] (706s left) gem_exec_schedule (fairslice)
[311.777620] Starting subtest: fairslice
[311.781564] Starting dynamic subtest: rcs0
[313.845142] Dynamic subtest rcs0: SUCCESS (2.063s)
[313.893806] Starting dynamic subtest: bcs0
[315.902798] Dynamic subtest bcs0: SUCCESS (2.055s)
[315.949155] Starting dynamic subtest: vcs0
[317.953155] Dynamic subtest vcs0: SUCCESS (2.049s)
[317.999298] Starting dynamic subtest: vcs1
[320.006534] Dynamic subtest vcs1: SUCCESS (2.051s)
[320.055236] Starting dynamic subtest: vecs0
[322.059161] Dynamic subtest vecs0: SUCCESS (2.050s)
[322.105166] Starting dynamic subtest: ccs0
[324.112348] Dynamic subtest ccs0: SUCCESS (2.051s)
[324.115423] Subtest fairslice: SUCCESS (12.332s)
[324.579968] [032/142] (693s left) kms_psr (primary_mmap_gtt)
[325.069928] Starting subtest: primary_mmap_gtt
[327.944562] Subtest primary_mmap_gtt: SKIP (2.872s)
[328.303232] [033/142] (689s left) kms_writeback (writeback-check-output)
[328.904202] Subtest writeback-check-output: SKIP (0.000s)
[329.029678] [034/142] (689s left) kms_flip (2x-flip-vs-expired-vblank)
[329.420217] Starting subtest: 2x-flip-vs-expired-vblank
[329.582184] Subtest 2x-flip-vs-expired-vblank: SKIP (0.162s)
[329.785167] [035/142] (688s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_rc_ccs)
[331.132207] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs
[331.138868] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[332.135935] [036/142] (686s left) kms_vblank (pipe-b-wait-idle)
[332.609702] Starting subtest: pipe-B-wait-idle
[335.707066] Subtest pipe-B-wait-idle: SUCCESS (3.095s)
[336.656657] [037/142] (681s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[337.035986] Starting subtest: multi-wait-for-submit-unsubmitted
[337.139393] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[337.288639] [038/142] (681s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[337.840798] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[341.432201] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (3.590s)
[342.086904] [039/142] (676s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[342.625840] Starting subtest: y-tiled-64bpp-rotate-180
[342.633809] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[342.785509] [040/142] (675s left) kms_concurrent (pipe-d)
[343.291632] Starting subtest: pipe-D
[343.295992] Starting dynamic subtest: eDP-1
[345.408868] Dynamic subtest eDP-1: SUCCESS (2.113s)
[345.413264] Subtest pipe-D: SUCCESS (2.125s)
[346.372257] [041/142] (672s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[346.873782] Starting subtest: psr-rgb101010-draw-blt
[358.329909] Subtest psr-rgb101010-draw-blt: SUCCESS (11.455s)
[358.526394] [042/142] (660s left) vc4/vc4_create_bo (create-bo-zeroed)
[358.821787] Subtest create-bo-zeroed: SKIP (0.000s)
[358.895416] [043/142] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[359.171824] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[359.176884] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[359.320502] [044/142] (659s left) syncobj_wait (wait-delayed-signal)
[359.453215] Starting subtest: wait-delayed-signal
[359.554381] Subtest wait-delayed-signal: SUCCESS (0.102s)
[359.635593] [045/142] (659s left) kms_vblank (pipe-b-query-idle-hang)
[359.849252] Starting subtest: pipe-B-query-idle-hang
[375.827544] Subtest pipe-B-query-idle-hang: SUCCESS (15.979s)
[376.778237] [046/142] (642s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[377.290059] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[377.297062] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[377.470614] [047/142] (641s left) kms_cursor_crc (cursor-random-256x85)
[378.548741] Starting subtest: cursor-random-256x85
[378.583115] Starting dynamic subtest: pipe-A-eDP-1
[384.401157] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.816s)
[384.513104] Starting dynamic subtest: pipe-D-eDP-1
[390.795620] Dynamic subtest pipe-D-eDP-1: SUCCESS (6.328s)
[390.799142] Subtest cursor-random-256x85: SUCCESS (12.246s)
[391.736840] [048/142] (627s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[392.148326] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[392.317285] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.164s)
[392.484506] [049/142] (626s left) kms_chamelium_hpd (hdmi-hpd)
[393.387231] Subtest hdmi-hpd: SKIP (0.000s)
[394.259371] [050/142] (624s left) syncobj_basic (bad-destroy-pad)
[394.625606] Starting subtest: bad-destroy-pad
[394.629315] Subtest bad-destroy-pad: SUCCESS (0.000s)
[394.776344] [051/142] (624s left) gem_mmap_wc (bad-offset)
[395.155129] Subtest bad-offset: SKIP (0.000s)
[395.269457] [052/142] (624s left) kms_dsc (dsc-with-output-formats)
[395.745346] Starting subtest: dsc-with-output-formats
[395.752430] Subtest dsc-with-output-formats: SKIP (0.004s)
[395.899839] [053/142] (623s left) gem_flink_race (flink_name)
[396.259292] Starting subtest: flink_name
[401.656223] Subtest flink_name: SUCCESS (5.395s)
[401.811904] [054/142] (617s left) kms_ccs (pipe-a-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[402.889897] Starting subtest: pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc
[402.898163] Subtest pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.004s)
[403.709562] [055/142] (615s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[404.897520] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[404.900508] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[405.593022] [056/142] (614s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_ccs)
[406.906994] Starting subtest: pipe-D-missing-ccs-buffer-y_tiled_ccs
[406.913741] Subtest pipe-D-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[407.576911] [057/142] (612s left) kms_ccs (pipe-d-bad-aux-stride-yf_tiled_ccs)
[409.373100] Starting subtest: pipe-D-bad-aux-stride-yf_tiled_ccs
[409.379118] Subtest pipe-D-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s)
[409.998511] [058/142] (609s left) kms_plane (plane-position-hole)
[410.497071] Starting subtest: plane-position-hole
[410.508521] Starting dynamic subtest: pipe-A-planes
[418.549530] Dynamic subtest pipe-A-planes: SUCCESS (8.047s)
[418.607430] Starting dynamic subtest: pipe-B-planes
[427.261507] Dynamic subtest pipe-B-planes: SUCCESS (8.700s)
[427.265097] Subtest plane-position-hole: SUCCESS (16.772s)
[428.125503] [059/142] (591s left) gem_softpin (noreloc-interruptible)
[428.531757] Starting subtest: noreloc-interruptible
[434.377789] Subtest noreloc-interruptible: SUCCESS (5.849s)
[434.646776] [060/142] (585s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[436.249399] Starting subtest: pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[436.255727] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[437.070319] [061/142] (583s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[437.600408] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[450.567587] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (12.973s)
[450.860294] [062/142] (569s left) api_intel_allocator (standalone)
[451.245454] Starting subtest: standalone
[451.352296] Subtest standalone: SUCCESS (0.107s)
[451.539611] [063/142] (568s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[452.045368] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[452.051817] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[452.215128] [064/142] (568s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-render)
[452.725799] Starting subtest: psr-1p-offscren-pri-indfb-draw-render
[464.350389] Subtest psr-1p-offscren-pri-indfb-draw-render: SUCCESS (11.633s)
[464.635440] [065/142] (555s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[465.596252] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_mc_ccs
[465.603047] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.002s)
[467.072831] [066/142] (553s left) i915_pm_rpm (fences-dpms)
[469.729367] Starting subtest: fences-dpms
[469.735810] Subtest fences-dpms: SKIP (0.000s)
[470.370661] [067/142] (550s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[470.760288] Starting subtest: invalid-multi-wait-all-unsubmitted
[470.763978] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.001s)
[470.914553] [068/142] (549s left) v3d/v3d_submit_cl (bad-multisync-in-sync)
[471.381640] Subtest bad-multisync-in-sync: SKIP (0.000s)
[471.515537] [069/142] (549s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[471.996464] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[472.006140] Starting dynamic subtest: pipe-A-default-mode
[472.430269] Dynamic subtest pipe-A-default-mode: SKIP (0.415s)
[472.437609] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.429s)
[473.358463] [070/142] (547s left) kms_prime (basic-crc-hybrid)
[477.197751] Subtest basic-crc-hybrid: SKIP (0.000s)
[477.410117] [071/142] (543s left) gem_exec_schedule (manyslice)
[477.855748] Starting subtest: manyslice
[477.859458] Starting dynamic subtest: rcs0
[488.624934] Dynamic subtest rcs0: SUCCESS (10.766s)
[488.674152] Starting dynamic subtest: bcs0
[498.796152] Dynamic subtest bcs0: SUCCESS (10.166s)
[498.844649] Starting dynamic subtest: vcs0
[507.030864] Dynamic subtest vcs0: SUCCESS (8.231s)
[507.077341] Starting dynamic subtest: vcs1
[512.181379] Dynamic subtest vcs1: SUCCESS (5.146s)
[512.232884] Starting dynamic subtest: vecs0
[520.939487] Dynamic subtest vecs0: SUCCESS (8.754s)
[520.985913] Starting dynamic subtest: ccs0
[530.620463] Dynamic subtest ccs0: SUCCESS (9.676s)
[530.624390] Subtest manyslice: SUCCESS (52.765s)
[531.121531] [072/142] (490s left) gem_ringfill (basic-all)
[537.517058] Starting subtest: basic-all
[538.876810] Subtest basic-all: SUCCESS (1.359s)
[539.664554] [073/142] (481s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[540.169957] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[540.176671] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[540.346089] [074/142] (481s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[540.736273] Subtest writes-after-reads-uncached: SKIP (0.000s)
[540.873693] [075/142] (480s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[541.389305] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[541.396098] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[541.565165] [076/142] (479s left) i915_pm_rps (engine-order)
[541.965830] Starting subtest: engine-order
[548.944637] Subtest engine-order: SUCCESS (6.976s)
[549.149239] [077/142] (472s left) kms_atomic (plane-primary-legacy)
[549.959762] Starting subtest: plane-primary-legacy
[550.257502] Subtest plane-primary-legacy: SUCCESS (0.298s)
[551.595175] [078/142] (470s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[552.058458] Starting subtest: nonblocking-crc-frame-sequence
[552.068492] Starting dynamic subtest: pipe-A-eDP-1
[553.409618] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.343s)
[553.470949] Starting dynamic subtest: pipe-B-eDP-1
[555.480607] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.063s)
[555.535679] Starting dynamic subtest: pipe-C-eDP-1
[557.762624] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.273s)
[557.819547] Starting dynamic subtest: pipe-D-eDP-1
[560.337540] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.558s)
[560.341254] Subtest nonblocking-crc-frame-sequence: SUCCESS (8.278s)
[561.289181] [079/142] (460s left) sw_sync (alloc_timeline)
[561.663929] Starting subtest: alloc_timeline
[561.667725] Subtest alloc_timeline: SUCCESS (0.000s)
[561.804668] [080/142] (460s left) vc4/vc4_tiling (get-bad-modifier)
[562.269069] Subtest get-bad-modifier: SKIP (0.000s)
[562.404398] [081/142] (459s left) gem_mmap_gtt (big-bo-tiledx)
[562.773820] Subtest big-bo-tiledX: SKIP (0.000s)
[562.898997] [082/142] (459s left) kms_sequence (queue-busy)
[563.377519] Starting subtest: queue-busy
[563.381207] Starting dynamic subtest: eDP-1-pipe-A
[565.640973] Dynamic subtest eDP-1-pipe-A: SUCCESS (2.257s)
[565.696889] Starting dynamic subtest: eDP-1-pipe-B
[568.425829] Dynamic subtest eDP-1-pipe-B: SUCCESS (2.777s)
[568.482275] Starting dynamic subtest: eDP-1-pipe-C
[571.639312] Dynamic subtest eDP-1-pipe-C: SUCCESS (3.204s)
[571.696221] Starting dynamic subtest: eDP-1-pipe-D
[574.836236] Dynamic subtest eDP-1-pipe-D: SUCCESS (3.186s)
[574.839747] Subtest queue-busy: SUCCESS (11.463s)
[575.789237] [083/142] (446s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[576.352341] Starting subtest: cursorA-vs-flipB-atomic-transitions
[576.363826] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.008s)
[576.688405] [084/142] (445s left) gem_ctx_param (invalid-get-no-zeromap)
[577.064385] Starting subtest: invalid-get-no-zeromap
[577.067986] Subtest invalid-get-no-zeromap: SUCCESS (0.002s)
[577.221775] [085/142] (445s left) gem_wait (await)
[577.669131] Starting subtest: await
[577.672951] Starting dynamic subtest: all-engines
[578.785077] Dynamic subtest all-engines: SUCCESS (1.114s)
[578.851829] Starting dynamic subtest: rcs0
[579.867440] Dynamic subtest rcs0: SUCCESS (1.078s)
[579.933436] Starting dynamic subtest: bcs0
[580.948469] Dynamic subtest bcs0: SUCCESS (1.076s)
[581.023396] Starting dynamic subtest: vcs0
[582.007596] Dynamic subtest vcs0: SUCCESS (1.055s)
[582.082815] Starting dynamic subtest: vcs1
[583.072360] Dynamic subtest vcs1: SUCCESS (1.059s)
[583.146288] Starting dynamic subtest: vecs0
[584.138291] Dynamic subtest vecs0: SUCCESS (1.061s)
[584.215565] Starting dynamic subtest: ccs0
[585.245472] Dynamic subtest ccs0: SUCCESS (1.102s)
[585.249176] Subtest await: SUCCESS (7.579s)
[585.642474] [086/142] (436s left) sw_sync (timeline_closed_signaled)
[586.013631] Starting subtest: timeline_closed_signaled
[586.017446] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[586.174522] [087/142] (436s left) gem_userptr_blits (sd-probe)
[586.582811] Starting subtest: sd-probe
[586.586245] Subtest sd-probe: SUCCESS (0.004s)
[586.909530] [088/142] (435s left) kms_ccs (pipe-c-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[588.425109] Starting subtest: pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[588.428730] Subtest pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SUCCESS (0.003s)
[589.319353] [089/142] (433s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[590.887471] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs
[590.903360] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.013s)
[591.738722] [090/142] (431s left) vc4/vc4_perfmon (destroy-valid-perfmon)
[592.224834] Subtest destroy-valid-perfmon: SKIP (0.000s)
[592.364718] [091/142] (430s left) kms_vblank (pipe-b-ts-continuation-modeset)
[592.843800] Starting subtest: pipe-B-ts-continuation-modeset
[595.881341] Subtest pipe-B-ts-continuation-modeset: SUCCESS (3.040s)
[596.841215] [092/142] (426s left) perf (non-zero-reason)
[597.802800] Starting subtest: non-zero-reason
[597.806438] Starting dynamic subtest: 0-rcs0
[610.531594] Dynamic subtest 0-rcs0: SUCCESS (12.729s)
[610.551127] Starting dynamic subtest: 1-vecs0
[618.150637] Dynamic subtest 1-vecs0: SUCCESS (7.616s)
[618.154479] Subtest non-zero-reason: SUCCESS (20.353s)
[618.211417] [093/142] (404s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[619.630399] Starting subtest: pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[619.643156] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.007s)
[620.096496] [094/142] (403s left) kms_flip (2x-nonexisting-fb-interruptible)
[620.529374] Starting subtest: 2x-nonexisting-fb-interruptible
[620.700191] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.166s)
[620.881252] [095/142] (402s left) device_reset (unbind-reset-rebind)
[621.686596] Starting subtest: unbind-reset-rebind
[626.403197] Subtest unbind-reset-rebind: SUCCESS (4.714s)
[626.544744] [096/142] (396s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[632.648369] Starting subtest: gem-execbuf-stress-pc8
[632.737544] Subtest gem-execbuf-stress-pc8: SKIP (0.086s)
[633.861287] [097/142] (389s left) i915_pm_dc (dc5-dpms-negative)
[635.359148] Starting subtest: dc5-dpms-negative
[635.367372] Subtest dc5-dpms-negative: SKIP (0.000s)
[635.529128] [098/142] (388s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[635.910362] Starting subtest: invalid-smem-bo-on-discrete
[635.917377] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[636.208186] [099/142] (387s left) kms_rotation_crc (sprite-rotation-90)
[636.693717] Starting subtest: sprite-rotation-90
[636.704516] Subtest sprite-rotation-90: SKIP (0.005s)
[636.859434] [100/142] (386s left) kms_cursor_crc (cursor-offscreen-256x256)
[637.855725] Starting subtest: cursor-offscreen-256x256
[637.948263] Starting dynamic subtest: pipe-A-eDP-1
[640.777373] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.829s)
[640.991390] Starting dynamic subtest: pipe-D-eDP-1
[645.124474] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.182s)
[645.128286] Subtest cursor-offscreen-256x256: SUCCESS (7.266s)
[646.116825] [101/142] (377s left) gem_exec_schedule (timeslicing)
[646.563796] Starting subtest: timeslicing
[646.567189] Starting dynamic subtest: rcs0
[646.597779] Dynamic subtest rcs0: SUCCESS (0.032s)
[646.662870] Starting dynamic subtest: bcs0
[646.666487] Dynamic subtest bcs0: SUCCESS (0.032s)
[646.718236] Starting dynamic subtest: vcs0
[646.721318] Dynamic subtest vcs0: SUCCESS (0.030s)
[646.772542] Starting dynamic subtest: vcs1
[646.776301] Dynamic subtest vcs1: SUCCESS (0.029s)
[646.828302] Starting dynamic subtest: vecs0
[646.831233] Dynamic subtest vecs0: SUCCESS (0.029s)
[646.882074] Starting dynamic subtest: ccs0
[646.885170] Dynamic subtest ccs0: SUCCESS (0.030s)
[646.888148] Subtest timeslicing: SUCCESS (0.207s)
[648.629737] [102/142] (375s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[649.159126] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[649.166140] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[649.328667] [103/142] (374s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[649.810461] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[649.819307] Starting dynamic subtest: pipe-A-eDP-1
[649.862418] Dynamic subtest pipe-A-eDP-1: SKIP (0.039s)
[649.926225] Starting dynamic subtest: pipe-B-eDP-1
[650.031741] Dynamic subtest pipe-B-eDP-1: SKIP (0.155s)
[650.091251] Starting dynamic subtest: pipe-C-eDP-1
[650.189375] Dynamic subtest pipe-C-eDP-1: SKIP (0.148s)
[650.247769] Starting dynamic subtest: pipe-D-eDP-1
[650.347875] Dynamic subtest pipe-D-eDP-1: SKIP (0.148s)
[650.354702] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.535s)
[650.533717] [104/142] (373s left) gem_exec_fence (syncobj-signal)
[652.572872] Starting subtest: syncobj-signal
[652.590620] Subtest syncobj-signal: SUCCESS (0.018s)
[653.066789] [105/142] (371s left) syncobj_wait (wait-all-interrupted)
[653.459750] Starting subtest: wait-all-interrupted
[653.562584] Subtest wait-all-interrupted: SUCCESS (0.103s)
[653.755851] [106/142] (370s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[654.162134] Subtest no-spare-fences-busy: SKIP (0.000s)
[654.311145] [107/142] (370s left) gem_exec_fair (basic-pace)
[654.724505] Subtest basic-pace: SKIP (0.000s)
[654.893625] [108/142] (369s left) prime_mmap (test_dup)
[655.276436] Starting subtest: test_dup
[655.279890] Starting dynamic subtest: test_dup-smem
[655.820770] Dynamic subtest test_dup-smem: SUCCESS (0.542s)
[655.824460] Subtest test_dup: SUCCESS (0.547s)
[656.016994] [109/142] (368s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[656.549513] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[656.556383] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[656.737576] [110/142] (367s left) drm_fdinfo (basics)
[657.125897] Starting subtest: basics
[657.129760] Subtest basics: SUCCESS (0.000s)
[657.284803] [111/142] (367s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[657.665929] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[657.791582] [112/142] (367s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[658.370802] Starting subtest: 2x-flip-vs-cursor-legacy
[658.386896] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.016s)
[658.725574] [113/142] (366s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[659.251347] Starting subtest: psr-farfromfence-mmap-gtt
[659.258378] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[659.432221] [114/142] (365s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[660.341407] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[661.219233] [115/142] (363s left) kms_frontbuffer_tracking (fbcpsr-badstride)
[661.738598] Starting subtest: fbcpsr-badstride
[677.109301] Subtest fbcpsr-badstride: SUCCESS (15.376s)
[677.372426] [116/142] (347s left) gen9_exec_parse (unaligned-access)
[677.782595] Subtest unaligned-access: SKIP (0.000s)
[677.917769] [117/142] (347s left) gem_ctx_persistence (legacy-engines-mixed-process)
[678.314331] Starting subtest: legacy-engines-mixed-process
[678.317998] Starting dynamic subtest: render
[679.564089] Dynamic subtest render: SUCCESS (1.241s)
[679.619408] Starting dynamic subtest: bsd1
[699.640744] Dynamic subtest bsd1: SUCCESS (20.077s)
[699.704250] Starting dynamic subtest: bsd2
[719.771141] Dynamic subtest bsd2: SUCCESS (20.119s)
[719.826819] Starting dynamic subtest: blt
[721.551475] Dynamic subtest blt: SUCCESS (1.774s)
[721.606904] Starting dynamic subtest: vebox
[741.646187] Dynamic subtest vebox: SUCCESS (20.095s)
[741.650106] Subtest legacy-engines-mixed-process: SUCCESS (63.334s)
[741.846513] [118/142] (283s left) gem_busy (busy)
[742.287762] Starting subtest: busy
[742.291160] Starting dynamic subtest: all-engines
[742.505621] Dynamic subtest all-engines: SUCCESS (0.216s)
[742.571147] Starting dynamic subtest: rcs0
[744.491197] Dynamic subtest rcs0: SUCCESS (1.980s)
[744.558086] Starting dynamic subtest: bcs0
[744.561765] Dynamic subtest bcs0: SUCCESS (0.052s)
[744.616738] Starting dynamic subtest: vcs0
[744.619813] Dynamic subtest vcs0: SUCCESS (0.047s)
[744.672351] Starting dynamic subtest: vcs1
[746.477509] Dynamic subtest vcs1: SUCCESS (1.873s)
[746.532447] Starting dynamic subtest: vecs0
[748.526275] Dynamic subtest vecs0: SUCCESS (2.042s)
[748.580493] Starting dynamic subtest: ccs0
[750.499621] Dynamic subtest ccs0: SUCCESS (1.971s)
[750.503099] Subtest busy: SUCCESS (8.211s)
[751.033431] [119/142] (274s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[751.564917] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[751.570965] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[751.743352] [120/142] (273s left) gem_exec_flush (basic-uc-pro-default)
[752.164816] Starting subtest: basic-uc-pro-default
[758.043824] Subtest basic-uc-pro-default: SUCCESS (5.878s)
[758.363253] [121/142] (267s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs)
[759.452491] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs
[762.677767] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs: SUCCESS (3.225s)
[763.940233] [122/142] (261s left) gem_pxp (reject-modify-context-protection-off-1)
[766.842391] Subtest reject-modify-context-protection-off-1: SKIP (0.000s)
[767.031730] [123/142] (258s left) gem_exec_params (invalid-bsd2-flag-on-render)
[767.336276] Starting subtest: invalid-bsd2-flag-on-render
[767.340295] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.001s)
[767.548114] [124/142] (258s left) gem_mmap_gtt (hang)
[767.931936] Subtest hang: SKIP (0.000s)
[768.072947] [125/142] (258s left) gem_exec_store (dword)
[768.501459] Starting subtest: dword
[768.504466] Starting dynamic subtest: rcs0
[768.558625] Dynamic subtest rcs0: SUCCESS (0.062s)
[768.617083] Starting dynamic subtest: bcs0
[768.621149] Dynamic subtest bcs0: SUCCESS (0.051s)
[768.673361] Starting dynamic subtest: vcs0
[770.459993] Dynamic subtest vcs0: SUCCESS (1.840s)
[770.521176] Starting dynamic subtest: vcs1
[772.509463] Dynamic subtest vcs1: SUCCESS (2.045s)
[772.564861] Starting dynamic subtest: vecs0
[774.492386] Dynamic subtest vecs0: SUCCESS (1.978s)
[774.542860] Starting dynamic subtest: ccs0
[774.546352] Dynamic subtest ccs0: SUCCESS (0.050s)
[774.550125] Subtest dword: SUCCESS (6.051s)
[774.868187] [126/142] (251s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[775.426350] Starting subtest: x-tiled-32bpp-rotate-270
[775.487558] Subtest x-tiled-32bpp-rotate-270: SKIP (0.058s)
[775.684495] [127/142] (250s left) syncobj_timeline (invalid-wait-zero-handles)
[776.064395] Starting subtest: invalid-wait-zero-handles
[776.067908] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[776.217506] [128/142] (250s left) i915_query (query-garbage)
[776.601332] Starting subtest: query-garbage
[776.605644] Subtest query-garbage: SUCCESS (0.000s)
[776.761922] [129/142] (249s left) syncobj_wait (invalid-signal-bad-pad)
[777.143316] Starting subtest: invalid-signal-bad-pad
[777.146787] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[777.302573] [130/142] (249s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[777.845888] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[777.853345] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[778.035273] [131/142] (248s left) drm_read (short-buffer-block)
[778.864533] Starting subtest: short-buffer-block
[778.868041] Subtest short-buffer-block: SUCCESS (0.000s)
[779.115613] [132/142] (247s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-pwrite)
[779.637167] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-pwrite
[798.967914] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (19.340s)
[799.264659] [133/142] (227s left) gem_tiled_pread_pwrite
[799.769921] [134/142] (227s left) kms_rotation_crc (exhaust-fences)
[800.249199] Starting subtest: exhaust-fences
[800.257045] Subtest exhaust-fences: SKIP (0.000s)
[800.412527] [135/142] (226s left) kms_addfb_basic (invalid-set-prop)
[800.786908] Starting subtest: invalid-set-prop
[800.790848] Subtest invalid-set-prop: SUCCESS (0.000s)
[801.080682] [136/142] (226s left) kms_addfb_basic (unused-pitches)
[801.459428] Starting subtest: unused-pitches
[801.462516] Subtest unused-pitches: SUCCESS (0.000s)
[801.753906] [137/142] (225s left) kms_cursor_crc (cursor-sliding-512x170)
[803.018721] Starting subtest: cursor-sliding-512x170
[803.030499] Subtest cursor-sliding-512x170: SKIP (0.008s)
[803.345948] [138/142] (224s left) kms_vblank (pipe-a-ts-continuation-idle)
[803.832155] Starting subtest: pipe-A-ts-continuation-idle
[804.444807] Subtest pipe-A-ts-continuation-idle: SUCCESS (0.609s)
[805.399715] [139/142] (222s left) gem_exec_balancer (hog)
[805.833239] Starting subtest: hog
[805.838877] Subtest hog: SKIP (0.000s)
[806.834452] [140/142] (220s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[807.381736] Starting subtest: yf-tiled-64bpp-rotate-270
[807.387827] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[807.546323] [141/142] (220s left) kms_plane_cursor (overlay)
[808.031336] Starting subtest: overlay
[808.042459] Starting dynamic subtest: pipe-A-eDP-1-size-64
[814.314497] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (6.274s)
[814.452396] Starting dynamic subtest: pipe-A-eDP-1-size-128
[821.790776] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (7.404s)
[821.912381] Starting dynamic subtest: pipe-A-eDP-1-size-256
[829.281123] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (7.415s)
[829.805066] Starting dynamic subtest: pipe-B-eDP-1-size-64
[835.777664] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (6.020s)
[835.871841] Starting dynamic subtest: pipe-B-eDP-1-size-128
[843.244166] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (7.404s)
[843.361460] Starting dynamic subtest: pipe-B-eDP-1-size-256
[850.789071] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (7.471s)
[851.305910] Starting dynamic subtest: pipe-C-eDP-1-size-64
[858.856913] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (7.600s)
[858.974516] Starting dynamic subtest: pipe-C-eDP-1-size-128
[862.875962] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (3.952s)
[862.982212] Starting dynamic subtest: pipe-C-eDP-1-size-256
[869.985094] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (7.061s)
[870.499488] Starting dynamic subtest: pipe-D-eDP-1-size-64
[875.249643] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (4.801s)
[875.349178] Starting dynamic subtest: pipe-D-eDP-1-size-128
[882.250742] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (6.950s)
[882.358718] Starting dynamic subtest: pipe-D-eDP-1-size-256
[889.291169] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (6.977s)
[889.753991] Subtest overlay: SUCCESS (81.718s)
[890.695775] [142/142] (137s left) gem_mmap_wc (read-write-distinct)
[891.091936] Subtest read-write-distinct: SKIP (0.000s)
[891.207890] Closing watchdogs
Done.