[56.934565] Initializing watchdogs
[56.934610]   /dev/watchdog0
[56.949073] [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]
[56.953837] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.956358] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.959803] [001/133] (960s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[57.043810] Starting subtest: y-tiled-to-vebox-yf-tiled
[57.047901] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[57.220318] [002/133] (960s left) kms_flip (nonexisting-fb-interruptible)
[57.323637] Starting subtest: nonexisting-fb-interruptible
[57.325892] Starting dynamic subtest: A-eDP1
[58.517103] Dynamic subtest A-eDP1: SUCCESS (1.192s)
[58.527897] Starting dynamic subtest: B-eDP1
[59.634459] Dynamic subtest B-eDP1: SUCCESS (1.116s)
[59.644230] Starting dynamic subtest: C-eDP1
[60.768780] Dynamic subtest C-eDP1: SUCCESS (1.134s)
[60.778736] Starting dynamic subtest: D-eDP1
[61.903793] Dynamic subtest D-eDP1: SUCCESS (1.135s)
[61.906082] Subtest nonexisting-fb-interruptible: SUCCESS (4.583s)
[63.081544] [003/133] (954s left) kms_content_protection (uevent-hdcp14)
[63.219166] Starting subtest: uevent-hdcp14
[63.223693] Subtest uevent-hdcp14: SKIP (0.000s)
[63.325703] [004/133] (954s left) gem_close_race (multigpu-basic-threads)
[63.390818] Starting subtest: multigpu-basic-threads
[63.395727] Subtest multigpu-basic-threads: SKIP (0.000s)
[63.606874] [005/133] (953s left) tools_test (tools_test)
[63.655465] Starting subtest: tools_test
[63.718833] Subtest tools_test: SUCCESS (0.079s)
[63.779823] [006/133] (953s left) gem_mmap_gtt (bad-object)
[63.839441] Subtest bad-object: SKIP (0.000s)
[63.902895] [007/133] (953s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[63.966811] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[63.969061] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s)
[64.074199] [008/133] (953s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[64.172264] Starting subtest: fbc-indfb-scaledprimary
[67.676665] Subtest fbc-indfb-scaledprimary: SUCCESS (3.508s)
[68.801233] [009/133] (948s left) prime_mmap (test_refcounting)
[68.855684] Starting subtest: test_refcounting
[68.857961] Starting dynamic subtest: test_refcounting-smem
[68.888418] Dynamic subtest test_refcounting-smem: SUCCESS (0.033s)
[68.890868] Subtest test_refcounting: SUCCESS (0.033s)
[68.974067] [010/133] (948s left) gem_ctx_shared (q-in-order)
[69.029498] Starting subtest: Q-in-order
[69.031816] Starting dynamic subtest: rcs0
[69.147674] Dynamic subtest rcs0: SUCCESS (0.117s)
[69.164040] Starting dynamic subtest: vcs0
[69.224236] Dynamic subtest vcs0: SUCCESS (0.077s)
[69.239402] Starting dynamic subtest: vcs1
[69.298447] Dynamic subtest vcs1: SUCCESS (0.074s)
[69.313109] Starting dynamic subtest: vecs0
[69.373780] Dynamic subtest vecs0: SUCCESS (0.074s)
[69.388298] Starting dynamic subtest: ccs0
[69.455322] Dynamic subtest ccs0: SUCCESS (0.081s)
[69.458342] Subtest Q-in-order: SUCCESS (0.425s)
[71.036651] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[71.039941] [011/133] (946s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[71.127194] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[71.129493] Starting dynamic subtest: pipe-A-eDP-1
[71.152993] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[71.163156] Starting dynamic subtest: pipe-B-eDP-1
[71.183755] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[71.191459] Starting dynamic subtest: pipe-C-eDP-1
[71.212503] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[71.220633] Starting dynamic subtest: pipe-D-eDP-1
[71.242602] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[71.246959] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[73.351582] [012/133] (944s left) gem_exec_fence (submit-chain)
[73.528338] Starting subtest: submit-chain
[73.545200] Subtest submit-chain: SUCCESS (0.017s)
[74.988918] [013/133] (942s left) syncobj_timeline (transfer-timeline-point)
[75.044835] Starting subtest: transfer-timeline-point
[75.047074] Subtest transfer-timeline-point: SUCCESS (0.000s)
[75.157048] [014/133] (942s left) kms_psr (psr-primary-mmap-cpu)
[75.250890] Starting subtest: psr-primary-mmap-cpu
[75.253761] Starting dynamic subtest: eDP-1
[76.956797] Dynamic subtest eDP-1: SUCCESS (1.707s)
[76.959103] Subtest psr-primary-mmap-cpu: SUCCESS (1.707s)
[77.822810] [015/133] (940s left) api_intel_bb (render)
[77.897098] Starting subtest: render
[77.899506] Starting dynamic subtest: render-none-512
[78.029213] Dynamic subtest render-none-512: SUCCESS (0.130s)
[78.038844] Starting dynamic subtest: render-none-1024
[78.406061] Dynamic subtest render-none-1024: SUCCESS (0.377s)
[78.416185] Starting dynamic subtest: render-x-512
[78.490553] Dynamic subtest render-x-512: SUCCESS (0.085s)
[78.501073] Starting dynamic subtest: render-x-1024
[78.836502] Dynamic subtest render-x-1024: SUCCESS (0.345s)
[78.848232] Starting dynamic subtest: render-y-512
[78.954180] Dynamic subtest render-y-512: SUCCESS (0.117s)
[78.964620] Starting dynamic subtest: render-y-1024
[79.301374] Dynamic subtest render-y-1024: SUCCESS (0.347s)
[79.304486] Subtest render: SUCCESS (1.403s)
[79.416705] [016/133] (938s left) gem_spin_batch (legacy-resubmit-new)
[79.483248] Starting subtest: legacy-resubmit-new
[79.485559] Starting dynamic subtest: default
[79.490057] Dynamic subtest default: SUCCESS (0.006s)
[79.499833] Starting dynamic subtest: render
[79.502083] Dynamic subtest render: SUCCESS (0.003s)
[79.509914] Starting dynamic subtest: bsd
[79.512162] Dynamic subtest bsd: SUCCESS (0.005s)
[79.519771] Starting dynamic subtest: bsd1
[79.521685] Dynamic subtest bsd1: SUCCESS (0.006s)
[79.528899] Starting dynamic subtest: bsd2
[79.530809] Dynamic subtest bsd2: SUCCESS (0.003s)
[79.538044] Starting dynamic subtest: blt
[79.540024] Dynamic subtest blt: SUCCESS (0.004s)
[79.547182] Starting dynamic subtest: vebox
[79.549047] Dynamic subtest vebox: SUCCESS (0.004s)
[79.551017] Subtest legacy-resubmit-new: SUCCESS (0.034s)
[80.977135] [017/133] (937s left) vgem_basic (dmabuf-fence-before)
[81.034103] Starting subtest: dmabuf-fence-before
[81.036421] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[81.092696] [018/133] (937s left) syncobj_timeline (etime-single-wait-all-submitted)
[81.155881] Starting subtest: etime-single-wait-all-submitted
[81.257349] Subtest etime-single-wait-all-submitted: SUCCESS (0.102s)
[81.333932] [019/133] (936s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[81.430494] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[81.434690] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[81.549034] [020/133] (936s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[81.651376] Starting subtest: x-tiled-64bpp-rotate-0
[83.307907] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.658s)
[83.464164] [021/133] (934s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[84.996922] Starting subtest: dpms-mode-unset-lpsp
[89.027532] Subtest dpms-mode-unset-lpsp: SUCCESS (4.032s)
[89.407701] [022/133] (928s left) kms_invalid_mode (bad-vsync-end)
[89.514050] Starting subtest: bad-vsync-end
[89.516315] Starting dynamic subtest: pipe-A-eDP-1
[89.798722] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[89.806885] Starting dynamic subtest: pipe-B-eDP-1
[89.809383] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[89.816938] Starting dynamic subtest: pipe-C-eDP-1
[89.819247] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[89.828082] Starting dynamic subtest: pipe-D-eDP-1
[89.830502] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[89.832853] Subtest bad-vsync-end: SUCCESS (0.289s)
[90.643212] [023/133] (927s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[90.751594] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[90.755787] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[90.865174] [024/133] (927s left) kms_cursor_edge_walk (128x128-right-edge)
[90.960351] Starting subtest: 128x128-right-edge
[90.962580] Starting dynamic subtest: pipe-A-eDP-1
[94.405825] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.446s)
[94.416460] Starting dynamic subtest: pipe-D-eDP-1
[97.539862] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.131s)
[97.542136] Subtest 128x128-right-edge: SUCCESS (6.579s)
[98.336767] [025/133] (920s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[98.397971] Starting subtest: multi-wait-for-submit-unsubmitted
[98.497736] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[98.602260] [026/133] (919s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[98.694515] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[105.394093] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.703s)
[105.508122] [027/133] (913s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[105.635380] Starting subtest: short-flip-after-cursor-toggle
[106.564807] Subtest short-flip-after-cursor-toggle: SUCCESS (0.931s)
[107.417844] [028/133] (911s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[107.519180] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[107.528264] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.011s)
[107.607051] [029/133] (910s left) i915_query (query-regions-unallocated)
[107.668161] Starting subtest: query-regions-unallocated
[107.670178] Subtest query-regions-unallocated: SUCCESS (0.000s)
[107.774768] [030/133] (910s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[107.876705] Starting subtest: 4-tiled-16bpp-rotate-180
[109.177246] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.301s)
[109.297911] [031/133] (909s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[109.389191] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[109.391079] Starting dynamic subtest: pipe-A-eDP-1
[109.410019] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[109.418078] Starting dynamic subtest: pipe-B-eDP-1
[109.434684] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[109.441426] Starting dynamic subtest: pipe-C-eDP-1
[109.459359] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[109.466279] Starting dynamic subtest: pipe-D-eDP-1
[109.484511] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[109.488099] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[111.682227] [032/133] (907s left) kms_atomic_transition (plane-all-modeset-transition)
[111.783411] Starting subtest: plane-all-modeset-transition
[111.787596] Subtest plane-all-modeset-transition: SKIP (0.000s)
[111.867505] [033/133] (906s left) kms_flip (2x-nonexisting-fb-interruptible)
[111.973297] Starting subtest: 2x-nonexisting-fb-interruptible
[111.985268] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.011s)
[112.076066] [034/133] (906s left) gem_create (create-valid-nonaligned)
[112.136805] Starting subtest: create-valid-nonaligned
[112.138998] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[112.321997] [035/133] (906s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[112.408060] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[112.412049] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[112.510043] [036/133] (906s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[112.598779] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[112.601123] Starting dynamic subtest: pipe-A-eDP-1
[112.624131] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[112.633785] Starting dynamic subtest: pipe-B-eDP-1
[112.655449] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[112.663156] Starting dynamic subtest: pipe-C-eDP-1
[112.684803] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[112.692651] Starting dynamic subtest: pipe-D-eDP-1
[112.714976] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[112.719346] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[114.845051] [037/133] (904s left) kms_chamelium_color (ctm-0-25)
[114.937364] Subtest ctm-0-25: SKIP (0.000s)
[114.985607] [038/133] (903s left) kms_flip (basic-flip-vs-wf_vblank)
[115.077396] Starting subtest: basic-flip-vs-wf_vblank
[115.082219] Starting dynamic subtest: A-eDP1
[116.990132] Dynamic subtest A-eDP1: SUCCESS (1.910s)
[116.999414] Starting dynamic subtest: B-eDP1
[118.922794] Dynamic subtest B-eDP1: SUCCESS (1.932s)
[118.933211] Starting dynamic subtest: C-eDP1
[120.841780] Dynamic subtest C-eDP1: SUCCESS (1.918s)
[120.854501] Starting dynamic subtest: D-eDP1
[122.772665] Dynamic subtest D-eDP1: SUCCESS (1.929s)
[122.777412] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.701s)
[124.000701] [039/133] (895s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[124.098462] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[124.102782] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[124.169709] [040/133] (894s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[124.260896] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[124.263359] Starting dynamic subtest: pipe-A-eDP-1
[124.319938] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[124.329449] Starting dynamic subtest: pipe-B-eDP-1
[124.385636] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[124.395087] Starting dynamic subtest: pipe-C-eDP-1
[124.454654] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[124.464851] Starting dynamic subtest: pipe-D-eDP-1
[124.521834] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[124.526265] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.008s)
[126.494416] [041/133] (892s left) gem_softpin (evict-active-interruptible)
[126.564930] Starting subtest: evict-active-interruptible
[126.570746] Subtest evict-active-interruptible: SUCCESS (0.006s)
[126.690323] [042/133] (892s left) i915_hangman (error-state-basic)
[126.757513] Starting subtest: error-state-basic
[126.924356] Subtest error-state-basic: SUCCESS (0.166s)
[126.997891] [043/133] (892s left) kms_psr (fbc-psr2-primary-page-flip)
[127.102213] Starting subtest: fbc-psr2-primary-page-flip
[127.104077] Starting dynamic subtest: eDP-1
[127.108267] Dynamic subtest eDP-1: SKIP (0.000s)
[127.112319] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[127.180288] [044/133] (892s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[127.281867] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[127.285890] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[127.372856] [045/133] (891s left) kms_lease (master-vs-lease)
[127.468144] Starting subtest: master-vs-lease
[127.470179] Subtest master-vs-lease: SUCCESS (0.002s)
[127.597854] [046/133] (891s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[127.701755] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf
[127.703651] Starting dynamic subtest: pipe-A-eDP-1
[127.788509] Dynamic subtest pipe-A-eDP-1: SKIP (0.090s)
[127.797933] Starting dynamic subtest: pipe-B-eDP-1
[128.949687] Dynamic subtest pipe-B-eDP-1: SKIP (1.159s)
[128.954302] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.251s)
[130.150632] [047/133] (889s left) kms_psr (fbc-pr-cursor-plane-move)
[130.259079] Starting subtest: fbc-pr-cursor-plane-move
[130.263559] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[130.331137] [048/133] (889s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[130.385829] Starting subtest: invalid-single-wait-unsubmitted
[130.388039] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[130.478286] [049/133] (888s left) gem_userptr_blits (input-checking)
[130.546658] Starting subtest: input-checking
[130.548587] Subtest input-checking: SUCCESS (0.000s)
[130.678238] [050/133] (888s left) kms_getfb (getfb2-into-addfb2)
[130.784159] Starting subtest: getfb2-into-addfb2
[130.786337] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[130.883735] [051/133] (888s left) kms_cursor_crc (cursor-offscreen-64x21)
[131.085912] Starting subtest: cursor-offscreen-64x21
[131.140181] Subtest cursor-offscreen-64x21: SKIP (0.052s)
[131.402214] [052/133] (888s left) gem_wait (write-busy)
[131.470750] Starting subtest: write-busy
[131.472820] Starting dynamic subtest: all-engines
[132.075660] Dynamic subtest all-engines: SUCCESS (0.604s)
[132.089730] Starting dynamic subtest: rcs0
[132.630904] Dynamic subtest rcs0: SUCCESS (0.554s)
[132.645870] Starting dynamic subtest: bcs0
[133.179614] Dynamic subtest bcs0: SUCCESS (0.548s)
[133.193827] Starting dynamic subtest: vcs0
[134.934405] Dynamic subtest vcs0: SUCCESS (1.752s)
[134.949336] Starting dynamic subtest: vcs1
[136.919079] Dynamic subtest vcs1: SUCCESS (1.983s)
[136.934166] Starting dynamic subtest: vecs0
[138.902346] Dynamic subtest vecs0: SUCCESS (1.981s)
[138.917479] Starting dynamic subtest: ccs0
[139.451048] Dynamic subtest ccs0: SUCCESS (0.549s)
[139.453389] Subtest write-busy: SUCCESS (7.980s)
[139.647980] [053/133] (879s left) sw_sync (sync_random_merge)
[139.692760] Starting subtest: sync_random_merge
[139.734645] Subtest sync_random_merge: SUCCESS (0.045s)
[139.813134] [054/133] (879s left) kms_async_flips (crc)
[139.913892] Starting subtest: crc
[140.287280] Starting dynamic subtest: pipe-A-eDP-1
[143.159460] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.873s)
[143.536597] Starting dynamic subtest: pipe-B-eDP-1
[146.492395] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.962s)
[146.877924] Starting dynamic subtest: pipe-C-eDP-1
[149.815653] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.947s)
[150.204171] Starting dynamic subtest: pipe-D-eDP-1
[153.046363] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.853s)
[153.048934] Subtest crc: SUCCESS (13.134s)
[154.210938] [055/133] (865s left) gem_pread (exhaustion)
[154.265968] Subtest exhaustion: SKIP (0.000s)
[154.320164] [056/133] (865s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[154.403916] Starting subtest: primary-yf-tiled-reflect-x-180
[154.463789] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[154.567626] [057/133] (865s left) kms_plane (pixel-format-yf-tiled-ccs-modifier)
[154.661787] Starting subtest: pixel-format-yf-tiled-ccs-modifier
[154.665655] Subtest pixel-format-yf-tiled-ccs-modifier: SKIP (0.000s)
[154.746502] [058/133] (865s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[154.871380] Starting subtest: basic-flip-before-cursor-atomic
[155.790689] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.916s)
[156.641951] [059/133] (863s left) syncobj_wait (invalid-reset-one-illegal-handle)
[156.701975] Starting subtest: invalid-reset-one-illegal-handle
[156.703859] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[156.801244] [060/133] (863s left) kms_psr (psr2-primary-mmap-gtt)
[156.899949] Starting subtest: psr2-primary-mmap-gtt
[156.902167] Starting dynamic subtest: eDP-1
[158.341922] Dynamic subtest eDP-1: SKIP (1.439s)
[158.346717] Subtest psr2-primary-mmap-gtt: SKIP (1.441s)
[158.462811] [061/133] (861s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[158.552280] Starting subtest: fbcpsr-slowdraw
[183.854995] Subtest fbcpsr-slowdraw: SUCCESS (25.305s)
[183.980853] [062/133] (835s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[184.080299] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[191.111305] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (7.034s)
[191.234034] [063/133] (828s left) kms_psr (pr-dpms)
[191.328342] Starting subtest: pr-dpms
[191.332412] Subtest pr-dpms: SKIP (0.000s)
[191.415119] [064/133] (828s left) kms_vblank (wait-forked-hang)
[191.514118] Starting subtest: wait-forked-hang
[191.516059] Starting dynamic subtest: pipe-A-eDP-1
[217.685551] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.171s)
[217.698633] Starting dynamic subtest: pipe-D-eDP-1
[242.646792] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.959s)
[242.649284] Subtest wait-forked-hang: SUCCESS (51.134s)
[243.447037] [065/133] (776s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[243.580236] Starting subtest: cursorB-vs-flipB-atomic-transitions
[243.584920] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[243.732713] [066/133] (776s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[243.832445] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[244.679662] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.848s)
[244.846560] [067/133] (775s left) gem_lmem_swapping (heavy-verify-multi)
[244.892231] Subtest heavy-verify-multi: SKIP (0.000s)
[244.938209] [068/133] (775s left) prime_vgem (wait)
[245.010907] Starting subtest: wait
[245.013300] Starting dynamic subtest: rcs0
[245.066579] Dynamic subtest rcs0: SUCCESS (0.055s)
[245.075047] Starting dynamic subtest: bcs0
[245.132053] Dynamic subtest bcs0: SUCCESS (0.064s)
[245.140251] Starting dynamic subtest: vcs0
[245.178457] Dynamic subtest vcs0: SUCCESS (0.046s)
[245.186797] Starting dynamic subtest: vcs1
[245.224527] Dynamic subtest vcs1: SUCCESS (0.045s)
[245.233017] Starting dynamic subtest: vecs0
[245.273144] Dynamic subtest vecs0: SUCCESS (0.047s)
[245.281447] Starting dynamic subtest: ccs0
[245.316377] Dynamic subtest ccs0: SUCCESS (0.042s)
[245.318504] Subtest wait: SUCCESS (0.306s)
[245.440684] [069/133] (774s left) vgem_basic (unload)
[245.484781] Starting subtest: unload
[274.463472] Subtest unload: SUCCESS (28.977s)
[274.562500] [070/133] (745s left) gem_exec_whisper (basic-fds-priority)
[274.629822] Starting subtest: basic-fds-priority
[299.023030] Subtest basic-fds-priority: SUCCESS (24.387s)
[299.224812] [071/133] (721s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[299.338042] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[299.344145] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[299.466546] [072/133] (720s left) gem_exec_schedule (noreorder)
[299.641757] Starting subtest: noreorder
[299.644375] Starting dynamic subtest: rcs0
[299.713837] Dynamic subtest rcs0: SUCCESS (0.072s)
[299.726033] Starting dynamic subtest: vcs0
[300.882881] Dynamic subtest vcs0: SUCCESS (1.166s)
[300.892745] Starting dynamic subtest: vcs1
[302.870867] Dynamic subtest vcs1: SUCCESS (1.985s)
[302.882895] Starting dynamic subtest: vecs0
[304.911332] Dynamic subtest vecs0: SUCCESS (2.038s)
[304.922940] Starting dynamic subtest: ccs0
[304.987094] Dynamic subtest ccs0: SUCCESS (0.074s)
[304.989930] Subtest noreorder: SUCCESS (5.347s)
[305.172469] [073/133] (715s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[305.264079] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[305.266311] Starting dynamic subtest: pipe-A
[305.329301] Dynamic subtest pipe-A: SUCCESS (0.068s)
[305.340039] Starting dynamic subtest: pipe-B
[306.474371] Dynamic subtest pipe-B: SUCCESS (1.144s)
[306.485480] Starting dynamic subtest: pipe-C
[307.583882] Dynamic subtest pipe-C: SUCCESS (1.108s)
[307.593743] Starting dynamic subtest: pipe-D
[308.718510] Dynamic subtest pipe-D: SUCCESS (1.134s)
[308.720844] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.458s)
[309.903557] [074/133] (710s left) gem_exec_params (invalid-bsd-ring)
[309.954787] Starting subtest: invalid-bsd-ring
[309.957281] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[310.050575] [075/133] (710s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[310.143222] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[310.147183] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[310.237294] [076/133] (710s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[310.331827] Starting subtest: fbcpsr-2p-rte
[310.336088] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[310.427167] [077/133] (710s left) gem_ctx_persistence (legacy-engines-persistence)
[310.482041] Starting subtest: legacy-engines-persistence
[310.484182] Starting dynamic subtest: render
[311.251964] Dynamic subtest render: SUCCESS (0.769s)
[311.265307] Starting dynamic subtest: bsd1
[311.995826] Dynamic subtest bsd1: SUCCESS (0.741s)
[312.008666] Starting dynamic subtest: bsd2
[312.781704] Dynamic subtest bsd2: SUCCESS (0.783s)
[312.795982] Starting dynamic subtest: blt
[313.533812] Dynamic subtest blt: SUCCESS (0.749s)
[313.543544] Starting dynamic subtest: vebox
[314.343639] Dynamic subtest vebox: SUCCESS (0.807s)
[314.346026] Subtest legacy-engines-persistence: SUCCESS (3.863s)
[314.424067] [078/133] (706s left) kms_plane_cursor (viewport)
[314.520850] Starting subtest: viewport
[314.525442] Starting dynamic subtest: pipe-A-eDP-1-size-64
[317.160062] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.638s)
[317.220337] Starting dynamic subtest: pipe-A-eDP-1-size-128
[319.828459] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.616s)
[319.890129] Starting dynamic subtest: pipe-A-eDP-1-size-256
[322.562063] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.682s)
[322.916104] Starting dynamic subtest: pipe-B-eDP-1-size-64
[326.273437] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.366s)
[326.333489] Starting dynamic subtest: pipe-B-eDP-1-size-128
[328.930324] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.605s)
[328.988651] Starting dynamic subtest: pipe-B-eDP-1-size-256
[331.553074] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.571s)
[331.922247] Starting dynamic subtest: pipe-C-eDP-1-size-64
[334.908719] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.997s)
[334.945091] Starting dynamic subtest: pipe-C-eDP-1-size-128
[337.229477] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.291s)
[337.267882] Starting dynamic subtest: pipe-C-eDP-1-size-256
[339.607492] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.347s)
[339.939860] Starting dynamic subtest: pipe-D-eDP-1-size-64
[342.943475] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.015s)
[342.981448] Starting dynamic subtest: pipe-D-eDP-1-size-128
[345.299387] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.325s)
[345.339484] Starting dynamic subtest: pipe-D-eDP-1-size-256
[347.588319] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.258s)
[347.902622] Subtest viewport: SUCCESS (33.381s)
[348.708065] [079/133] (671s left) gem_exec_fence (concurrent)
[348.891952] Starting subtest: concurrent
[348.896606] Subtest concurrent: SKIP (0.000s)
[351.011360] [080/133] (669s left) gem_mmap_gtt (medium-copy-odd)
[351.070704] Subtest medium-copy-odd: SKIP (0.000s)
[351.137884] [081/133] (669s left) gem_exec_parallel (userptr)
[351.198702] Starting subtest: userptr
[351.201153] Starting dynamic subtest: rcs0
[352.720077] Dynamic subtest rcs0: SUCCESS (1.521s)
[352.732085] Starting dynamic subtest: bcs0
[354.237925] Dynamic subtest bcs0: SUCCESS (1.515s)
[354.252520] Starting dynamic subtest: vcs0
[355.897523] Dynamic subtest vcs0: SUCCESS (1.655s)
[355.908257] Starting dynamic subtest: vcs1
[357.910050] Dynamic subtest vcs1: SUCCESS (2.010s)
[357.922226] Starting dynamic subtest: vecs0
[359.830663] Dynamic subtest vecs0: SUCCESS (1.917s)
[359.842868] Starting dynamic subtest: ccs0
[361.334311] Dynamic subtest ccs0: SUCCESS (1.503s)
[361.336627] Subtest userptr: SUCCESS (10.136s)
[361.520548] [082/133] (659s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[361.647087] Starting subtest: verify-pxp-execution-after-suspend-resume
[369.537963] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.891s)
[369.709808] [083/133] (651s left) kms_cursor_crc (cursor-random-512x512)
[369.981727] Starting subtest: cursor-random-512x512
[369.986566] Subtest cursor-random-512x512: SKIP (0.001s)
[370.128175] [084/133] (650s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[370.234586] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[370.238714] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[370.307848] [085/133] (650s left) gem_mmap_gtt (hang)
[370.373205] Subtest hang: SKIP (0.000s)
[370.439665] [086/133] (650s left) gem_exec_schedule (u-semaphore-noskip)
[370.514126] Starting subtest: u-semaphore-noskip
[370.920727] Subtest u-semaphore-noskip: SUCCESS (0.411s)
[371.260699] [087/133] (649s left) kms_big_fb (linear-16bpp-rotate-180)
[371.373426] Starting subtest: linear-16bpp-rotate-180
[372.787834] Subtest linear-16bpp-rotate-180: SUCCESS (1.414s)
[372.904865] [088/133] (648s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[373.000900] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[376.687799] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.688s)
[377.896505] [089/133] (643s left) gem_mmap_offset (isolation)
[377.943811] Starting subtest: isolation
[377.960454] Subtest isolation: SUCCESS (0.006s)
[378.051730] [090/133] (643s left) gem_fence_thrash (bo-write-verify-none)
[378.084654] Starting subtest: bo-write-verify-none
[378.104276] Subtest bo-write-verify-none: SKIP (0.016s)
[378.161836] [091/133] (643s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[378.250616] Starting subtest: psr-rgb101010-draw-mmap-cpu
[385.359347] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (7.111s)
[385.489951] [092/133] (635s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[385.585182] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[391.910470] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.327s)
[393.110682] [093/133] (628s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[393.227315] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[393.231660] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[393.344958] [094/133] (627s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[393.435574] Starting subtest: coverage-vs-premult-vs-constant
[393.734573] Starting dynamic subtest: pipe-A-eDP-1
[394.357362] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.620s)
[394.367213] Starting dynamic subtest: pipe-D-eDP-1
[395.733766] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.377s)
[395.736168] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.300s)
[396.586971] [095/133] (624s left) kms_chamelium_frames (hdmi-crc-single)
[396.977339] Subtest hdmi-crc-single: SKIP (0.000s)
[397.788417] [096/133] (623s left) kms_addfb_basic (no-handle)
[397.839269] Starting subtest: no-handle
[397.841483] Subtest no-handle: SUCCESS (0.000s)
[397.967803] [097/133] (623s left) gem_wait (invalid-buf)
[398.031421] Starting subtest: invalid-buf
[398.033786] Subtest invalid-buf: SUCCESS (0.000s)
[398.285670] [098/133] (623s left) i915_drm_fdinfo (basics)
[398.322241] Starting subtest: basics
[398.324678] Subtest basics: SUCCESS (0.000s)
[398.395179] [099/133] (623s left) perf_pmu (busy-double-start)
[398.440282] Starting subtest: busy-double-start
[398.442652] Starting dynamic subtest: rcs0
[402.087750] Dynamic subtest rcs0: FAIL (3.594s)
[402.099628] Starting dynamic subtest: bcs0
[405.607700] Dynamic subtest bcs0: SUCCESS (3.573s)
[405.619530] Starting dynamic subtest: vcs0
[409.447931] Dynamic subtest vcs0: SUCCESS (3.838s)
[409.460219] Starting dynamic subtest: vcs1
[413.414957] Dynamic subtest vcs1: SUCCESS (3.964s)
[413.431519] Starting dynamic subtest: vecs0
[417.436744] Dynamic subtest vecs0: SUCCESS (4.019s)
[417.448738] Starting dynamic subtest: ccs0
[421.073827] Dynamic subtest ccs0: FAIL (3.565s)
[421.076125] Subtest busy-double-start: FAIL (22.564s)
[421.120106] [100/133] (600s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[421.243401] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[421.247809] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[421.329086] [101/133] (600s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[421.387104] Starting subtest: invalid-multi-wait-unsubmitted
[421.389397] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[421.490191] [102/133] (600s left) gem_ctx_persistence (engines-mixed)
[421.550346] Starting subtest: engines-mixed
[421.552529] Starting dynamic subtest: rcs0
[421.642865] Dynamic subtest rcs0: SUCCESS (0.095s)
[421.655486] Starting dynamic subtest: bcs0
[421.704877] Dynamic subtest bcs0: SUCCESS (0.060s)
[421.717827] Starting dynamic subtest: vcs0
[421.735663] Dynamic subtest vcs0: SUCCESS (0.029s)
[421.751466] Starting dynamic subtest: vcs1
[421.820752] Dynamic subtest vcs1: SUCCESS (0.084s)
[421.832669] Starting dynamic subtest: vecs0
[421.846302] Dynamic subtest vecs0: SUCCESS (0.024s)
[421.857354] Starting dynamic subtest: ccs0
[421.924827] Dynamic subtest ccs0: SUCCESS (0.077s)
[421.927420] Subtest engines-mixed: SUCCESS (0.379s)
[422.019183] [103/133] (599s left) gen9_exec_parse (basic-rejected)
[422.082615] Subtest basic-rejected: SKIP (0.000s)
[422.159910] [104/133] (599s left) vgem_basic (busy-fence)
[422.201640] Starting subtest: busy-fence
[422.203547] Subtest busy-fence: SUCCESS (0.000s)
[422.261547] [105/133] (599s left) kms_draw_crc (draw-method-blt)
[422.353571] Starting subtest: draw-method-blt
[422.355511] Starting dynamic subtest: xrgb8888-untiled
[422.622211] Dynamic subtest xrgb8888-untiled: SUCCESS (0.269s)
[422.637515] Starting dynamic subtest: rgb565-untiled
[422.867120] Dynamic subtest rgb565-untiled: SUCCESS (0.242s)
[422.878903] Starting dynamic subtest: xrgb2101010-untiled
[423.088491] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[423.100358] Starting dynamic subtest: xbgr16161616f-untiled
[423.322363] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.231s)
[423.334791] Starting dynamic subtest: xrgb8888-xtiled
[423.455109] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.131s)
[423.467600] Starting dynamic subtest: rgb565-xtiled
[423.577236] Dynamic subtest rgb565-xtiled: SUCCESS (0.120s)
[423.588682] Starting dynamic subtest: xrgb2101010-xtiled
[423.688631] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.109s)
[423.698530] Starting dynamic subtest: xbgr16161616f-xtiled
[423.799689] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[423.811445] Starting dynamic subtest: xrgb8888-4tiled
[423.932922] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[423.944493] Starting dynamic subtest: rgb565-4tiled
[424.055018] Dynamic subtest rgb565-4tiled: SUCCESS (0.120s)
[424.066529] Starting dynamic subtest: xrgb2101010-4tiled
[424.166085] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.109s)
[424.177458] Starting dynamic subtest: xbgr16161616f-4tiled
[424.277222] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.110s)
[424.279039] Subtest draw-method-blt: SUCCESS (1.925s)
[424.384666] [106/133] (597s left) gem_exec_balancer (hog)
[424.440381] Starting subtest: hog
[424.444119] Subtest hog: SKIP (0.000s)
[424.738832] [107/133] (597s left) api_intel_allocator (alloc-simple)
[424.794474] Starting subtest: alloc-simple
[424.796943] Subtest alloc-simple: SUCCESS (0.003s)
[424.928905] [108/133] (597s left) kms_cursor_legacy (torture-bo)
[425.048447] Starting subtest: torture-bo
[425.050411] Starting dynamic subtest: pipe-A
[430.769986] Dynamic subtest pipe-A: SUCCESS (5.721s)
[430.786500] Starting dynamic subtest: pipe-B
[438.182749] Dynamic subtest pipe-B: SUCCESS (7.412s)
[438.192428] Starting dynamic subtest: pipe-C
[445.412463] Dynamic subtest pipe-C: SUCCESS (7.227s)
[445.427253] Starting dynamic subtest: pipe-D
[451.352969] Dynamic subtest pipe-D: SUCCESS (5.938s)
[451.366425] Starting dynamic subtest: all-pipes
[457.908858] Dynamic subtest all-pipes: SUCCESS (6.553s)
[457.911808] Subtest torture-bo: SUCCESS (32.861s)
[458.071126] [109/133] (563s left) gem_exec_params (cliprects-invalid)
[458.154112] Starting subtest: cliprects-invalid
[458.156217] Subtest cliprects-invalid: SUCCESS (0.000s)
[458.271237] [110/133] (563s left) prime_mmap (test_dup)
[458.332962] Starting subtest: test_dup
[458.335388] Starting dynamic subtest: test_dup-smem
[458.361663] Dynamic subtest test_dup-smem: SUCCESS (0.032s)
[458.364124] Subtest test_dup: SUCCESS (0.032s)
[458.437837] [111/133] (563s left) kms_flip (bo-too-big-interruptible)
[458.538344] Starting subtest: bo-too-big-interruptible
[458.540256] Starting dynamic subtest: A-eDP1
[459.852449] Dynamic subtest A-eDP1: SUCCESS (1.312s)
[459.862186] Starting dynamic subtest: B-eDP1
[461.089069] Dynamic subtest B-eDP1: SUCCESS (1.235s)
[461.100214] Starting dynamic subtest: C-eDP1
[462.341928] Dynamic subtest C-eDP1: SUCCESS (1.252s)
[462.353565] Starting dynamic subtest: D-eDP1
[463.679148] Dynamic subtest D-eDP1: SUCCESS (1.336s)
[463.681545] Subtest bo-too-big-interruptible: SUCCESS (5.143s)
[464.885199] [112/133] (557s left) kms_busy (extended-pageflip-hang-oldfb)
[464.987276] Starting subtest: extended-pageflip-hang-oldfb
[464.989255] Starting dynamic subtest: pipe-A
[465.392353] Dynamic subtest pipe-A: SUCCESS (0.402s)
[465.403811] Starting dynamic subtest: pipe-D
[466.519616] Dynamic subtest pipe-D: SUCCESS (1.125s)
[466.522057] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.532s)
[467.344925] [113/133] (554s left) i915_pm_rps (fence-order)
[467.415907] Starting subtest: fence-order
[470.263354] Subtest fence-order: SUCCESS (2.848s)
[470.395213] [114/133] (551s left) kms_plane (plane-position-hole-dpms)
[470.502221] Starting subtest: plane-position-hole-dpms
[471.036053] Starting dynamic subtest: pipe-A-plane-1
[473.359763] Dynamic subtest pipe-A-plane-1: SUCCESS (2.325s)
[473.373713] Starting dynamic subtest: pipe-A-plane-2
[475.712710] Dynamic subtest pipe-A-plane-2: SUCCESS (2.352s)
[475.723579] Starting dynamic subtest: pipe-A-plane-3
[478.071471] Dynamic subtest pipe-A-plane-3: SUCCESS (2.358s)
[478.087684] Starting dynamic subtest: pipe-A-plane-4
[480.495430] Dynamic subtest pipe-A-plane-4: SUCCESS (2.422s)
[480.506092] Starting dynamic subtest: pipe-A-plane-5
[480.520120] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[481.721660] Starting dynamic subtest: pipe-B-plane-1
[484.134552] Dynamic subtest pipe-B-plane-1: SUCCESS (2.418s)
[484.147742] Starting dynamic subtest: pipe-B-plane-2
[486.491953] Dynamic subtest pipe-B-plane-2: SUCCESS (2.357s)
[486.502643] Starting dynamic subtest: pipe-B-plane-3
[488.839041] Dynamic subtest pipe-B-plane-3: SUCCESS (2.347s)
[488.849653] Starting dynamic subtest: pipe-B-plane-4
[491.223753] Dynamic subtest pipe-B-plane-4: SUCCESS (2.382s)
[491.235456] Starting dynamic subtest: pipe-B-plane-5
[491.247654] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[491.249795] Subtest plane-position-hole-dpms: SUCCESS (20.746s)
[492.047245] [115/133] (530s left) kms_pm_rpm (legacy-planes)
[494.088233] Starting subtest: legacy-planes
[494.090701] Starting dynamic subtest: plane-65
[502.373919] Dynamic subtest plane-65: SUCCESS (8.288s)
[502.386603] Starting dynamic subtest: plane-95
[510.647588] Dynamic subtest plane-95: SUCCESS (8.270s)
[510.660543] Starting dynamic subtest: plane-125
[518.922642] Dynamic subtest plane-125: SUCCESS (8.272s)
[518.934870] Starting dynamic subtest: plane-135
[527.223318] Dynamic subtest plane-135: SUCCESS (8.297s)
[527.226273] Subtest legacy-planes: SUCCESS (33.140s)
[527.607388] [116/133] (494s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[527.699757] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[531.735754] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.039s)
[532.908528] [117/133] (489s left) kms_color (deep-color)
[532.995447] Starting subtest: deep-color
[534.059365] Starting dynamic subtest: pipe-A-eDP-1-gamma
[534.773851] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.714s)
[534.784408] Starting dynamic subtest: pipe-A-eDP-1-degamma
[536.259345] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.486s)
[536.269142] Starting dynamic subtest: pipe-A-eDP-1-ctm
[537.712612] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.449s)
[538.471370] Starting dynamic subtest: pipe-B-eDP-1-gamma
[539.238173] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.772s)
[539.248091] Starting dynamic subtest: pipe-B-eDP-1-degamma
[540.702829] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.464s)
[540.713192] Starting dynamic subtest: pipe-B-eDP-1-ctm
[542.142070] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.439s)
[542.888661] Starting dynamic subtest: pipe-C-eDP-1-gamma
[543.573199] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.693s)
[543.583504] Starting dynamic subtest: pipe-C-eDP-1-degamma
[544.953062] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.377s)
[544.962727] Starting dynamic subtest: pipe-C-eDP-1-ctm
[546.373660] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.421s)
[547.177258] Starting dynamic subtest: pipe-D-eDP-1-gamma
[547.882561] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.712s)
[547.892505] Starting dynamic subtest: pipe-D-eDP-1-degamma
[549.265042] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.381s)
[549.274796] Starting dynamic subtest: pipe-D-eDP-1-ctm
[550.695419] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.430s)
[550.697810] Subtest deep-color: SUCCESS (17.701s)
[551.550421] [118/133] (470s left) syncobj_eventfd (timeline-wait)
[551.617197] Starting subtest: timeline-wait
[551.626738] Subtest timeline-wait: SUCCESS (0.011s)
[551.724174] [119/133] (470s left) kms_psr (fbc-psr2-sprite-render)
[551.813213] Starting subtest: fbc-psr2-sprite-render
[551.815455] Starting dynamic subtest: eDP-1
[551.819483] Dynamic subtest eDP-1: SKIP (0.000s)
[551.823536] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[551.893760] [120/133] (470s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[552.000123] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[552.004443] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[552.092830] [121/133] (470s left) perf_pmu (busy-idle-no-semaphores)
[552.130484] Starting subtest: busy-idle-no-semaphores
[552.132704] Starting dynamic subtest: rcs0
[552.783910] Dynamic subtest rcs0: SUCCESS (0.653s)
[552.804644] Starting dynamic subtest: bcs0
[553.437391] Dynamic subtest bcs0: SUCCESS (0.652s)
[553.448912] Starting dynamic subtest: vcs0
[554.088943] Dynamic subtest vcs0: SUCCESS (0.649s)
[554.100613] Starting dynamic subtest: vcs1
[555.520386] Dynamic subtest vcs1: SUCCESS (1.428s)
[555.534116] Starting dynamic subtest: vecs0
[557.503917] Dynamic subtest vecs0: SUCCESS (1.981s)
[557.515524] Starting dynamic subtest: ccs0
[559.554063] Dynamic subtest ccs0: SUCCESS (2.048s)
[559.556704] Subtest busy-idle-no-semaphores: SUCCESS (7.426s)
[559.672013] [122/133] (462s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[559.792141] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[566.335472] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.546s)
[566.461867] [123/133] (456s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[566.558496] Starting subtest: 4-tiled-16bpp-rotate-270
[566.579386] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.020s)
[566.684521] [124/133] (455s left) kms_flip (2x-absolute-wf_vblank)
[566.779170] Starting subtest: 2x-absolute-wf_vblank
[566.788114] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[566.872302] [125/133] (455s left) core_setmaster (master-drop-set-root)
[566.938823] Starting subtest: master-drop-set-root
[566.955339] Subtest master-drop-set-root: SUCCESS (0.013s)
[566.999393] [126/133] (455s left) gem_ctx_param (invalid-param-set)
[567.051905] Starting subtest: invalid-param-set
[567.054236] Subtest invalid-param-set: SUCCESS (0.000s)
[567.160481] [127/133] (455s left) kms_sequence (get-idle)
[567.253224] Starting subtest: get-idle
[567.255565] Starting dynamic subtest: pipe-A-eDP-1
[569.070778] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.815s)
[569.081387] Starting dynamic subtest: pipe-B-eDP-1
[571.548644] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.477s)
[571.558824] Starting dynamic subtest: pipe-C-eDP-1
[574.052545] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.502s)
[574.063087] Starting dynamic subtest: pipe-D-eDP-1
[576.486214] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.433s)
[576.488632] Subtest get-idle: SUCCESS (9.232s)
[577.310746] [128/133] (445s left) gem_mmap_wc (invalid-flags)
[577.374609] Subtest invalid-flags: SKIP (0.000s)
[577.433701] [129/133] (445s left) kms_chamelium_frames (vga-frame-dump)
[577.824160] Subtest vga-frame-dump: SKIP (0.000s)
[578.612217] [130/133] (444s left) kms_flip (2x-flip-vs-dpms-on-nop)
[578.708380] Starting subtest: 2x-flip-vs-dpms-on-nop
[578.719362] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.010s)
[578.814665] [131/133] (444s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[578.919291] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[578.923511] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[579.022526] [132/133] (443s left) gem_userptr_blits (unsync-unmap-cycles)
[579.082298] Subtest unsync-unmap-cycles: SKIP (0.000s)
[579.202858] [133/133] (443s left) fbdev (eof)
[579.242405] Starting subtest: eof
[579.244680] Subtest eof: SUCCESS (0.000s)
[579.308323] 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.