[57.041184] Initializing watchdogs
[57.041237]   /dev/watchdog0
[57.056071] [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.063417] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.065928] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.069458] [001/156] (960s left) kms_setmode (invalid-clone-single-crtc-stealing)
[57.144152] Starting subtest: invalid-clone-single-crtc-stealing
[57.150920] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s)
[57.292252] [002/156] (960s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[57.419801] Starting subtest: cursorB-vs-flipB-varying-size
[57.424565] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[57.570949] [003/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-pwrite)
[57.679372] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-pwrite
[57.683835] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[57.765458] [004/156] (959s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[57.873302] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[57.876850] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[57.965814] [005/156] (959s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[58.065626] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[58.735701] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.671s)
[58.902534] [006/156] (958s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[58.956049] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[58.958419] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[59.071207] [007/156] (958s left) kms_scaling_modes (scaling-mode-full-aspect)
[59.157862] Starting subtest: scaling-mode-full-aspect
[59.160233] Starting dynamic subtest: pipe-A-eDP-1
[59.258654] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.101s)
[59.268134] Starting dynamic subtest: pipe-B-eDP-1
[60.406403] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.146s)
[60.416295] Starting dynamic subtest: pipe-C-eDP-1
[61.609236] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.202s)
[61.616874] Starting dynamic subtest: pipe-D-eDP-1
[62.741729] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s)
[62.744100] Subtest scaling-mode-full-aspect: SUCCESS (3.585s)
[63.917462] [008/156] (953s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[64.011632] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[64.015814] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[64.104564] [009/156] (953s left) prime_udl
[64.178026] [010/156] (953s left) kms_atomic_transition (modeset-transition-fencing)
[64.265374] Starting subtest: modeset-transition-fencing
[64.267642] Starting dynamic subtest: 1x-outputs
[75.335893] Dynamic subtest 1x-outputs: SUCCESS (11.071s)
[75.338512] Subtest modeset-transition-fencing: SUCCESS (11.072s)
[76.187227] [011/156] (941s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[76.282721] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[76.284597] Starting dynamic subtest: pipe-A-eDP-1
[78.439566] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.157s)
[78.446544] Starting dynamic subtest: pipe-B-eDP-1
[80.709424] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.270s)
[80.717225] Starting dynamic subtest: pipe-C-eDP-1
[82.779534] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.067s)
[82.787630] Starting dynamic subtest: pipe-D-eDP-1
[84.843176] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.064s)
[84.845123] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.562s)
[86.011093] [012/156] (932s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[86.074842] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[86.180647] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.105s)
[86.258283] [013/156] (931s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[86.364147] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[90.350860] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.991s)
[91.522239] [014/156] (926s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-blt)
[91.617046] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-blt
[91.621106] Subtest fbchdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[91.703579] [015/156] (926s left) gem_mmap_gtt (basic-write-gtt)
[91.751134] Subtest basic-write-gtt: SKIP (0.000s)
[91.801394] [016/156] (926s left) gem_ringfill (basic-all)
[97.284430] Starting subtest: basic-all
[98.452431] Subtest basic-all: SUCCESS (1.168s)
[98.582253] [FACT gem_ringfill (basic-all)] new: kernel.kmod_is_loaded.vgem: true
[98.585907] [017/156] (919s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping)
[98.684246] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping
[98.688732] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[98.756159] [018/156] (919s left) dumb_buffer (create-valid-dumb)
[98.807372] Starting subtest: create-valid-dumb
[98.809351] Subtest create-valid-dumb: SUCCESS (0.000s)
[98.911282] [019/156] (919s left) perf (oa-exponents)
[99.025045] Starting subtest: oa-exponents
[99.026965] Starting dynamic subtest: 0-rcs0
[100.801513] Dynamic subtest 0-rcs0: SUCCESS (1.776s)
[100.810832] Starting dynamic subtest: 1-vcs0
[102.629704] Dynamic subtest 1-vcs0: SUCCESS (1.827s)
[102.632390] Subtest oa-exponents: SUCCESS (3.605s)
[102.675465] [020/156] (915s left) prime_mmap (test_forked_cpu_write)
[102.732790] Starting subtest: test_forked_cpu_write
[102.735054] Starting dynamic subtest: test_forked_cpu_write-smem
[102.739862] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.007s)
[102.742234] Subtest test_forked_cpu_write: SUCCESS (0.008s)
[102.842131] [021/156] (915s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[102.951796] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[102.956245] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[103.056647] [022/156] (915s left) kms_psr (fbc-psr-sprite-render)
[103.142720] Starting subtest: fbc-psr-sprite-render
[103.144997] Starting dynamic subtest: eDP-1
[103.149153] Dynamic subtest eDP-1: SKIP (0.000s)
[103.153319] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[103.227196] [023/156] (915s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[103.327978] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-wc
[103.332425] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[103.433087] [024/156] (915s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[103.527168] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[110.848022] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.326s)
[110.984603] [025/156] (907s left) api_intel_allocator (fork-simple-once)
[111.048407] Starting subtest: fork-simple-once
[111.061331] Subtest fork-simple-once: SUCCESS (0.015s)
[111.202686] [026/156] (907s left) gem_userptr_blits (huge-split)
[111.276127] Starting subtest: huge-split
[111.284981] Subtest huge-split: SUCCESS (0.009s)
[111.414027] [027/156] (907s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[111.509186] Starting subtest: plane-primary-toggle-with-vblank-wait
[111.511474] Starting dynamic subtest: pipe-A-eDP-1
[111.681301] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.174s)
[111.999765] Starting dynamic subtest: pipe-B-eDP-1
[112.941747] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.950s)
[113.242727] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.733s)
[114.104178] [028/156] (904s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[114.200927] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[114.205517] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[114.302376] [029/156] (904s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[114.394638] Starting subtest: y-tiled-8bpp-rotate-0
[114.399071] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[114.487666] [030/156] (904s left) syncobj_timeline (etime-single-wait-all-submitted)
[114.548073] Starting subtest: etime-single-wait-all-submitted
[114.656068] Subtest etime-single-wait-all-submitted: SUCCESS (0.105s)
[114.728294] [031/156] (904s left) gem_create (create-clear)
[114.779859] Starting subtest: create-clear
[114.783632] Starting dynamic subtest: smem0
[147.610561] Dynamic subtest smem0: SUCCESS (32.828s)
[147.612676] Subtest create-clear: SUCCESS (32.828s)
[147.829072] [032/156] (870s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite)
[147.943675] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite
[147.947987] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[148.026149] [033/156] (870s left) gem_linear_blits (interruptible)
[148.396057] Starting subtest: interruptible
[152.378519] Subtest interruptible: SUCCESS (3.985s)
[152.503355] [034/156] (866s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[152.651615] Starting subtest: pipe-fbc-rte
[152.658248] Starting dynamic subtest: pipe-B-eDP-1
[158.071052] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.424s)
[158.079292] Subtest pipe-fbc-rte: SUCCESS (5.426s)
[160.343818] [035/156] (858s left) gem_exec_fence (syncobj-timeline-signal)
[160.918636] Starting subtest: syncobj-timeline-signal
[160.925277] Subtest syncobj-timeline-signal: SUCCESS (0.009s)
[162.400141] [036/156] (856s left) core_auth (many-magics)
[162.461982] Starting subtest: many-magics
[163.375289] Subtest many-magics: SUCCESS (0.916s)
[163.541761] [037/156] (855s left) kms_lease (empty-lease)
[163.637589] Starting subtest: empty-lease
[163.639630] Starting dynamic subtest: pipe-A-eDP-1
[163.641580] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[163.650576] Starting dynamic subtest: pipe-B-eDP-1
[163.652424] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[163.660336] Starting dynamic subtest: pipe-C-eDP-1
[163.662145] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[163.669758] Starting dynamic subtest: pipe-D-eDP-1
[163.671632] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[163.673485] Subtest empty-lease: SUCCESS (0.007s)
[163.758307] [038/156] (855s left) kms_plane_scaling (invalid-parameters)
[163.846150] Starting subtest: invalid-parameters
[163.848112] Starting dynamic subtest: less-than-1-height-src
[163.858151] Dynamic subtest less-than-1-height-src: SUCCESS (0.013s)
[163.866510] Starting dynamic subtest: less-than-1-width-src
[163.873363] Dynamic subtest less-than-1-width-src: SUCCESS (0.013s)
[163.874973] Subtest invalid-parameters: SUCCESS (0.026s)
[163.963142] [039/156] (855s left) kms_chamelium_hpd (common-hpd-after-suspend)
[164.351139] Subtest common-hpd-after-suspend: SKIP (0.000s)
[165.160612] [040/156] (853s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-pwrite)
[165.257128] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-pwrite
[165.261085] Subtest hdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[165.368140] [041/156] (853s left) gem_ctx_param (set-priority-range)
[165.434268] Starting subtest: set-priority-range
[165.446521] Subtest set-priority-range: SUCCESS (0.015s)
[165.557184] [042/156] (853s left) gem_busy (semaphore)
[165.739861] Subtest semaphore: SKIP (0.000s)
[165.955745] [043/156] (853s left) gem_mmap_wc (pf-nonblock)
[166.017826] Subtest pf-nonblock: SKIP (0.000s)
[166.072358] [044/156] (853s left) kms_psr (fbc-psr2-primary-blt)
[166.164772] Starting subtest: fbc-psr2-primary-blt
[166.167094] Starting dynamic subtest: eDP-1
[166.171663] Dynamic subtest eDP-1: SKIP (0.000s)
[166.176319] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[166.246854] [045/156] (853s left) kms_debugfs (display-off-read-all)
[166.333803] Starting subtest: display-off-read-all
[166.620513] Subtest display-off-read-all: SUCCESS (0.287s)
[167.503559] [046/156] (851s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-indfb-plflip-blt)
[167.607650] Starting subtest: psrhdr-2p-primscrn-indfb-plflip-blt
[167.612016] Subtest psrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[167.710761] [047/156] (851s left) vgem_basic (bad-flag)
[167.753238] Starting subtest: bad-flag
[167.755403] Subtest bad-flag: SUCCESS (0.000s)
[167.807416] [048/156] (851s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[167.913685] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[167.918244] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[168.003818] [049/156] (851s left) gem_ctx_shared (q-promotion)
[168.066433] Starting subtest: Q-promotion
[168.069069] Starting dynamic subtest: rcs0
[168.175317] Dynamic subtest rcs0: SUCCESS (0.107s)
[168.190733] Starting dynamic subtest: vcs0
[168.265877] Dynamic subtest vcs0: SUCCESS (0.091s)
[168.282853] Starting dynamic subtest: vcs1
[168.344133] Dynamic subtest vcs1: SUCCESS (0.077s)
[168.358274] Starting dynamic subtest: vecs0
[168.413490] Dynamic subtest vecs0: SUCCESS (0.069s)
[168.428142] Starting dynamic subtest: ccs0
[168.492997] Dynamic subtest ccs0: SUCCESS (0.078s)
[168.496106] Subtest Q-promotion: SUCCESS (0.425s)
[169.365619] [050/156] (850s left) gem_mmap_wc (invalid-flags)
[169.421180] Subtest invalid-flags: SKIP (0.000s)
[169.481101] [051/156] (850s left) perf_pmu (busy-start)
[169.519728] Starting subtest: busy-start
[169.521957] Starting dynamic subtest: rcs0
[172.070782] Dynamic subtest rcs0: SUCCESS (2.552s)
[172.082319] Starting dynamic subtest: bcs0
[174.620499] Dynamic subtest bcs0: SUCCESS (2.548s)
[174.631225] Starting dynamic subtest: vcs0
[178.303448] Dynamic subtest vcs0: SUCCESS (3.680s)
[178.313894] Starting dynamic subtest: vcs1
[182.271599] Dynamic subtest vcs1: SUCCESS (3.966s)
[182.281445] Starting dynamic subtest: vecs0
[186.303764] Dynamic subtest vecs0: SUCCESS (4.030s)
[186.315746] Starting dynamic subtest: ccs0
[188.853459] Dynamic subtest ccs0: SUCCESS (2.549s)
[188.856010] Subtest busy-start: SUCCESS (19.336s)
[188.973842] [052/156] (830s left) kms_flip (flip-vs-panning)
[189.075613] Starting subtest: flip-vs-panning
[189.077987] Starting dynamic subtest: A-eDP1
[197.815714] Dynamic subtest A-eDP1: SUCCESS (8.742s)
[197.827855] Starting dynamic subtest: B-eDP1
[206.543083] Dynamic subtest B-eDP1: SUCCESS (8.725s)
[206.552589] Starting dynamic subtest: C-eDP1
[215.270489] Dynamic subtest C-eDP1: SUCCESS (8.726s)
[215.287153] Starting dynamic subtest: D-eDP1
[223.998421] Dynamic subtest D-eDP1: SUCCESS (8.726s)
[224.002893] Subtest flip-vs-panning: SUCCESS (34.929s)
[225.165474] [053/156] (794s left) gem_userptr_blits (access-control)
[225.236709] Starting subtest: access-control
[225.241103] Subtest access-control: SKIP (0.000s)
[225.357535] [054/156] (794s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[225.444449] Starting subtest: flip-vs-dpms-off-vs-modeset
[225.446707] Starting dynamic subtest: A-eDP1
[229.123018] Dynamic subtest A-eDP1: SUCCESS (3.678s)
[229.133201] Starting dynamic subtest: B-eDP1
[232.342531] Dynamic subtest B-eDP1: SUCCESS (3.217s)
[232.351388] Starting dynamic subtest: C-eDP1
[235.554254] Dynamic subtest C-eDP1: SUCCESS (3.212s)
[235.562817] Starting dynamic subtest: D-eDP1
[238.768738] Dynamic subtest D-eDP1: SUCCESS (3.214s)
[238.771218] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.327s)
[239.577608] [055/156] (780s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[239.677825] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[239.681818] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[239.759536] [056/156] (780s left) kms_frontbuffer_tracking (fbchdr-modesetfrombusy)
[239.851070] Starting subtest: fbchdr-modesetfrombusy
[239.855163] Subtest fbchdr-modesetfrombusy: SKIP (0.000s)
[239.956935] [057/156] (779s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[240.050362] Starting subtest: disable-crc-after-crtc
[240.052732] Starting dynamic subtest: pipe-A-eDP-1
[241.734081] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.682s)
[241.743241] Starting dynamic subtest: pipe-B-eDP-1
[244.087652] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.353s)
[244.098061] Starting dynamic subtest: pipe-C-eDP-1
[246.334870] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.244s)
[246.343790] Starting dynamic subtest: pipe-D-eDP-1
[248.583237] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.249s)
[248.585608] Subtest disable-crc-after-crtc: SUCCESS (8.535s)
[249.421791] [058/156] (770s left) kms_cursor_crc (cursor-sliding-64x21)
[249.597798] Starting subtest: cursor-sliding-64x21
[249.645085] Subtest cursor-sliding-64x21: SKIP (0.042s)
[249.876883] [059/156] (770s left) i915_query (query-regions-unallocated)
[249.927816] Starting subtest: query-regions-unallocated
[249.930091] Subtest query-regions-unallocated: SUCCESS (0.000s)
[250.021742] [060/156] (769s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[250.114979] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[250.119308] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[250.215167] [061/156] (769s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[250.303051] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[250.305438] Starting dynamic subtest: pipe-A-eDP-1
[250.329064] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[250.339681] Starting dynamic subtest: pipe-B-eDP-1
[250.358750] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[250.366934] Starting dynamic subtest: pipe-C-eDP-1
[250.385530] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[250.393441] Starting dynamic subtest: pipe-D-eDP-1
[250.412042] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[250.415456] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[252.589753] [062/156] (767s left) gem_exec_reloc (basic-gtt)
[252.651827] Subtest basic-gtt: SKIP (0.000s)
[252.716800] [063/156] (767s left) gem_mmap (short-mmap)
[252.776771] Subtest short-mmap: SKIP (0.000s)
[252.838743] [064/156] (767s left) kms_dsc (dsc-with-formats)
[252.928633] Starting subtest: dsc-with-formats
[252.969598] Subtest dsc-with-formats: SKIP (0.001s)
[253.010885] [065/156] (767s left) prime_self_import (basic-with_two_bos)
[253.055373] Starting subtest: basic-with_two_bos
[253.082768] Subtest basic-with_two_bos: SUCCESS (0.027s)
[253.197396] [066/156] (766s left) kms_cursor_crc (cursor-offscreen-max-size)
[253.488758] Starting subtest: cursor-offscreen-max-size
[253.495374] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[253.622130] [067/156] (766s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[253.708872] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[260.754605] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.049s)
[260.876829] [068/156] (759s left) kms_flip (flip-vs-rmfb)
[260.968070] Starting subtest: flip-vs-rmfb
[260.970500] Starting dynamic subtest: A-eDP1
[269.614732] Dynamic subtest A-eDP1: SUCCESS (8.646s)
[269.624929] Starting dynamic subtest: B-eDP1
[278.251059] Dynamic subtest B-eDP1: SUCCESS (8.635s)
[278.264313] Starting dynamic subtest: C-eDP1
[286.869763] Dynamic subtest C-eDP1: SUCCESS (8.616s)
[286.878376] Starting dynamic subtest: D-eDP1
[295.507883] Dynamic subtest D-eDP1: SUCCESS (8.636s)
[295.512720] Subtest flip-vs-rmfb: SUCCESS (34.542s)
[296.700449] [069/156] (723s left) kms_fbcon_fbt (fbc)
[296.750041] Starting subtest: fbc
[303.440293] Subtest fbc: SUCCESS (6.688s)
[304.839592] [070/156] (715s left) kms_content_protection (dp-mst-type-1)
[304.986917] Starting subtest: dp-mst-type-1
[304.991852] Subtest dp-mst-type-1: SKIP (0.000s)
[305.124953] [071/156] (715s left) kms_psr (psr-dpms)
[305.207957] Starting subtest: psr-dpms
[305.210173] Starting dynamic subtest: eDP-1
[308.193099] Dynamic subtest eDP-1: SUCCESS (2.984s)
[308.195458] Subtest psr-dpms: SUCCESS (2.986s)
[309.072111] [072/156] (711s left) gem_exec_balancer (sequential)
[309.130218] Starting subtest: sequential
[312.210660] Subtest sequential: SUCCESS (3.080s)
[312.544164] [073/156] (707s left) gem_userptr_blits (unsync-unmap-cycles)
[312.609585] Subtest unsync-unmap-cycles: SKIP (0.000s)
[312.752761] [074/156] (707s left) api_intel_bb (intel-bb-blit-x)
[312.814751] Starting subtest: intel-bb-blit-x
[316.336481] Subtest intel-bb-blit-x: SUCCESS (3.520s)
[316.463373] [075/156] (704s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[316.571855] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[316.575820] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[316.656169] [076/156] (703s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[316.759973] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[316.764518] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[316.859629] [077/156] (703s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[316.960166] Starting subtest: fbc-pr-sprite-mmap-gtt
[316.964514] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[317.034115] [078/156] (703s left) syncobj_timeline (invalid-wait-illegal-handle)
[317.098610] Starting subtest: invalid-wait-illegal-handle
[317.101115] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[317.214701] [079/156] (703s left) kms_psr (pr-primary-mmap-cpu)
[317.314223] Starting subtest: pr-primary-mmap-cpu
[317.318742] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[317.386078] [080/156] (703s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[317.482452] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[317.484268] Starting dynamic subtest: pipe-A-eDP-1
[317.502862] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[317.514103] Starting dynamic subtest: pipe-B-eDP-1
[317.531641] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[317.539769] Starting dynamic subtest: pipe-C-eDP-1
[317.557768] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[317.565905] Starting dynamic subtest: pipe-D-eDP-1
[317.584278] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[317.587746] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[319.733057] [081/156] (701s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[319.778566] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[319.780761] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[319.877644] [082/156] (700s left) gem_exec_balancer (busy)
[319.927828] Starting subtest: busy
[321.257896] Subtest busy: SUCCESS (1.332s)
[321.569187] [083/156] (699s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[321.658046] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-wc
[321.662161] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[321.747939] [084/156] (699s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[321.840703] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[321.844712] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[321.931843] [085/156] (699s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[322.037868] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[325.994422] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.964s)
[327.147150] [086/156] (693s left) gem_exec_whisper (basic-contexts-priority-all)
[327.216350] Starting subtest: basic-contexts-priority-all
[350.463110] Subtest basic-contexts-priority-all: SUCCESS (23.242s)
[350.663960] [087/156] (670s left) gem_mmap_offset (blt-coherency)
[350.738254] Starting subtest: blt-coherency
[350.748070] Subtest blt-coherency: SUCCESS (0.012s)
[350.872931] [088/156] (670s left) gem_softpin (safe-alignment)
[350.920808] Starting subtest: safe-alignment
[350.956216] Subtest safe-alignment: SUCCESS (0.035s)
[352.359776] [089/156] (668s left) gem_exec_reloc (basic-write-read-noreloc)
[352.408300] Subtest basic-write-read-noreloc: SKIP (0.000s)
[352.477232] [090/156] (668s left) i915_drm_fdinfo (most-busy-idle-check-all)
[352.513712] Starting subtest: most-busy-idle-check-all
[352.515710] Starting dynamic subtest: rcs0
[352.520330] Dynamic subtest rcs0: SKIP (0.004s)
[352.529946] Starting dynamic subtest: bcs0
[352.534561] Dynamic subtest bcs0: SKIP (0.000s)
[352.543359] Starting dynamic subtest: vcs0
[352.547771] Dynamic subtest vcs0: SKIP (0.000s)
[352.556342] Starting dynamic subtest: vcs1
[352.560788] Dynamic subtest vcs1: SKIP (0.000s)
[352.569772] Starting dynamic subtest: vecs0
[352.574322] Dynamic subtest vecs0: SKIP (0.001s)
[352.582905] Starting dynamic subtest: ccs0
[352.587323] Dynamic subtest ccs0: SKIP (0.000s)
[352.591767] Subtest most-busy-idle-check-all: SKIP (0.010s)
[352.630921] [091/156] (668s left) gem_exec_schedule (implicit-read-write)
[352.737096] Starting subtest: implicit-read-write
[352.739035] Starting dynamic subtest: rcs0
[352.910032] Dynamic subtest rcs0: SUCCESS (0.180s)
[352.922986] Starting dynamic subtest: vcs0
[353.016987] Dynamic subtest vcs0: SUCCESS (0.106s)
[353.028119] Starting dynamic subtest: vcs1
[353.122975] Dynamic subtest vcs1: SUCCESS (0.105s)
[353.137141] Starting dynamic subtest: vecs0
[353.226044] Dynamic subtest vecs0: SUCCESS (0.101s)
[353.237894] Starting dynamic subtest: ccs0
[353.350413] Dynamic subtest ccs0: SUCCESS (0.124s)
[353.352506] Subtest implicit-read-write: SUCCESS (0.622s)
[354.406620] [092/156] (666s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[354.497808] Starting subtest: psr-1p-primscrn-spr-indfb-move
[361.874066] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.381s)
[362.030891] [093/156] (659s left) gem_ctx_persistence (hostile)
[362.097621] Starting subtest: hostile
[371.584809] Subtest hostile: SUCCESS (9.481s)
[371.711330] [094/156] (649s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-plflip-blt)
[371.812538] Starting subtest: psrhdr-1p-primscrn-indfb-plflip-blt
[371.816586] Subtest psrhdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[371.917827] [095/156] (649s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[372.038814] Starting subtest: verify-pxp-stale-buf-optout-execution
[373.100380] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.062s)
[373.218500] [096/156] (648s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[373.329630] Starting subtest: x-tiled-16bpp-rotate-0
[374.663377] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.335s)
[374.801590] [097/156] (646s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[374.893479] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[374.896907] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[374.973167] [098/156] (646s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[375.086368] Starting subtest: 2x-flip-vs-cursor-atomic
[375.090279] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[375.228185] [099/156] (646s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[375.325619] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[375.328989] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[375.416486] [100/156] (646s left) kms_color_pipeline (plane-lut1d-lut1d)
[375.508521] Starting subtest: plane-lut1d-lut1d
[375.676975] Starting dynamic subtest: pipe-A-plane-0
[375.843472] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[375.852169] Starting dynamic subtest: pipe-A-plane-1
[376.010069] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[376.019165] Starting dynamic subtest: pipe-A-plane-2
[376.143486] Dynamic subtest pipe-A-plane-2: SUCCESS (0.132s)
[377.351942] Starting dynamic subtest: pipe-B-plane-0
[377.534789] Dynamic subtest pipe-B-plane-0: SUCCESS (0.189s)
[377.544383] Starting dynamic subtest: pipe-B-plane-1
[377.666756] Dynamic subtest pipe-B-plane-1: SUCCESS (0.132s)
[377.676393] Starting dynamic subtest: pipe-B-plane-2
[377.799708] Dynamic subtest pipe-B-plane-2: SUCCESS (0.132s)
[378.979549] Starting dynamic subtest: pipe-C-plane-0
[379.105691] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[379.117912] Starting dynamic subtest: pipe-C-plane-1
[379.250220] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[379.261738] Starting dynamic subtest: pipe-C-plane-2
[379.405110] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[380.592018] Starting dynamic subtest: pipe-D-plane-0
[380.728754] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[380.738464] Starting dynamic subtest: pipe-D-plane-1
[380.872653] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[380.882408] Starting dynamic subtest: pipe-D-plane-2
[381.006131] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[381.296232] Subtest plane-lut1d-lut1d: SUCCESS (5.786s)
[382.167706] [101/156] (639s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-pgflip-blt)
[382.269195] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-pgflip-blt
[382.273209] Subtest fbcpsrhdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[382.379972] [102/156] (639s left) kms_prop_blob (blob-prop-lifetime)
[382.435679] Starting subtest: blob-prop-lifetime
[382.437669] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[382.550437] [103/156] (639s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[382.650534] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[382.654851] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[382.758514] [104/156] (638s left) kms_plane_scaling (planes-scaler-unity-scaling)
[382.862130] Starting subtest: planes-scaler-unity-scaling
[382.864449] Starting dynamic subtest: pipe-A
[382.922946] Dynamic subtest pipe-A: SUCCESS (0.064s)
[382.935198] Starting dynamic subtest: pipe-B
[384.099392] Dynamic subtest pipe-B: SUCCESS (1.174s)
[384.111216] Starting dynamic subtest: pipe-C
[385.217981] Dynamic subtest pipe-C: SUCCESS (1.117s)
[385.228795] Starting dynamic subtest: pipe-D
[386.337019] Dynamic subtest pipe-D: SUCCESS (1.118s)
[386.339372] Subtest planes-scaler-unity-scaling: SUCCESS (3.479s)
[387.495629] [105/156] (634s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[387.614585] Starting subtest: cursor-vs-flip-atomic
[417.345672] Subtest cursor-vs-flip-atomic: SUCCESS (29.730s)
[418.212190] [106/156] (603s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[418.311871] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[418.316060] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[418.417161] [107/156] (603s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[418.472584] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[418.474539] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[418.564054] [108/156] (603s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[418.666054] Starting subtest: fbc-psr2-cursor-plane-update-sf
[418.668310] Starting dynamic subtest: pipe-A-eDP-1
[418.738778] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[418.751184] Starting dynamic subtest: pipe-B-eDP-1
[419.894092] Dynamic subtest pipe-B-eDP-1: SKIP (1.154s)
[419.898658] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.233s)
[421.087929] [109/156] (600s left) kms_chamelium_color (ctm-red-to-blue)
[421.188387] Subtest ctm-red-to-blue: SKIP (0.000s)
[421.231424] [110/156] (600s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[421.334054] Starting subtest: pr-overlay-plane-update-continuous-sf
[421.338234] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[421.399403] [111/156] (600s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-onoff)
[421.491722] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-onoff
[421.495889] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[421.573636] [112/156] (600s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-fullscreen)
[421.673113] Starting subtest: hdr-2p-primscrn-spr-indfb-fullscreen
[421.677363] Subtest hdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[421.776889] [113/156] (600s left) perf_pmu (busy-idle)
[421.816413] Starting subtest: busy-idle
[421.818704] Starting dynamic subtest: rcs0
[422.520306] Dynamic subtest rcs0: SUCCESS (0.702s)
[422.532613] Starting dynamic subtest: bcs0
[423.186966] Dynamic subtest bcs0: SUCCESS (0.665s)
[423.199318] Starting dynamic subtest: vcs0
[424.319107] Dynamic subtest vcs0: SUCCESS (1.128s)
[424.330068] Starting dynamic subtest: vcs1
[426.303423] Dynamic subtest vcs1: SUCCESS (1.982s)
[426.316106] Starting dynamic subtest: vecs0
[428.288404] Dynamic subtest vecs0: SUCCESS (1.981s)
[428.300677] Starting dynamic subtest: ccs0
[428.963551] Dynamic subtest ccs0: SUCCESS (0.674s)
[428.966277] Subtest busy-idle: SUCCESS (7.148s)
[429.068621] [114/156] (593s left) tools_test (tools_test)
[429.119488] Starting subtest: tools_test
[429.178908] Subtest tools_test: SUCCESS (0.073s)
[429.242400] [115/156] (592s left) gem_sync (basic-store-each)
[429.318171] Starting subtest: basic-store-each
[431.870934] Subtest basic-store-each: SUCCESS (2.562s)
[432.076227] [116/156] (590s left) gem_shrink (reclaim)
[432.132239] Starting subtest: reclaim
[434.585844] Subtest reclaim: SUCCESS (2.425s)
[434.684157] [117/156] (587s left) gem_exec_schedule (semaphore-power)
[434.869693] Subtest semaphore-power: SKIP (0.000s)
[435.063261] [118/156] (587s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[435.170799] Starting subtest: 4-tiled-32bpp-rotate-270
[435.190666] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.020s)
[435.316190] [119/156] (587s left) kms_pm_dc (dc5-psr)
[436.406244] Starting subtest: dc5-psr
[439.288187] Subtest dc5-psr: SUCCESS (2.883s)
[440.106401] [120/156] (582s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[440.197197] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[440.199368] Starting dynamic subtest: pipe-A-eDP-1
[440.618709] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.393s)
[440.630580] Starting dynamic subtest: pipe-B-eDP-1
[442.001587] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.383s)
[442.013400] Starting dynamic subtest: pipe-C-eDP-1
[443.414065] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.410s)
[443.424502] Starting dynamic subtest: pipe-D-eDP-1
[444.754605] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[444.756883] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.531s)
[445.959753] [121/156] (576s left) gem_exec_schedule (preempt-contexts)
[446.029365] Starting subtest: preempt-contexts
[446.031419] Starting dynamic subtest: rcs0
[446.074853] Dynamic subtest rcs0: SUCCESS (0.054s)
[446.084346] Starting dynamic subtest: bcs0
[446.130380] Dynamic subtest bcs0: SUCCESS (0.055s)
[446.142941] Starting dynamic subtest: vcs0
[446.178969] Dynamic subtest vcs0: SUCCESS (0.047s)
[446.191588] Starting dynamic subtest: vcs1
[446.220836] Dynamic subtest vcs1: SUCCESS (0.041s)
[446.231243] Starting dynamic subtest: vecs0
[446.269699] Dynamic subtest vecs0: SUCCESS (0.048s)
[446.279885] Starting dynamic subtest: ccs0
[446.316274] Dynamic subtest ccs0: SUCCESS (0.047s)
[446.318491] Subtest preempt-contexts: SUCCESS (0.296s)
[447.394298] [122/156] (575s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-shrfb-plflip-blt)
[447.499805] Starting subtest: fbchdr-2p-primscrn-shrfb-plflip-blt
[447.504344] Subtest fbchdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[447.615132] [123/156] (574s left) gem_ctx_param (invalid-get-engines)
[447.669830] Starting subtest: invalid-get-engines
[447.672365] Subtest invalid-get-engines: SUCCESS (0.001s)
[447.793395] [124/156] (574s left) gen7_exec_parse (bitmasks)
[447.856879] Subtest bitmasks: SKIP (0.000s)
[447.928045] [125/156] (574s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-plflip-blt)
[448.023133] Starting subtest: fbchdr-1p-primscrn-indfb-plflip-blt
[448.027339] Subtest fbchdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[448.119743] [126/156] (574s left) kms_big_fb (linear-16bpp-rotate-0)
[448.213504] Starting subtest: linear-16bpp-rotate-0
[449.591752] Subtest linear-16bpp-rotate-0: SUCCESS (1.378s)
[449.734849] [127/156] (572s left) prime_vgem (wait)
[449.830189] Starting subtest: wait
[449.832191] Starting dynamic subtest: rcs0
[449.916781] Dynamic subtest rcs0: SUCCESS (0.086s)
[449.928012] Starting dynamic subtest: bcs0
[449.981578] Dynamic subtest bcs0: SUCCESS (0.063s)
[449.991426] Starting dynamic subtest: vcs0
[450.024375] Dynamic subtest vcs0: SUCCESS (0.042s)
[450.033153] Starting dynamic subtest: vcs1
[450.069100] Dynamic subtest vcs1: SUCCESS (0.044s)
[450.077867] Starting dynamic subtest: vecs0
[450.115881] Dynamic subtest vecs0: SUCCESS (0.046s)
[450.124682] Starting dynamic subtest: ccs0
[450.164929] Dynamic subtest ccs0: SUCCESS (0.048s)
[450.166853] Subtest wait: SUCCESS (0.335s)
[450.311153] [128/156] (572s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[450.403656] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[450.407212] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[450.500104] [129/156] (572s left) i915_pm_rpm (gem-evict-pwrite)
[452.429450] Starting subtest: gem-evict-pwrite
[452.434824] Subtest gem-evict-pwrite: SKIP (0.000s)
[452.839222] [130/156] (569s left) gem_exec_reloc (basic-wc-read-active)
[452.903467] Subtest basic-wc-read-active: SKIP (0.000s)
[452.985806] [131/156] (569s left) kms_vblank (wait-forked)
[453.076498] Starting subtest: wait-forked
[453.078398] Starting dynamic subtest: pipe-A-eDP-1
[454.883467] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.807s)
[454.898737] Starting dynamic subtest: pipe-D-eDP-1
[457.402158] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.515s)
[457.404516] Subtest wait-forked: SUCCESS (4.325s)
[458.274504] [132/156] (564s left) syncobj_wait (multi-wait-submitted-signaled)
[458.321891] Starting subtest: multi-wait-submitted-signaled
[458.323970] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[458.443083] [133/156] (564s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[458.535382] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[462.743055] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.213s)
[463.892429] [134/156] (559s left) gem_lmem_swapping (verify-random)
[463.932826] Subtest verify-random: SKIP (0.000s)
[463.976282] [135/156] (559s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[464.065119] Starting subtest: read-crc-frame-sequence
[464.067395] Starting dynamic subtest: pipe-A-eDP-1
[464.766789] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.702s)
[464.776630] Starting dynamic subtest: pipe-B-eDP-1
[466.168684] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.399s)
[466.179039] Starting dynamic subtest: pipe-C-eDP-1
[467.551079] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.381s)
[467.560308] Starting dynamic subtest: pipe-D-eDP-1
[468.880070] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.328s)
[468.882231] Subtest read-crc-frame-sequence: SUCCESS (4.816s)
[469.710913] [136/156] (553s left) vgem_basic (sysfs)
[469.751880] Starting subtest: sysfs
[469.754125] Subtest sysfs: SUCCESS (0.001s)
[469.808970] [137/156] (553s left) gem_exec_reloc (basic-write-cpu-active)
[469.869087] Subtest basic-write-cpu-active: SKIP (0.000s)
[469.939926] [138/156] (553s left) gem_exec_nop (basic-sequential)
[470.012826] Starting subtest: basic-sequential
[484.786277] Subtest basic-sequential: SUCCESS (14.783s)
[485.022196] [139/156] (538s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[485.134319] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[485.179493] [140/156] (538s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[485.269968] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[492.678474] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.414s)
[492.800666] [141/156] (530s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[492.924961] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[492.929565] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[493.028119] [142/156] (530s left) i915_pm_freq_api (freq-reset)
[493.091112] Starting subtest: freq-reset
[493.093264] Starting dynamic subtest: gt0
[493.291101] Dynamic subtest gt0: SUCCESS (0.198s)
[493.299986] Starting dynamic subtest: gt1
[493.462578] Dynamic subtest gt1: SUCCESS (0.170s)
[493.465536] Subtest freq-reset: SUCCESS (0.370s)
[493.536221] [143/156] (529s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[493.630949] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[493.634898] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[493.730756] [144/156] (529s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[493.822438] Starting subtest: addfb25-y-tiled-legacy
[493.824595] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[493.925247] [145/156] (529s left) gem_exercise_blt (fast-copy)
[493.993509] Starting subtest: fast-copy
[493.996052] Starting dynamic subtest: linear-smem-smem
[494.016480] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[494.028354] Starting dynamic subtest: xmajor-smem-smem
[494.038876] Dynamic subtest xmajor-smem-smem: SUCCESS (0.021s)
[494.049387] Starting dynamic subtest: tile4-smem-smem
[494.058357] Dynamic subtest tile4-smem-smem: SUCCESS (0.019s)
[494.067551] Starting dynamic subtest: tile64-smem-smem
[494.076064] Dynamic subtest tile64-smem-smem: SUCCESS (0.016s)
[494.078381] Subtest fast-copy: SUCCESS (0.083s)
[494.182959] [146/156] (529s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[494.290983] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[494.295155] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[494.379148] [147/156] (529s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[494.505339] Starting subtest: cursorA-vs-flipB-atomic-transitions
[494.508911] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[494.661514] [148/156] (528s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[494.757050] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[494.758905] Starting dynamic subtest: pipe-A-default-mode
[497.473801] Dynamic subtest pipe-A-default-mode: SUCCESS (2.717s)
[497.476285] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.719s)
[498.337114] [149/156] (525s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[498.440895] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[498.445841] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[498.557323] [150/156] (525s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[498.933829] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[499.746490] [151/156] (523s left) kms_cursor_crc (cursor-random-128x42)
[499.986090] Starting subtest: cursor-random-128x42
[500.032041] Subtest cursor-random-128x42: SKIP (0.041s)
[500.230567] [152/156] (523s left) syncobj_wait (multi-wait-for-submit-signaled)
[500.281336] Starting subtest: multi-wait-for-submit-signaled
[500.283663] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[500.381562] [153/156] (523s left) kms_invalid_mode (bad-hsync-start)
[500.472675] Starting subtest: bad-hsync-start
[500.474962] Starting dynamic subtest: pipe-A-eDP-1
[500.759722] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[500.770478] Starting dynamic subtest: pipe-B-eDP-1
[500.772427] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[500.780842] Starting dynamic subtest: pipe-C-eDP-1
[500.783234] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[500.792121] Starting dynamic subtest: pipe-D-eDP-1
[500.794372] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[500.796662] Subtest bad-hsync-start: SUCCESS (0.295s)
[501.591753] [154/156] (522s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[501.689884] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[501.694326] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[501.781540] [155/156] (522s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-pgflip-blt)
[501.884019] Starting subtest: hdr-2p-scndscrn-shrfb-pgflip-blt
[501.888172] Subtest hdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[501.984212] [156/156] (521s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[502.035206] Starting subtest: single-wait-all-for-submit-signaled
[502.037543] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[502.132488] Closing watchdogs
results: parsing output: 0/ for test: kms_setmode
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: syncobj_wait
results: parsing output: 6/ for test: kms_scaling_modes
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: prime_udl
results: parsing output: 9/ for test: kms_atomic_transition
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_mmap_gtt
results: parsing output: 15/ for test: gem_ringfill
results: parsing output: 16/ for test: kms_plane
results: parsing output: 17/ for test: dumb_buffer
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: prime_mmap
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: api_intel_allocator
results: parsing output: 25/ for test: gem_userptr_blits
results: parsing output: 26/ for test: kms_atomic_transition
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: gem_create
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_linear_blits
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_fence
results: parsing output: 35/ for test: core_auth
results: parsing output: 36/ for test: kms_lease
results: parsing output: 37/ for test: kms_plane_scaling
results: parsing output: 38/ for test: kms_chamelium_hpd
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_ctx_param
results: parsing output: 41/ for test: gem_busy
results: parsing output: 42/ for test: gem_mmap_wc
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_debugfs
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: vgem_basic
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: gem_ctx_shared
results: parsing output: 49/ for test: gem_mmap_wc
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: gem_userptr_blits
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: kms_plane
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_pipe_crc_basic
results: parsing output: 57/ for test: kms_cursor_crc
results: parsing output: 58/ for test: i915_query
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: gem_exec_reloc
results: parsing output: 62/ for test: gem_mmap
results: parsing output: 63/ for test: kms_dsc
results: parsing output: 64/ for test: prime_self_import
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: kms_fbcon_fbt
results: parsing output: 69/ for test: kms_content_protection
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: gem_exec_balancer
results: parsing output: 72/ for test: gem_userptr_blits
results: parsing output: 73/ for test: api_intel_bb
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: gem_exec_balancer
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_exec_whisper
results: parsing output: 86/ for test: gem_mmap_offset
results: parsing output: 87/ for test: gem_softpin
results: parsing output: 88/ for test: gem_exec_reloc
results: parsing output: 89/ for test: i915_drm_fdinfo
results: parsing output: 90/ for test: gem_exec_schedule
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_ctx_persistence
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_pxp
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_color_pipeline
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_prop_blob
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: kms_cursor_legacy
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: syncobj_wait
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: kms_chamelium_color
results: parsing output: 109/ for test: kms_psr2_sf
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: tools_test
results: parsing output: 114/ for test: gem_sync
results: parsing output: 115/ for test: gem_shrink
results: parsing output: 116/ for test: gem_exec_schedule
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: kms_pm_dc
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: gem_exec_schedule
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_ctx_param
results: parsing output: 123/ for test: gen7_exec_parse
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: prime_vgem
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: i915_pm_rpm
results: parsing output: 129/ for test: gem_exec_reloc
results: parsing output: 130/ for test: kms_vblank
results: parsing output: 131/ for test: syncobj_wait
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_lmem_swapping
results: parsing output: 134/ for test: kms_pipe_crc_basic
results: parsing output: 135/ for test: vgem_basic
results: parsing output: 136/ for test: gem_exec_reloc
results: parsing output: 137/ for test: gem_exec_nop
results: parsing output: 138/ for test: kms_tiled_display
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_pm_freq_api
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_addfb_basic
results: parsing output: 144/ for test: gem_exercise_blt
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_cursor_legacy
results: parsing output: 147/ for test: kms_flip_scaled_crc
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_chamelium_edid
results: parsing output: 150/ for test: kms_cursor_crc
results: parsing output: 151/ for test: syncobj_wait
results: parsing output: 152/ for test: kms_invalid_mode
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: syncobj_timeline
Done.