[57.977855] Initializing watchdogs
[57.977987]   /dev/watchdog0
[57.993154] [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]
[57.997476] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.999723] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.003364] [001/133] (960s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[58.081771] Starting subtest: y-tiled-to-vebox-yf-tiled
[58.086074] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[58.284190] [002/133] (960s left) kms_flip (nonexisting-fb-interruptible)
[58.382580] Starting subtest: nonexisting-fb-interruptible
[58.384955] Starting dynamic subtest: A-eDP1
[59.561182] Dynamic subtest A-eDP1: SUCCESS (1.176s)
[59.567889] Starting dynamic subtest: B-eDP1
[60.698495] Dynamic subtest B-eDP1: SUCCESS (1.137s)
[60.706367] Starting dynamic subtest: C-eDP1
[61.835077] Dynamic subtest C-eDP1: SUCCESS (1.134s)
[61.841786] Starting dynamic subtest: D-eDP1
[62.970237] Dynamic subtest D-eDP1: SUCCESS (1.134s)
[62.975146] Subtest nonexisting-fb-interruptible: SUCCESS (4.592s)
[64.134460] [003/133] (954s left) kms_content_protection (uevent-hdcp14)
[64.280870] Starting subtest: uevent-hdcp14
[64.285684] Subtest uevent-hdcp14: SKIP (0.000s)
[64.406052] [004/133] (954s left) gem_close_race (multigpu-basic-threads)
[64.477596] Starting subtest: multigpu-basic-threads
[64.482257] Subtest multigpu-basic-threads: SKIP (0.000s)
[64.700189] [005/133] (953s left) tools_test (tools_test)
[64.754923] Starting subtest: tools_test
[64.809440] Subtest tools_test: SUCCESS (0.071s)
[64.868539] [006/133] (953s left) gem_mmap_gtt (bad-object)
[64.928370] Subtest bad-object: SKIP (0.000s)
[64.985998] [007/133] (953s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[65.034041] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[65.036491] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s)
[65.135957] [008/133] (953s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[65.237580] Starting subtest: fbc-indfb-scaledprimary
[68.385093] Subtest fbc-indfb-scaledprimary: SUCCESS (3.151s)
[69.526192] [009/133] (949s left) prime_mmap (test_refcounting)
[69.592166] Starting subtest: test_refcounting
[69.594556] Starting dynamic subtest: test_refcounting-smem
[69.624171] Dynamic subtest test_refcounting-smem: SUCCESS (0.034s)
[69.626604] Subtest test_refcounting: SUCCESS (0.034s)
[69.717576] [010/133] (949s left) gem_ctx_shared (q-in-order)
[69.785795] Starting subtest: Q-in-order
[69.788255] Starting dynamic subtest: rcs0
[69.910657] Dynamic subtest rcs0: SUCCESS (0.123s)
[69.924183] Starting dynamic subtest: vcs0
[69.985346] Dynamic subtest vcs0: SUCCESS (0.075s)
[70.000213] Starting dynamic subtest: vcs1
[70.057032] Dynamic subtest vcs1: SUCCESS (0.071s)
[70.071197] Starting dynamic subtest: vecs0
[70.129311] Dynamic subtest vecs0: SUCCESS (0.072s)
[70.144069] Starting dynamic subtest: ccs0
[70.209417] Dynamic subtest ccs0: SUCCESS (0.077s)
[70.212125] Subtest Q-in-order: SUCCESS (0.422s)
[71.975770] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[71.978960] [011/133] (946s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[72.075065] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[72.077449] Starting dynamic subtest: pipe-A-eDP-1
[72.101369] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[72.109062] Starting dynamic subtest: pipe-B-eDP-1
[72.130815] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[72.137683] Starting dynamic subtest: pipe-C-eDP-1
[72.159270] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[72.166023] Starting dynamic subtest: pipe-D-eDP-1
[72.187134] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[72.191409] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[74.376916] [012/133] (944s left) gem_exec_fence (submit-chain)
[74.558965] Starting subtest: submit-chain
[74.577579] Subtest submit-chain: SUCCESS (0.019s)
[75.996316] [013/133] (942s left) syncobj_timeline (transfer-timeline-point)
[76.056208] Starting subtest: transfer-timeline-point
[76.058679] Subtest transfer-timeline-point: SUCCESS (0.000s)
[76.171357] [014/133] (942s left) kms_psr (psr-primary-mmap-cpu)
[76.266060] Starting subtest: psr-primary-mmap-cpu
[76.268413] Starting dynamic subtest: eDP-1
[77.967684] Dynamic subtest eDP-1: SUCCESS (1.702s)
[77.970064] Subtest psr-primary-mmap-cpu: SUCCESS (1.702s)
[78.865793] [015/133] (940s left) api_intel_bb (render)
[78.927056] Starting subtest: render
[78.928954] Starting dynamic subtest: render-none-512
[79.025676] Dynamic subtest render-none-512: SUCCESS (0.101s)
[79.036940] Starting dynamic subtest: render-none-1024
[79.406783] Dynamic subtest render-none-1024: SUCCESS (0.379s)
[79.417698] Starting dynamic subtest: render-x-512
[79.492361] Dynamic subtest render-x-512: SUCCESS (0.086s)
[79.502825] Starting dynamic subtest: render-x-1024
[79.827721] Dynamic subtest render-x-1024: SUCCESS (0.334s)
[79.837814] Starting dynamic subtest: render-y-512
[79.925325] Dynamic subtest render-y-512: SUCCESS (0.099s)
[79.935540] Starting dynamic subtest: render-y-1024
[80.511964] Dynamic subtest render-y-1024: SUCCESS (0.585s)
[80.514946] Subtest render: SUCCESS (1.586s)
[80.645866] [016/133] (938s left) gem_spin_batch (legacy-resubmit-new)
[80.709123] Starting subtest: legacy-resubmit-new
[80.711467] Starting dynamic subtest: default
[80.716643] Dynamic subtest default: SUCCESS (0.007s)
[80.724972] Starting dynamic subtest: render
[80.727314] Dynamic subtest render: SUCCESS (0.003s)
[80.735334] Starting dynamic subtest: bsd
[80.737699] Dynamic subtest bsd: SUCCESS (0.005s)
[80.744957] Starting dynamic subtest: bsd1
[80.747341] Dynamic subtest bsd1: SUCCESS (0.003s)
[80.754466] Starting dynamic subtest: bsd2
[80.756706] Dynamic subtest bsd2: SUCCESS (0.006s)
[80.763594] Starting dynamic subtest: blt
[80.765783] Dynamic subtest blt: SUCCESS (0.007s)
[80.772779] Starting dynamic subtest: vebox
[80.775080] Dynamic subtest vebox: SUCCESS (0.005s)
[80.777212] Subtest legacy-resubmit-new: SUCCESS (0.038s)
[81.975362] [017/133] (937s left) vgem_basic (dmabuf-fence-before)
[82.020850] Starting subtest: dmabuf-fence-before
[82.022801] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[82.073863] [018/133] (937s left) syncobj_timeline (etime-single-wait-all-submitted)
[82.128871] Starting subtest: etime-single-wait-all-submitted
[82.234209] Subtest etime-single-wait-all-submitted: SUCCESS (0.104s)
[82.308720] [019/133] (936s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[82.412574] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[82.417326] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[82.515568] [020/133] (936s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[82.613614] Starting subtest: x-tiled-64bpp-rotate-0
[84.257282] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.645s)
[84.424960] [021/133] (934s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[86.042656] Starting subtest: dpms-mode-unset-lpsp
[90.104458] Subtest dpms-mode-unset-lpsp: SUCCESS (4.063s)
[90.476888] [022/133] (928s left) kms_invalid_mode (bad-vsync-end)
[90.577392] Starting subtest: bad-vsync-end
[90.579854] Starting dynamic subtest: pipe-A-eDP-1
[90.871069] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.294s)
[90.879189] Starting dynamic subtest: pipe-B-eDP-1
[90.881100] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[90.887872] Starting dynamic subtest: pipe-C-eDP-1
[90.889815] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[90.897272] Starting dynamic subtest: pipe-D-eDP-1
[90.899549] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[90.901825] Subtest bad-vsync-end: SUCCESS (0.299s)
[91.689177] [023/133] (927s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[91.789883] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[91.794789] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[91.897935] [024/133] (927s left) kms_cursor_edge_walk (128x128-right-edge)
[91.990980] Starting subtest: 128x128-right-edge
[91.993334] Starting dynamic subtest: pipe-A-eDP-1
[95.440498] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.450s)
[95.448064] Starting dynamic subtest: pipe-D-eDP-1
[98.555489] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.113s)
[98.557862] Subtest 128x128-right-edge: SUCCESS (6.565s)
[99.368217] [025/133] (920s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[99.426038] Starting subtest: multi-wait-for-submit-unsubmitted
[99.530892] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[99.603250] [026/133] (919s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[99.690867] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[106.424118] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.738s)
[106.536160] [027/133] (912s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[106.671227] Starting subtest: short-flip-after-cursor-toggle
[107.597772] Subtest short-flip-after-cursor-toggle: SUCCESS (0.927s)
[108.450713] [028/133] (911s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[108.552383] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[108.560916] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.010s)
[108.638440] [029/133] (910s left) i915_query (query-regions-unallocated)
[108.696122] Starting subtest: query-regions-unallocated
[108.698097] Subtest query-regions-unallocated: SUCCESS (0.000s)
[108.804021] [030/133] (910s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[108.900193] Starting subtest: 4-tiled-16bpp-rotate-180
[110.268909] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.370s)
[110.389164] [031/133] (909s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[110.489290] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[110.491116] Starting dynamic subtest: pipe-A-eDP-1
[110.510235] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[110.517600] Starting dynamic subtest: pipe-B-eDP-1
[110.534641] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[110.541623] Starting dynamic subtest: pipe-C-eDP-1
[110.558731] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[110.566193] Starting dynamic subtest: pipe-D-eDP-1
[110.583882] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[110.587308] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[112.806775] [032/133] (906s left) kms_atomic_transition (plane-all-modeset-transition)
[112.907975] Starting subtest: plane-all-modeset-transition
[112.912388] Subtest plane-all-modeset-transition: SKIP (0.000s)
[112.995934] [033/133] (906s left) kms_flip (2x-nonexisting-fb-interruptible)
[113.090168] Starting subtest: 2x-nonexisting-fb-interruptible
[113.101634] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.011s)
[113.190286] [034/133] (906s left) gem_create (create-valid-nonaligned)
[113.235968] Starting subtest: create-valid-nonaligned
[113.238359] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[113.439039] [035/133] (906s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[113.542073] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[113.546408] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[113.642669] [036/133] (906s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[113.731426] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[113.733783] Starting dynamic subtest: pipe-A-eDP-1
[113.756655] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[113.766785] Starting dynamic subtest: pipe-B-eDP-1
[113.787818] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[113.795153] Starting dynamic subtest: pipe-C-eDP-1
[113.815936] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[113.823150] Starting dynamic subtest: pipe-D-eDP-1
[113.844617] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[113.848648] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[116.055010] [037/133] (903s left) kms_chamelium_color (ctm-0-25)
[116.150350] Subtest ctm-0-25: SKIP (0.000s)
[116.194253] [038/133] (903s left) kms_flip (basic-flip-vs-wf_vblank)
[116.299281] Starting subtest: basic-flip-vs-wf_vblank
[116.301205] Starting dynamic subtest: A-eDP1
[118.212614] Dynamic subtest A-eDP1: SUCCESS (1.916s)
[118.223129] Starting dynamic subtest: B-eDP1
[120.147812] Dynamic subtest B-eDP1: SUCCESS (1.933s)
[120.159024] Starting dynamic subtest: C-eDP1
[122.063298] Dynamic subtest C-eDP1: SUCCESS (1.914s)
[122.072844] Starting dynamic subtest: D-eDP1
[123.982563] Dynamic subtest D-eDP1: SUCCESS (1.917s)
[123.985022] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.691s)
[125.229060] [039/133] (894s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[125.338044] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[125.342485] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[125.409641] [040/133] (894s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[125.504419] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[125.506974] Starting dynamic subtest: pipe-A-eDP-1
[125.565802] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[125.576518] Starting dynamic subtest: pipe-B-eDP-1
[125.635208] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[125.647575] Starting dynamic subtest: pipe-C-eDP-1
[125.705432] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[125.714351] Starting dynamic subtest: pipe-D-eDP-1
[125.772028] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[125.776498] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[127.792229] [041/133] (892s left) gem_softpin (evict-active-interruptible)
[127.848799] Starting subtest: evict-active-interruptible
[127.852500] Subtest evict-active-interruptible: SUCCESS (0.006s)
[127.987970] [042/133] (892s left) i915_hangman (error-state-basic)
[128.062044] Starting subtest: error-state-basic
[128.217304] Subtest error-state-basic: SUCCESS (0.156s)
[128.290056] [043/133] (891s left) kms_psr (fbc-psr2-primary-page-flip)
[128.389031] Starting subtest: fbc-psr2-primary-page-flip
[128.391251] Starting dynamic subtest: eDP-1
[128.395204] Dynamic subtest eDP-1: SKIP (0.000s)
[128.399104] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[128.458716] [044/133] (891s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[128.567375] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[128.571880] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[128.660195] [045/133] (891s left) kms_lease (master-vs-lease)
[128.749191] Starting subtest: master-vs-lease
[128.751055] Subtest master-vs-lease: SUCCESS (0.001s)
[128.851763] [046/133] (891s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[128.956517] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf
[128.958412] Starting dynamic subtest: pipe-A-eDP-1
[129.097955] Dynamic subtest pipe-A-eDP-1: SKIP (0.144s)
[129.105956] Starting dynamic subtest: pipe-B-eDP-1
[130.256569] Dynamic subtest pipe-B-eDP-1: SKIP (1.155s)
[130.261469] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.302s)
[131.456566] [047/133] (888s left) kms_psr (fbc-pr-cursor-plane-move)
[131.558338] Starting subtest: fbc-pr-cursor-plane-move
[131.562748] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[131.628570] [048/133] (888s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[131.688173] Starting subtest: invalid-single-wait-unsubmitted
[131.690476] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[131.818886] [049/133] (888s left) gem_userptr_blits (input-checking)
[131.900886] Starting subtest: input-checking
[131.902837] Subtest input-checking: SUCCESS (0.000s)
[132.042552] [050/133] (888s left) kms_getfb (getfb2-into-addfb2)
[132.144316] Starting subtest: getfb2-into-addfb2
[132.146564] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[132.239119] [051/133] (888s left) kms_cursor_crc (cursor-offscreen-64x21)
[132.437270] Starting subtest: cursor-offscreen-64x21
[132.483488] Subtest cursor-offscreen-64x21: SKIP (0.041s)
[132.717822] [052/133] (887s left) gem_wait (write-busy)
[132.783371] Starting subtest: write-busy
[132.785220] Starting dynamic subtest: all-engines
[133.412862] Dynamic subtest all-engines: SUCCESS (0.628s)
[133.428298] Starting dynamic subtest: rcs0
[133.968498] Dynamic subtest rcs0: SUCCESS (0.554s)
[133.983168] Starting dynamic subtest: bcs0
[134.516874] Dynamic subtest bcs0: SUCCESS (0.548s)
[134.531102] Starting dynamic subtest: vcs0
[135.893394] Dynamic subtest vcs0: SUCCESS (1.374s)
[135.909053] Starting dynamic subtest: vcs1
[137.942022] Dynamic subtest vcs1: SUCCESS (2.047s)
[137.957277] Starting dynamic subtest: vecs0
[139.927198] Dynamic subtest vecs0: SUCCESS (1.983s)
[139.942329] Starting dynamic subtest: ccs0
[140.477375] Dynamic subtest ccs0: SUCCESS (0.550s)
[140.480360] Subtest write-busy: SUCCESS (7.693s)
[140.674160] [053/133] (879s left) sw_sync (sync_random_merge)
[140.709349] Starting subtest: sync_random_merge
[140.747724] Subtest sync_random_merge: SUCCESS (0.042s)
[140.809567] [054/133] (879s left) kms_async_flips (crc)
[140.909359] Starting subtest: crc
[141.290082] Starting dynamic subtest: pipe-A-eDP-1
[144.169060] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.878s)
[144.549413] Starting dynamic subtest: pipe-B-eDP-1
[147.517708] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.978s)
[147.911655] Starting dynamic subtest: pipe-C-eDP-1
[150.818782] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.915s)
[151.185530] Starting dynamic subtest: pipe-D-eDP-1
[154.022496] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.847s)
[154.024700] Subtest crc: SUCCESS (13.115s)
[155.215283] [055/133] (865s left) gem_pread (exhaustion)
[155.279235] Subtest exhaustion: SKIP (0.000s)
[155.337478] [056/133] (865s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[155.432230] Starting subtest: primary-yf-tiled-reflect-x-180
[155.491338] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[155.594273] [057/133] (865s left) kms_plane (pixel-format-yf-tiled-ccs-modifier)
[155.676253] Starting subtest: pixel-format-yf-tiled-ccs-modifier
[155.680497] Subtest pixel-format-yf-tiled-ccs-modifier: SKIP (0.000s)
[155.745635] [058/133] (865s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[155.886440] Starting subtest: basic-flip-before-cursor-atomic
[156.798777] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.911s)
[157.632998] [059/133] (863s left) syncobj_wait (invalid-reset-one-illegal-handle)
[157.685188] Starting subtest: invalid-reset-one-illegal-handle
[157.687614] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[157.770031] [060/133] (863s left) kms_psr (psr2-primary-mmap-gtt)
[157.859394] Starting subtest: psr2-primary-mmap-gtt
[157.861756] Starting dynamic subtest: eDP-1
[159.334807] Dynamic subtest eDP-1: SKIP (1.473s)
[159.339842] Subtest psr2-primary-mmap-gtt: SKIP (1.475s)
[159.487319] [061/133] (861s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[159.595319] Starting subtest: fbcpsr-slowdraw
[184.792589] Subtest fbcpsr-slowdraw: SUCCESS (25.199s)
[184.902428] [062/133] (836s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[184.998272] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[191.858764] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.864s)
[191.980338] [063/133] (828s left) kms_psr (pr-dpms)
[192.064965] Starting subtest: pr-dpms
[192.068963] Subtest pr-dpms: SKIP (0.000s)
[192.153086] [064/133] (828s left) kms_vblank (wait-forked-hang)
[192.251574] Starting subtest: wait-forked-hang
[192.253696] Starting dynamic subtest: pipe-A-eDP-1
[218.664845] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.411s)
[218.677064] Starting dynamic subtest: pipe-D-eDP-1
[233.743040] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.076s)
[233.744919] Subtest wait-forked-hang: SUCCESS (41.492s)
[234.546867] [065/133] (786s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[234.657423] Starting subtest: cursorB-vs-flipB-atomic-transitions
[234.661969] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[234.800384] [066/133] (786s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[234.893293] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[235.717619] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.826s)
[235.926736] [067/133] (785s left) gem_lmem_swapping (heavy-verify-multi)
[235.972084] Subtest heavy-verify-multi: SKIP (0.000s)
[236.011395] [068/133] (785s left) prime_vgem (wait)
[236.079847] Starting subtest: wait
[236.082357] Starting dynamic subtest: rcs0
[236.124842] Dynamic subtest rcs0: SUCCESS (0.045s)
[236.132582] Starting dynamic subtest: bcs0
[236.179253] Dynamic subtest bcs0: SUCCESS (0.053s)
[236.186777] Starting dynamic subtest: vcs0
[236.224723] Dynamic subtest vcs0: SUCCESS (0.045s)
[236.232945] Starting dynamic subtest: vcs1
[236.270356] Dynamic subtest vcs1: SUCCESS (0.045s)
[236.278460] Starting dynamic subtest: vecs0
[236.314315] Dynamic subtest vecs0: SUCCESS (0.043s)
[236.322058] Starting dynamic subtest: ccs0
[236.363986] Dynamic subtest ccs0: SUCCESS (0.049s)
[236.366268] Subtest wait: SUCCESS (0.285s)
[236.491047] [069/133] (784s left) vgem_basic (unload)
[236.528128] Starting subtest: unload
[265.551651] Subtest unload: SUCCESS (29.023s)
[265.652365] [070/133] (755s left) gem_exec_whisper (basic-fds-priority)
[265.712385] Starting subtest: basic-fds-priority
[289.459855] Subtest basic-fds-priority: SUCCESS (23.745s)
[289.660902] [071/133] (731s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[289.759293] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[289.763194] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[289.847951] [072/133] (731s left) gem_exec_schedule (noreorder)
[290.042826] Starting subtest: noreorder
[290.044662] Starting dynamic subtest: rcs0
[290.107866] Dynamic subtest rcs0: SUCCESS (0.066s)
[290.117504] Starting dynamic subtest: vcs0
[291.922587] Dynamic subtest vcs0: SUCCESS (1.811s)
[291.935238] Starting dynamic subtest: vcs1
[293.904337] Dynamic subtest vcs1: SUCCESS (1.980s)
[293.915368] Starting dynamic subtest: vecs0
[295.892398] Dynamic subtest vecs0: SUCCESS (1.985s)
[295.903401] Starting dynamic subtest: ccs0
[295.974064] Dynamic subtest ccs0: SUCCESS (0.080s)
[295.977177] Subtest noreorder: SUCCESS (5.935s)
[296.172888] [073/133] (725s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[296.266311] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[296.268474] Starting dynamic subtest: pipe-A
[296.333940] Dynamic subtest pipe-A: SUCCESS (0.067s)
[296.341775] Starting dynamic subtest: pipe-B
[297.460250] Dynamic subtest pipe-B: SUCCESS (1.125s)
[297.469064] Starting dynamic subtest: pipe-C
[298.575066] Dynamic subtest pipe-C: SUCCESS (1.113s)
[298.587783] Starting dynamic subtest: pipe-D
[299.724392] Dynamic subtest pipe-D: SUCCESS (1.148s)
[299.726569] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.459s)
[300.870338] [074/133] (720s left) gem_exec_params (invalid-bsd-ring)
[300.921322] Starting subtest: invalid-bsd-ring
[300.923730] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[301.012748] [075/133] (720s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[301.096172] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[301.100307] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[301.184918] [076/133] (720s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[301.282123] Starting subtest: fbcpsr-2p-rte
[301.286035] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[301.380398] [077/133] (720s left) gem_ctx_persistence (legacy-engines-persistence)
[301.437686] Starting subtest: legacy-engines-persistence
[301.440037] Starting dynamic subtest: render
[302.244815] Dynamic subtest render: SUCCESS (0.807s)
[302.258668] Starting dynamic subtest: bsd1
[302.999938] Dynamic subtest bsd1: SUCCESS (0.752s)
[303.011049] Starting dynamic subtest: bsd2
[303.743881] Dynamic subtest bsd2: SUCCESS (0.741s)
[303.754287] Starting dynamic subtest: blt
[304.510088] Dynamic subtest blt: SUCCESS (0.763s)
[304.524830] Starting dynamic subtest: vebox
[305.275825] Dynamic subtest vebox: SUCCESS (0.764s)
[305.278312] Subtest legacy-engines-persistence: SUCCESS (3.840s)
[305.418830] [078/133] (716s left) kms_plane_cursor (viewport)
[305.511865] Starting subtest: viewport
[305.515864] Starting dynamic subtest: pipe-A-eDP-1-size-64
[307.986261] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.476s)
[308.046475] Starting dynamic subtest: pipe-A-eDP-1-size-128
[310.510556] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.471s)
[310.569919] Starting dynamic subtest: pipe-A-eDP-1-size-256
[313.078810] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.515s)
[313.429814] Starting dynamic subtest: pipe-B-eDP-1-size-64
[316.648952] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.228s)
[316.708403] Starting dynamic subtest: pipe-B-eDP-1-size-128
[319.241317] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.538s)
[319.298447] Starting dynamic subtest: pipe-B-eDP-1-size-256
[321.806929] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.517s)
[322.153754] Starting dynamic subtest: pipe-C-eDP-1-size-64
[324.918339] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.774s)
[324.955600] Starting dynamic subtest: pipe-C-eDP-1-size-128
[327.096038] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.147s)
[327.133560] Starting dynamic subtest: pipe-C-eDP-1-size-256
[329.318423] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.192s)
[329.646837] Starting dynamic subtest: pipe-D-eDP-1-size-64
[332.587456] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.950s)
[332.626517] Starting dynamic subtest: pipe-D-eDP-1-size-128
[334.733269] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.114s)
[334.767830] Starting dynamic subtest: pipe-D-eDP-1-size-256
[336.898230] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.136s)
[337.221640] Subtest viewport: SUCCESS (31.709s)
[338.099855] [079/133] (683s left) gem_exec_fence (concurrent)
[338.282478] Starting subtest: concurrent
[338.287128] Subtest concurrent: SKIP (0.000s)
[339.952919] [080/133] (681s left) gem_mmap_gtt (medium-copy-odd)
[340.007209] Subtest medium-copy-odd: SKIP (0.000s)
[340.070305] [081/133] (681s left) gem_exec_parallel (userptr)
[340.130688] Starting subtest: userptr
[340.133161] Starting dynamic subtest: rcs0
[341.627442] Dynamic subtest rcs0: SUCCESS (1.495s)
[341.637856] Starting dynamic subtest: bcs0
[343.117981] Dynamic subtest bcs0: SUCCESS (1.489s)
[343.126110] Starting dynamic subtest: vcs0
[344.886394] Dynamic subtest vcs0: SUCCESS (1.764s)
[344.895606] Starting dynamic subtest: vcs1
[346.902474] Dynamic subtest vcs1: SUCCESS (2.012s)
[346.913088] Starting dynamic subtest: vecs0
[348.885234] Dynamic subtest vecs0: SUCCESS (1.980s)
[348.896747] Starting dynamic subtest: ccs0
[350.408716] Dynamic subtest ccs0: SUCCESS (1.522s)
[350.411521] Subtest userptr: SUCCESS (10.278s)
[350.594678] [082/133] (671s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[350.723806] Starting subtest: verify-pxp-execution-after-suspend-resume
[358.657429] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.936s)
[358.793100] [083/133] (662s left) kms_cursor_crc (cursor-random-512x512)
[359.065391] Starting subtest: cursor-random-512x512
[359.069614] Subtest cursor-random-512x512: SKIP (0.001s)
[359.225855] [084/133] (662s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[359.315639] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[359.319939] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[359.382385] [085/133] (662s left) gem_mmap_gtt (hang)
[359.437072] Subtest hang: SKIP (0.000s)
[359.497535] [086/133] (662s left) gem_exec_schedule (u-semaphore-noskip)
[359.577443] Starting subtest: u-semaphore-noskip
[359.970843] Subtest u-semaphore-noskip: SUCCESS (0.401s)
[360.303829] [087/133] (661s left) kms_big_fb (linear-16bpp-rotate-180)
[360.403602] Starting subtest: linear-16bpp-rotate-180
[361.703773] Subtest linear-16bpp-rotate-180: SUCCESS (1.302s)
[361.835858] [088/133] (660s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[361.933129] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[365.625248] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.694s)
[366.818818] [089/133] (655s left) gem_mmap_offset (isolation)
[366.876655] Starting subtest: isolation
[366.894297] Subtest isolation: SUCCESS (0.006s)
[366.985940] [090/133] (655s left) gem_fence_thrash (bo-write-verify-none)
[367.022118] Starting subtest: bo-write-verify-none
[367.040856] Subtest bo-write-verify-none: SKIP (0.019s)
[367.104550] [091/133] (655s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[367.204518] Starting subtest: psr-rgb101010-draw-mmap-cpu
[374.279325] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (7.078s)
[374.388296] [092/133] (647s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[374.492402] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[380.637045] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.147s)
[381.765455] [093/133] (640s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[381.879754] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[381.884267] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[381.971086] [094/133] (640s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[382.065938] Starting subtest: coverage-vs-premult-vs-constant
[382.342151] Starting dynamic subtest: pipe-A-eDP-1
[382.895566] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.554s)
[382.904659] Starting dynamic subtest: pipe-D-eDP-1
[384.167700] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.269s)
[384.169624] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.100s)
[385.017880] [095/133] (637s left) kms_chamelium_frames (hdmi-crc-single)
[385.404511] Subtest hdmi-crc-single: SKIP (0.000s)
[386.225780] [096/133] (636s left) kms_addfb_basic (no-handle)
[386.282133] Starting subtest: no-handle
[386.284436] Subtest no-handle: SUCCESS (0.000s)
[386.421033] [097/133] (635s left) gem_wait (invalid-buf)
[386.476184] Starting subtest: invalid-buf
[386.478651] Subtest invalid-buf: SUCCESS (0.000s)
[386.705278] [098/133] (635s left) i915_drm_fdinfo (basics)
[386.748132] Starting subtest: basics
[386.750394] Subtest basics: SUCCESS (0.000s)
[386.808848] [099/133] (635s left) perf_pmu (busy-double-start)
[386.844841] Starting subtest: busy-double-start
[386.846854] Starting dynamic subtest: rcs0
[390.485491] Dynamic subtest rcs0: FAIL (3.589s)
[390.495560] Starting dynamic subtest: bcs0
[394.024681] Dynamic subtest bcs0: SUCCESS (3.592s)
[394.036989] Starting dynamic subtest: vcs0
[398.444799] Dynamic subtest vcs0: SUCCESS (4.418s)
[398.456845] Starting dynamic subtest: vcs1
[402.386803] Dynamic subtest vcs1: SUCCESS (3.940s)
[402.396184] Starting dynamic subtest: vecs0
[406.446768] Dynamic subtest vecs0: SUCCESS (4.058s)
[406.459814] Starting dynamic subtest: ccs0
[410.086348] Dynamic subtest ccs0: FAIL (3.566s)
[410.088411] Subtest busy-double-start: FAIL (23.173s)
[410.130147] [100/133] (612s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[410.249223] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[410.253580] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[410.352875] [101/133] (612s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[410.414062] Starting subtest: invalid-multi-wait-unsubmitted
[410.416430] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[410.516658] [102/133] (612s left) gem_ctx_persistence (engines-mixed)
[410.581181] Starting subtest: engines-mixed
[410.583371] Starting dynamic subtest: rcs0
[410.684119] Dynamic subtest rcs0: SUCCESS (0.104s)
[410.697799] Starting dynamic subtest: bcs0
[410.748598] Dynamic subtest bcs0: SUCCESS (0.063s)
[410.759836] Starting dynamic subtest: vcs0
[410.816701] Dynamic subtest vcs0: SUCCESS (0.066s)
[410.826703] Starting dynamic subtest: vcs1
[410.885562] Dynamic subtest vcs1: SUCCESS (0.067s)
[410.897580] Starting dynamic subtest: vecs0
[410.934715] Dynamic subtest vecs0: SUCCESS (0.047s)
[410.944859] Starting dynamic subtest: ccs0
[410.993075] Dynamic subtest ccs0: SUCCESS (0.056s)
[410.995968] Subtest engines-mixed: SUCCESS (0.416s)
[411.073068] [103/133] (611s left) gen9_exec_parse (basic-rejected)
[411.137869] Subtest basic-rejected: SKIP (0.000s)
[411.221903] [104/133] (611s left) vgem_basic (busy-fence)
[411.263526] Starting subtest: busy-fence
[411.265411] Subtest busy-fence: SUCCESS (0.000s)
[411.321609] [105/133] (611s left) kms_draw_crc (draw-method-blt)
[411.411501] Starting subtest: draw-method-blt
[411.413132] Starting dynamic subtest: xrgb8888-untiled
[411.654316] Dynamic subtest xrgb8888-untiled: SUCCESS (0.244s)
[411.664606] Starting dynamic subtest: rgb565-untiled
[411.876498] Dynamic subtest rgb565-untiled: SUCCESS (0.221s)
[411.885723] Starting dynamic subtest: xrgb2101010-untiled
[412.087548] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[412.096487] Starting dynamic subtest: xbgr16161616f-untiled
[412.309774] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.220s)
[412.319055] Starting dynamic subtest: xrgb8888-xtiled
[412.431910] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[412.443543] Starting dynamic subtest: rgb565-xtiled
[412.554080] Dynamic subtest rgb565-xtiled: SUCCESS (0.120s)
[412.564927] Starting dynamic subtest: xrgb2101010-xtiled
[412.665322] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.109s)
[412.676773] Starting dynamic subtest: xbgr16161616f-xtiled
[412.776540] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.109s)
[412.785477] Starting dynamic subtest: xrgb8888-4tiled
[412.887370] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.109s)
[412.895978] Starting dynamic subtest: rgb565-4tiled
[412.998668] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[413.007650] Starting dynamic subtest: xrgb2101010-4tiled
[413.110032] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[413.118863] Starting dynamic subtest: xbgr16161616f-4tiled
[413.232283] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.122s)
[413.234210] Subtest draw-method-blt: SUCCESS (1.823s)
[413.348533] [106/133] (609s left) gem_exec_balancer (hog)
[413.408260] Starting subtest: hog
[413.412083] Subtest hog: SKIP (0.000s)
[413.701877] [107/133] (609s left) api_intel_allocator (alloc-simple)
[413.748227] Starting subtest: alloc-simple
[413.750722] Subtest alloc-simple: SUCCESS (0.003s)
[413.874476] [108/133] (608s left) kms_cursor_legacy (torture-bo)
[413.993649] Starting subtest: torture-bo
[413.995969] Starting dynamic subtest: pipe-A
[419.761048] Dynamic subtest pipe-A: SUCCESS (5.762s)
[419.773611] Starting dynamic subtest: pipe-B
[426.255125] Dynamic subtest pipe-B: SUCCESS (6.494s)
[426.268185] Starting dynamic subtest: pipe-C
[432.901144] Dynamic subtest pipe-C: SUCCESS (6.645s)
[432.916721] Starting dynamic subtest: pipe-D
[440.213939] Dynamic subtest pipe-D: SUCCESS (7.312s)
[440.225072] Starting dynamic subtest: all-pipes
[447.016682] Dynamic subtest all-pipes: SUCCESS (6.798s)
[447.019250] Subtest torture-bo: SUCCESS (33.019s)
[447.169155] [109/133] (575s left) gem_exec_params (cliprects-invalid)
[447.222091] Starting subtest: cliprects-invalid
[447.224294] Subtest cliprects-invalid: SUCCESS (0.000s)
[447.320774] [110/133] (575s left) prime_mmap (test_dup)
[447.365238] Starting subtest: test_dup
[447.367088] Starting dynamic subtest: test_dup-smem
[447.393656] Dynamic subtest test_dup-smem: SUCCESS (0.030s)
[447.395987] Subtest test_dup: SUCCESS (0.031s)
[447.466670] [111/133] (575s left) kms_flip (bo-too-big-interruptible)
[447.561395] Starting subtest: bo-too-big-interruptible
[447.563267] Starting dynamic subtest: A-eDP1
[449.171162] Dynamic subtest A-eDP1: SUCCESS (1.607s)
[449.187058] Starting dynamic subtest: B-eDP1
[450.411893] Dynamic subtest B-eDP1: SUCCESS (1.238s)
[450.424767] Starting dynamic subtest: C-eDP1
[451.664890] Dynamic subtest C-eDP1: SUCCESS (1.247s)
[451.675540] Starting dynamic subtest: D-eDP1
[452.989372] Dynamic subtest D-eDP1: SUCCESS (1.327s)
[452.994779] Subtest bo-too-big-interruptible: SUCCESS (5.431s)
[454.191993] [112/133] (568s left) kms_busy (extended-pageflip-hang-oldfb)
[454.276357] Starting subtest: extended-pageflip-hang-oldfb
[454.278224] Starting dynamic subtest: pipe-A
[454.680744] Dynamic subtest pipe-A: SUCCESS (0.405s)
[454.695855] Starting dynamic subtest: pipe-D
[455.822203] Dynamic subtest pipe-D: SUCCESS (1.139s)
[455.824501] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.549s)
[456.654987] [113/133] (566s left) i915_pm_rps (fence-order)
[456.721853] Starting subtest: fence-order
[459.606553] Subtest fence-order: SUCCESS (2.882s)
[459.724592] [114/133] (563s left) kms_plane (plane-position-hole-dpms)
[459.827298] Starting subtest: plane-position-hole-dpms
[460.365134] Starting dynamic subtest: pipe-A-plane-1
[462.772620] Dynamic subtest pipe-A-plane-1: SUCCESS (2.406s)
[462.782466] Starting dynamic subtest: pipe-A-plane-2
[465.260622] Dynamic subtest pipe-A-plane-2: SUCCESS (2.486s)
[465.273539] Starting dynamic subtest: pipe-A-plane-3
[467.660244] Dynamic subtest pipe-A-plane-3: SUCCESS (2.401s)
[467.669438] Starting dynamic subtest: pipe-A-plane-4
[470.039478] Dynamic subtest pipe-A-plane-4: SUCCESS (2.377s)
[470.050145] Starting dynamic subtest: pipe-A-plane-5
[470.065820] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[471.256477] Starting dynamic subtest: pipe-B-plane-1
[473.678122] Dynamic subtest pipe-B-plane-1: SUCCESS (2.434s)
[473.688589] Starting dynamic subtest: pipe-B-plane-2
[476.105412] Dynamic subtest pipe-B-plane-2: SUCCESS (2.427s)
[476.117343] Starting dynamic subtest: pipe-B-plane-3
[478.504759] Dynamic subtest pipe-B-plane-3: SUCCESS (2.396s)
[478.514987] Starting dynamic subtest: pipe-B-plane-4
[480.891030] Dynamic subtest pipe-B-plane-4: SUCCESS (2.388s)
[480.900694] Starting dynamic subtest: pipe-B-plane-5
[480.916432] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[480.918763] Subtest plane-position-hole-dpms: SUCCESS (21.089s)
[481.699385] [115/133] (541s left) kms_pm_rpm (legacy-planes)
[484.036790] Starting subtest: legacy-planes
[484.039134] Starting dynamic subtest: plane-65
[492.361156] Dynamic subtest plane-65: SUCCESS (8.327s)
[492.372480] Starting dynamic subtest: plane-95
[500.645330] Dynamic subtest plane-95: SUCCESS (8.281s)
[500.657828] Starting dynamic subtest: plane-125
[508.914374] Dynamic subtest plane-125: SUCCESS (8.266s)
[508.926726] Starting dynamic subtest: plane-135
[517.181934] Dynamic subtest plane-135: SUCCESS (8.264s)
[517.185084] Subtest legacy-planes: SUCCESS (33.149s)
[517.549586] [116/133] (505s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[517.644645] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[521.647686] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.006s)
[522.822107] [117/133] (500s left) kms_color (deep-color)
[522.915724] Starting subtest: deep-color
[523.999729] Starting dynamic subtest: pipe-A-eDP-1-gamma
[524.703619] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.703s)
[524.713794] Starting dynamic subtest: pipe-A-eDP-1-degamma
[526.118490] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.413s)
[526.130291] Starting dynamic subtest: pipe-A-eDP-1-ctm
[527.532864] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.413s)
[528.309656] Starting dynamic subtest: pipe-B-eDP-1-gamma
[529.008959] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.705s)
[529.021048] Starting dynamic subtest: pipe-B-eDP-1-degamma
[530.490185] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.484s)
[530.500540] Starting dynamic subtest: pipe-B-eDP-1-ctm
[531.926772] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.435s)
[532.700774] Starting dynamic subtest: pipe-C-eDP-1-gamma
[533.375409] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.681s)
[533.385293] Starting dynamic subtest: pipe-C-eDP-1-degamma
[534.778812] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.405s)
[534.789535] Starting dynamic subtest: pipe-C-eDP-1-ctm
[536.164426] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.382s)
[536.979847] Starting dynamic subtest: pipe-D-eDP-1-gamma
[537.683809] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.713s)
[537.693999] Starting dynamic subtest: pipe-D-eDP-1-degamma
[539.121275] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.437s)
[539.131059] Starting dynamic subtest: pipe-D-eDP-1-ctm
[540.587389] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.465s)
[540.589857] Subtest deep-color: SUCCESS (17.674s)
[541.434463] [118/133] (481s left) syncobj_eventfd (timeline-wait)
[541.501638] Starting subtest: timeline-wait
[541.510429] Subtest timeline-wait: SUCCESS (0.011s)
[541.589850] [119/133] (481s left) kms_psr (fbc-psr2-sprite-render)
[541.686272] Starting subtest: fbc-psr2-sprite-render
[541.688367] Starting dynamic subtest: eDP-1
[541.692557] Dynamic subtest eDP-1: SKIP (0.000s)
[541.696746] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[541.759127] [120/133] (481s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[541.858352] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[541.862389] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[541.960975] [121/133] (481s left) perf_pmu (busy-idle-no-semaphores)
[542.002608] Starting subtest: busy-idle-no-semaphores
[542.005046] Starting dynamic subtest: rcs0
[542.652493] Dynamic subtest rcs0: SUCCESS (0.650s)
[542.663915] Starting dynamic subtest: bcs0
[543.304255] Dynamic subtest bcs0: SUCCESS (0.649s)
[543.318572] Starting dynamic subtest: vcs0
[543.957373] Dynamic subtest vcs0: SUCCESS (0.650s)
[543.969677] Starting dynamic subtest: vcs1
[545.533883] Dynamic subtest vcs1: SUCCESS (1.574s)
[545.546096] Starting dynamic subtest: vecs0
[547.518062] Dynamic subtest vecs0: SUCCESS (1.981s)
[547.529354] Starting dynamic subtest: ccs0
[549.502447] Dynamic subtest ccs0: SUCCESS (1.982s)
[549.505486] Subtest busy-idle-no-semaphores: SUCCESS (7.502s)
[549.620978] [122/133] (473s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[549.719646] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[556.290185] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.574s)
[556.429444] [123/133] (466s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[556.535935] Starting subtest: 4-tiled-16bpp-rotate-270
[556.564151] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.027s)
[556.688800] [124/133] (466s left) kms_flip (2x-absolute-wf_vblank)
[556.805624] Starting subtest: 2x-absolute-wf_vblank
[556.813372] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[556.904473] [125/133] (466s left) core_setmaster (master-drop-set-root)
[556.972691] Starting subtest: master-drop-set-root
[556.985578] Subtest master-drop-set-root: SUCCESS (0.013s)
[557.036432] [126/133] (466s left) gem_ctx_param (invalid-param-set)
[557.076808] Starting subtest: invalid-param-set
[557.079104] Subtest invalid-param-set: SUCCESS (0.000s)
[557.152832] [127/133] (466s left) kms_sequence (get-idle)
[557.235385] Starting subtest: get-idle
[557.236981] Starting dynamic subtest: pipe-A-eDP-1
[559.055420] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.819s)
[559.065111] Starting dynamic subtest: pipe-B-eDP-1
[561.538297] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.483s)
[561.555095] Starting dynamic subtest: pipe-C-eDP-1
[563.961282] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.422s)
[563.972054] Starting dynamic subtest: pipe-D-eDP-1
[566.388367] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.423s)
[566.390578] Subtest get-idle: SUCCESS (9.152s)
[567.224752] [128/133] (456s left) gem_mmap_wc (invalid-flags)
[567.297815] Subtest invalid-flags: SKIP (0.000s)
[567.368431] [129/133] (456s left) kms_chamelium_frames (vga-frame-dump)
[567.756558] Subtest vga-frame-dump: SKIP (0.000s)
[568.533548] [130/133] (455s left) kms_flip (2x-flip-vs-dpms-on-nop)
[568.624413] Starting subtest: 2x-flip-vs-dpms-on-nop
[568.632810] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.010s)
[568.770275] [131/133] (454s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[568.853508] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[568.857742] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[568.962138] [132/133] (454s left) gem_userptr_blits (unsync-unmap-cycles)
[569.020715] Subtest unsync-unmap-cycles: SKIP (0.000s)
[569.149359] [133/133] (454s left) fbdev (eof)
[569.192174] Starting subtest: eof
[569.194293] Subtest eof: SUCCESS (0.000s)
[569.270016] Closing watchdogs
results: parsing output: 0/ for test: gem_render_copy
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_content_protection
results: parsing output: 3/ for test: gem_close_race
results: parsing output: 4/ for test: tools_test
results: parsing output: 5/ for test: gem_mmap_gtt
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: prime_mmap
results: parsing output: 9/ for test: gem_ctx_shared
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: gem_exec_fence
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: api_intel_bb
results: parsing output: 15/ for test: gem_spin_batch
results: parsing output: 16/ for test: vgem_basic
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_pm_rpm
results: parsing output: 21/ for test: kms_invalid_mode
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_cursor_edge_walk
results: parsing output: 24/ for test: syncobj_wait
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_cursor_legacy
results: parsing output: 27/ for test: kms_flip
results: parsing output: 28/ for test: i915_query
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: kms_atomic_transition
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: gem_create
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: kms_chamelium_color
results: parsing output: 37/ for test: kms_flip
results: parsing output: 38/ for test: kms_flip_scaled_crc
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: gem_softpin
results: parsing output: 41/ for test: i915_hangman
results: parsing output: 42/ for test: kms_psr
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: kms_lease
results: parsing output: 45/ for test: kms_psr2_sf
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: gem_userptr_blits
results: parsing output: 49/ for test: kms_getfb
results: parsing output: 50/ for test: kms_cursor_crc
results: parsing output: 51/ for test: gem_wait
results: parsing output: 52/ for test: sw_sync
results: parsing output: 53/ for test: kms_async_flips
results: parsing output: 54/ for test: gem_pread
results: parsing output: 55/ for test: kms_rotation_crc
results: parsing output: 56/ for test: kms_plane
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: syncobj_wait
results: parsing output: 59/ for test: kms_psr
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: kms_vblank
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: gem_lmem_swapping
results: parsing output: 67/ for test: prime_vgem
results: parsing output: 68/ for test: vgem_basic
results: parsing output: 69/ for test: gem_exec_whisper
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: kms_plane_scaling
results: parsing output: 73/ for test: gem_exec_params
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_ctx_persistence
results: parsing output: 77/ for test: kms_plane_cursor
results: parsing output: 78/ for test: gem_exec_fence
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: gem_exec_parallel
results: parsing output: 81/ for test: gem_pxp
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: kms_psr2_sf
results: parsing output: 84/ for test: gem_mmap_gtt
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_mmap_offset
results: parsing output: 89/ for test: gem_fence_thrash
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_plane_alpha_blend
results: parsing output: 94/ for test: kms_chamelium_frames
results: parsing output: 95/ for test: kms_addfb_basic
results: parsing output: 96/ for test: gem_wait
results: parsing output: 97/ for test: i915_drm_fdinfo
results: parsing output: 98/ for test: perf_pmu
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: gem_ctx_persistence
results: parsing output: 102/ for test: gen9_exec_parse
results: parsing output: 103/ for test: vgem_basic
results: parsing output: 104/ for test: kms_draw_crc
results: parsing output: 105/ for test: gem_exec_balancer
results: parsing output: 106/ for test: api_intel_allocator
results: parsing output: 107/ for test: kms_cursor_legacy
results: parsing output: 108/ for test: gem_exec_params
results: parsing output: 109/ for test: prime_mmap
results: parsing output: 110/ for test: kms_flip
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: i915_pm_rps
results: parsing output: 113/ for test: kms_plane
results: parsing output: 114/ for test: kms_pm_rpm
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_color
results: parsing output: 117/ for test: syncobj_eventfd
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: perf_pmu
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: core_setmaster
results: parsing output: 125/ for test: gem_ctx_param
results: parsing output: 126/ for test: kms_sequence
results: parsing output: 127/ for test: gem_mmap_wc
results: parsing output: 128/ for test: kms_chamelium_frames
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_userptr_blits
results: parsing output: 132/ for test: fbdev
Done.