[57.086445] Initializing watchdogs
[57.086491]   /dev/watchdog0
[57.101612] [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.106077] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.108721] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.112205] [001/156] (960s left) kms_vblank (query-idle)
[57.234192] Starting subtest: query-idle
[57.236625] Starting dynamic subtest: pipe-A-eDP-1
[59.043171] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.775s)
[59.056403] Starting dynamic subtest: pipe-D-eDP-1
[61.485843] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.403s)
[61.487809] Subtest query-idle: SUCCESS (4.181s)
[62.318720] [002/156] (955s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[62.445886] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[62.450291] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[62.540196] [003/156] (955s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[62.628229] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[62.643133] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.013s)
[62.713185] [004/156] (955s left) kms_vblank (ts-continuation-dpms-rpm)
[62.813738] Starting subtest: ts-continuation-dpms-rpm
[62.816083] Starting dynamic subtest: pipe-A-eDP-1
[65.336581] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.503s)
[65.350912] Starting dynamic subtest: pipe-D-eDP-1
[67.609533] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.255s)
[67.611819] Subtest ts-continuation-dpms-rpm: SUCCESS (4.761s)
[68.505542] [005/156] (949s left) gem_softpin (32b-excludes-last-page)
[68.575649] Starting subtest: 32b-excludes-last-page
[68.577647] Subtest 32b-excludes-last-page: SUCCESS (0.004s)
[68.709490] [006/156] (949s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[68.812429] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[76.300812] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.472s)
[76.446122] [007/156] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[76.543215] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[76.547874] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[76.637365] [008/156] (941s left) prime_vgem (basic-fence-flip)
[76.737072] Starting subtest: basic-fence-flip
[77.026588] Subtest basic-fence-flip: SUCCESS (0.289s)
[77.129128] [FACT prime_vgem (basic-fence-flip)] new: kernel.kmod_is_loaded.vgem: true
[77.132554] [009/156] (940s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-blt)
[77.228550] Starting subtest: fbcpsrhdr-rgb101010-draw-blt
[77.232655] Subtest fbcpsrhdr-rgb101010-draw-blt: SKIP (0.000s)
[77.316180] [010/156] (940s left) gem_exec_reloc (basic-active)
[77.372318] Subtest basic-active: SKIP (0.000s)
[77.440250] [011/156] (940s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[77.531672] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[77.536265] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[77.655868] [012/156] (940s left) prime_mmap (test_map_unmap)
[77.714120] Starting subtest: test_map_unmap
[77.716411] Starting dynamic subtest: test_map_unmap-smem
[77.745851] Dynamic subtest test_map_unmap-smem: SUCCESS (0.032s)
[77.748252] Subtest test_map_unmap: SUCCESS (0.033s)
[77.844358] [013/156] (940s left) kms_chamelium_color (ctm-max)
[77.928358] Subtest ctm-max: SKIP (0.000s)
[77.971781] [014/156] (940s left) kms_chamelium_color (degamma)
[78.063938] Subtest degamma: SKIP (0.000s)
[78.106124] [015/156] (940s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[78.199606] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[78.201716] Starting dynamic subtest: pipe-A-eDP-1
[78.297970] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[78.307144] Starting dynamic subtest: pipe-B-eDP-1
[79.434221] Dynamic subtest pipe-B-eDP-1: SKIP (1.133s)
[79.439083] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.234s)
[80.630876] [016/156] (937s left) i915_hangman (detector)
[80.694890] Starting subtest: detector
[80.697250] Starting dynamic subtest: rcs0
[91.231347] Dynamic subtest rcs0: SUCCESS (10.528s)
[91.241925] Starting dynamic subtest: bcs0
[103.262629] Dynamic subtest bcs0: SUCCESS (12.026s)
[103.275916] Starting dynamic subtest: vcs0
[114.208824] Dynamic subtest vcs0: SUCCESS (10.943s)
[114.221563] Starting dynamic subtest: vcs1
[126.239208] Dynamic subtest vcs1: SUCCESS (12.028s)
[126.250237] Starting dynamic subtest: vecs0
[138.206248] Dynamic subtest vecs0: SUCCESS (11.965s)
[138.215922] Starting dynamic subtest: ccs0
[148.253951] Dynamic subtest ccs0: SUCCESS (10.045s)
[148.256692] Subtest detector: SUCCESS (67.542s)
[148.333799] [017/156] (869s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[148.433531] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[155.392206] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.962s)
[155.531211] [018/156] (862s left) gem_pwrite (basic-self)
[155.585405] Subtest basic-self: SKIP (0.000s)
[155.645923] [019/156] (862s left) perf (rc6-disable)
[155.769993] Starting subtest: rc6-disable
[155.952244] Subtest rc6-disable: SUCCESS (0.181s)
[155.999709] [020/156] (862s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[156.093504] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[163.774377] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.684s)
[163.891933] [021/156] (854s left) kms_pm_rpm (dpms-non-lpsp)
[165.435484] Starting subtest: dpms-non-lpsp
[165.440336] Subtest dpms-non-lpsp: SKIP (0.001s)
[166.166576] [022/156] (852s left) i915_suspend (basic-s2idle-without-i915)
[166.204088] Starting subtest: basic-s2idle-without-i915
[186.601808] Subtest basic-s2idle-without-i915: SUCCESS (5.807s)
[186.729984] [023/156] (831s left) kms_invalid_mode (int-max-clock)
[186.841605] Starting subtest: int-max-clock
[186.844037] Starting dynamic subtest: pipe-A-eDP-1
[187.147303] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[187.156543] Starting dynamic subtest: pipe-B-eDP-1
[187.158664] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[187.166424] Starting dynamic subtest: pipe-C-eDP-1
[187.168462] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[187.176314] Starting dynamic subtest: pipe-D-eDP-1
[187.178417] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[187.180479] Subtest int-max-clock: SUCCESS (0.300s)
[187.994650] [024/156] (830s left) gem_ctx_persistence (engines-cleanup)
[188.058322] Starting subtest: engines-cleanup
[188.060650] Starting dynamic subtest: rcs0
[188.123144] Dynamic subtest rcs0: SUCCESS (0.063s)
[188.134499] Starting dynamic subtest: bcs0
[188.191757] Dynamic subtest bcs0: SUCCESS (0.066s)
[188.203635] Starting dynamic subtest: vcs0
[188.227201] Dynamic subtest vcs0: SUCCESS (0.033s)
[188.235820] Starting dynamic subtest: vcs1
[188.261215] Dynamic subtest vcs1: SUCCESS (0.032s)
[188.270178] Starting dynamic subtest: vecs0
[188.296205] Dynamic subtest vecs0: SUCCESS (0.033s)
[188.309770] Starting dynamic subtest: ccs0
[188.341391] Dynamic subtest ccs0: SUCCESS (0.043s)
[188.343854] Subtest engines-cleanup: SUCCESS (0.275s)
[188.421807] [025/156] (830s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-pwrite)
[188.522321] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-pwrite
[188.526553] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[188.634141] [026/156] (829s left) api_intel_bb (delta-check)
[188.698027] Starting subtest: delta-check
[188.703247] Subtest delta-check: SUCCESS (0.006s)
[188.829720] [027/156] (829s left) kms_frontbuffer_tracking (hdr-modesetfrombusy)
[188.940919] Starting subtest: hdr-modesetfrombusy
[188.944605] Subtest hdr-modesetfrombusy: SKIP (0.000s)
[189.038726] [028/156] (829s left) gem_ctx_shared (q-smoketest)
[189.101368] Starting subtest: Q-smoketest
[189.103541] Starting dynamic subtest: rcs0
[194.818046] Dynamic subtest rcs0: SUCCESS (5.503s)
[194.828394] Starting dynamic subtest: bcs0
[200.358397] Dynamic subtest bcs0: SUCCESS (5.493s)
[200.369104] Starting dynamic subtest: vcs0
[205.855713] Dynamic subtest vcs0: SUCCESS (5.464s)
[205.870602] Starting dynamic subtest: vcs1
[211.350170] Dynamic subtest vcs1: SUCCESS (5.481s)
[211.361594] Starting dynamic subtest: vecs0
[216.833565] Dynamic subtest vecs0: SUCCESS (5.479s)
[216.844009] Starting dynamic subtest: ccs0
[222.312090] Dynamic subtest ccs0: SUCCESS (5.468s)
[222.314389] Subtest Q-smoketest: SUCCESS (32.893s)
[222.390205] [029/156] (796s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-pgflip-blt)
[222.494716] Starting subtest: psrhdr-1p-primscrn-shrfb-pgflip-blt
[222.498937] Subtest psrhdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[222.597522] [030/156] (796s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[222.653660] Starting subtest: single-wait-for-submit-unsubmitted
[222.761149] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[222.834374] [031/156] (795s left) kms_addfb_basic (master-rmfb)
[222.893916] Starting subtest: master-rmfb
[222.896194] Subtest master-rmfb: SUCCESS (0.001s)
[223.049310] [032/156] (795s left) gem_exec_whisper (basic-fds)
[223.109143] Starting subtest: basic-fds
[230.879520] Subtest basic-fds: SUCCESS (7.761s)
[231.059378] [033/156] (787s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[231.165368] Starting subtest: yf-tiled-64bpp-rotate-0
[231.169822] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[231.269677] [034/156] (787s left) kms_async_flips (invalid-async-flip-atomic)
[231.370346] Starting subtest: invalid-async-flip-atomic
[231.745865] Starting dynamic subtest: pipe-A-eDP-1
[232.561504] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.816s)
[232.918881] Starting dynamic subtest: pipe-B-eDP-1
[233.803386] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.893s)
[234.163798] Starting dynamic subtest: pipe-C-eDP-1
[235.007287] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.851s)
[235.362219] Starting dynamic subtest: pipe-D-eDP-1
[236.140705] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.787s)
[236.143248] Subtest invalid-async-flip-atomic: SUCCESS (4.771s)
[237.297236] [035/156] (781s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[237.364522] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[237.366917] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[237.454307] [036/156] (781s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[237.559313] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[244.893651] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.336s)
[245.023003] [037/156] (774s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[245.118772] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[245.121120] Starting dynamic subtest: pipe-A-eDP-1
[245.143087] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[245.155562] Starting dynamic subtest: pipe-B-eDP-1
[245.175996] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[245.185197] Starting dynamic subtest: pipe-C-eDP-1
[245.206503] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[245.215558] Starting dynamic subtest: pipe-D-eDP-1
[245.238523] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[245.242916] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[247.388578] [038/156] (771s left) gem_userptr_blits (unsync-unmap-cycles)
[247.451110] Subtest unsync-unmap-cycles: SKIP (0.000s)
[247.575960] [039/156] (771s left) kms_lease (lease-invalid-connector)
[247.666178] Starting subtest: lease-invalid-connector
[247.668465] Starting dynamic subtest: pipe-A-eDP-1
[247.670804] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[247.679975] Starting dynamic subtest: pipe-B-eDP-1
[247.682372] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[247.691519] Starting dynamic subtest: pipe-C-eDP-1
[247.693902] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[247.702869] Starting dynamic subtest: pipe-D-eDP-1
[247.705185] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[247.707406] Subtest lease-invalid-connector: SUCCESS (0.004s)
[247.744571] [040/156] (771s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[247.844633] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[247.848808] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[247.953601] [041/156] (771s left) kms_psr (psr2-primary-render)
[248.050826] Starting subtest: psr2-primary-render
[248.053121] Starting dynamic subtest: eDP-1
[249.792749] Dynamic subtest eDP-1: SUCCESS (1.741s)
[249.795164] Subtest psr2-primary-render: SUCCESS (1.742s)
[250.668876] [042/156] (768s left) gen9_exec_parse (bb-large)
[250.722958] Subtest bb-large: SKIP (0.000s)
[250.800098] [043/156] (768s left) gen7_exec_parse (basic-allocation)
[250.849804] Subtest basic-allocation: SKIP (0.000s)
[250.929764] [044/156] (768s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[251.028642] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[251.030986] Starting dynamic subtest: pipe-A
[251.256017] Dynamic subtest pipe-A: SUCCESS (0.229s)
[251.266602] Starting dynamic subtest: pipe-B
[252.531160] Dynamic subtest pipe-B: SUCCESS (1.274s)
[252.541403] Starting dynamic subtest: pipe-C
[253.871272] Dynamic subtest pipe-C: SUCCESS (1.339s)
[253.883201] Starting dynamic subtest: pipe-D
[255.207687] Dynamic subtest pipe-D: SUCCESS (1.335s)
[255.210033] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.180s)
[256.382997] [045/156] (763s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-render)
[256.483669] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-render
[256.488291] Subtest hdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[256.574554] [046/156] (762s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[256.679507] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc
[256.683454] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[256.782053] [047/156] (762s left) perf_pmu (interrupts)
[256.821789] Starting subtest: interrupts
[257.926826] Subtest interrupts: SUCCESS (1.092s)
[258.033553] [048/156] (761s left) gem_flink_race (flink_close)
[258.065605] Starting subtest: flink_close
[263.173765] Subtest flink_close: SUCCESS (5.093s)
[263.256632] [049/156] (756s left) gem_mmap_gtt (basic)
[263.314059] Subtest basic: SKIP (0.000s)
[263.381992] [050/156] (756s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt)
[263.486517] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt
[263.491001] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[263.590922] [051/156] (756s left) kms_psr (fbc-pr-sprite-blt)
[263.679958] Starting subtest: fbc-pr-sprite-blt
[263.684354] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[263.753090] [052/156] (755s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[263.872746] Starting subtest: cursorA-vs-flipB-toggle
[263.877745] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[264.011625] [053/156] (755s left) kms_cursor_crc (cursor-size-hints)
[264.151450] Starting subtest: cursor-size-hints
[264.153294] Starting dynamic subtest: pipe-A-eDP-1
[264.757499] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.605s)
[264.767995] Starting dynamic subtest: pipe-D-eDP-1
[266.042873] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.285s)
[266.045350] Subtest cursor-size-hints: SUCCESS (1.894s)
[266.862587] [054/156] (752s left) gem_softpin (evict-snoop)
[266.918656] Starting subtest: evict-snoop
[266.923223] Subtest evict-snoop: SKIP (0.000s)
[267.032922] [055/156] (752s left) kms_content_protection (lic-type-0-hdcp14)
[267.173374] Starting subtest: lic-type-0-hdcp14
[267.177924] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[267.298174] [056/156] (752s left) kms_flip (2x-nonexisting-fb)
[267.390860] Starting subtest: 2x-nonexisting-fb
[267.402008] Subtest 2x-nonexisting-fb: SKIP (0.010s)
[267.493057] [057/156] (752s left) kms_plane_multiple (tiling-x)
[267.594669] Starting subtest: tiling-x
[267.597022] Starting dynamic subtest: pipe-A-eDP-1
[271.433336] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.834s)
[271.444788] Starting dynamic subtest: pipe-B-eDP-1
[275.981495] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.548s)
[275.992971] Starting dynamic subtest: pipe-C-eDP-1
[280.415980] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.432s)
[280.426909] Starting dynamic subtest: pipe-D-eDP-1
[284.845913] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.428s)
[284.848689] Subtest tiling-x: SUCCESS (17.246s)
[285.659528] [058/156] (734s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-onoff)
[285.757693] Starting subtest: hdr-2p-primscrn-spr-indfb-onoff
[285.762125] Subtest hdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[285.837697] [059/156] (734s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[285.888011] Starting subtest: x-tiled-to-vebox-y-tiled
[285.892495] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[286.071556] [060/156] (733s left) gem_fenced_exec_thrash (too-many-fences)
[286.117965] Subtest too-many-fences: SKIP (0.000s)
[286.186162] [061/156] (733s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-plflip-blt)
[286.275796] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-plflip-blt
[286.279882] Subtest fbcpsrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[286.361906] [062/156] (733s left) syncobj_timeline (invalid-signal-illegal-handle)
[286.408579] Starting subtest: invalid-signal-illegal-handle
[286.410928] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[286.518473] [063/156] (733s left) kms_pm_rpm (modeset-pc8-residency-stress)
[288.028335] Starting subtest: modeset-pc8-residency-stress
[288.036463] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[288.791952] [064/156] (731s left) kms_cursor_legacy (single-bo)
[288.908705] Starting subtest: single-bo
[288.910915] Starting dynamic subtest: pipe-A
[294.344708] Dynamic subtest pipe-A: SUCCESS (5.380s)
[294.357833] Starting dynamic subtest: pipe-B
[299.724371] Dynamic subtest pipe-B: SUCCESS (5.380s)
[299.735692] Starting dynamic subtest: pipe-C
[305.106565] Dynamic subtest pipe-C: SUCCESS (5.379s)
[305.118767] Starting dynamic subtest: pipe-D
[310.487453] Dynamic subtest pipe-D: SUCCESS (5.379s)
[310.499155] Starting dynamic subtest: all-pipes
[315.876546] Dynamic subtest all-pipes: SUCCESS (5.386s)
[315.878373] Subtest single-bo: SUCCESS (26.918s)
[315.983282] [065/156] (704s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[316.080119] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[316.082446] Starting dynamic subtest: pipe-A-eDP-1
[316.103347] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[316.115375] Starting dynamic subtest: pipe-B-eDP-1
[316.134753] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[316.143931] Starting dynamic subtest: pipe-C-eDP-1
[316.163297] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[316.172211] Starting dynamic subtest: pipe-D-eDP-1
[316.191869] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[316.193781] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[318.331572] [066/156] (701s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[318.438924] Starting subtest: x-tiled-8bpp-rotate-90
[318.459631] Subtest x-tiled-8bpp-rotate-90: SKIP (0.020s)
[318.583867] [067/156] (701s left) syncobj_eventfd (invalid-illegal-handle)
[318.627996] Starting subtest: invalid-illegal-handle
[318.630188] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[318.719996] [068/156] (701s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[318.818457] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[319.774581] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.956s)
[319.900711] [069/156] (700s left) gem_compute (compute-square)
[319.962683] Starting subtest: compute-square
[319.967174] Subtest compute-square: SUCCESS (0.007s)
[320.099603] [070/156] (700s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[320.203820] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[320.207254] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[320.305845] [071/156] (700s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[320.393798] Starting subtest: pr-overlay-plane-move-continuous-sf
[320.397370] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[320.467120] [072/156] (700s left) kms_psr (pr-no-drrs)
[320.551288] Starting subtest: pr-no-drrs
[320.554898] Subtest pr-no-drrs: SKIP (0.000s)
[320.621390] [073/156] (699s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-pwrite)
[320.715801] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-pwrite
[320.719128] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[320.824570] [074/156] (699s left) kms_chamelium_hpd (hdmi-hpd)
[321.211169] Subtest hdmi-hpd: SKIP (0.000s)
[322.053612] [075/156] (698s left) syncobj_wait (invalid-reset-bad-pad)
[322.105876] Starting subtest: invalid-reset-bad-pad
[322.107946] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[322.204764] [076/156] (698s left) gem_mmap_gtt (basic-write-read-distinct)
[322.256430] Subtest basic-write-read-distinct: SKIP (0.000s)
[322.315812] [077/156] (698s left) kms_flip (basic-plain-flip)
[322.416665] Starting subtest: basic-plain-flip
[322.418940] Starting dynamic subtest: A-eDP1
[324.066181] Dynamic subtest A-eDP1: SUCCESS (1.648s)
[324.076836] Starting dynamic subtest: B-eDP1
[325.697963] Dynamic subtest B-eDP1: SUCCESS (1.630s)
[325.710240] Starting dynamic subtest: C-eDP1
[327.327275] Dynamic subtest C-eDP1: SUCCESS (1.627s)
[327.337462] Starting dynamic subtest: D-eDP1
[328.956045] Dynamic subtest D-eDP1: SUCCESS (1.626s)
[328.961217] Subtest basic-plain-flip: SUCCESS (6.545s)
[330.123945] [078/156] (690s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[330.222803] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[330.227175] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[330.346487] [079/156] (690s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[330.441797] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[330.446180] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[330.528421] [080/156] (690s left) gem_exec_params (invalid-ring)
[330.594650] Starting subtest: invalid-ring
[330.596885] Subtest invalid-ring: SUCCESS (0.000s)
[330.721020] [081/156] (690s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-pgflip-blt)
[330.830933] Starting subtest: psrhdr-2p-scndscrn-shrfb-pgflip-blt
[330.834845] Subtest psrhdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[330.915847] [082/156] (690s left) gem_mmap_offset (partial-remap)
[330.968450] Starting subtest: partial-remap
[330.970464] Starting dynamic subtest: smem0
[330.979712] Dynamic subtest smem0: SUCCESS (0.004s)
[330.982084] Subtest partial-remap: SUCCESS (0.004s)
[331.112646] [083/156] (689s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[331.212727] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[340.756547] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.544s)
[340.894530] [084/156] (680s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[340.987634] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[340.999369] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[341.079550] [085/156] (680s left) kms_draw_crc (draw-method-mmap-wc)
[341.164860] Starting subtest: draw-method-mmap-wc
[341.166790] Starting dynamic subtest: xrgb8888-untiled
[341.407330] Dynamic subtest xrgb8888-untiled: SUCCESS (0.244s)
[341.417903] Starting dynamic subtest: rgb565-untiled
[341.629594] Dynamic subtest rgb565-untiled: SUCCESS (0.220s)
[341.640122] Starting dynamic subtest: xrgb2101010-untiled
[341.851704] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[341.863678] Starting dynamic subtest: xbgr16161616f-untiled
[342.062557] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[342.072523] Starting dynamic subtest: xrgb8888-xtiled
[342.195619] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[342.205588] Starting dynamic subtest: rgb565-xtiled
[342.329310] Dynamic subtest rgb565-xtiled: SUCCESS (0.132s)
[342.339018] Starting dynamic subtest: xrgb2101010-xtiled
[342.452571] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[342.463097] Starting dynamic subtest: xbgr16161616f-xtiled
[342.573771] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s)
[342.583244] Starting dynamic subtest: xrgb8888-4tiled
[342.718222] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[342.730007] Starting dynamic subtest: rgb565-4tiled
[342.852268] Dynamic subtest rgb565-4tiled: SUCCESS (0.133s)
[342.863442] Starting dynamic subtest: xrgb2101010-4tiled
[343.007246] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.154s)
[343.017101] Starting dynamic subtest: xbgr16161616f-4tiled
[343.185244] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[343.187332] Subtest draw-method-mmap-wc: SUCCESS (2.023s)
[343.294219] [086/156] (677s left) gem_userptr_blits (stress-mm)
[343.363421] Starting subtest: stress-mm
[348.785536] Subtest stress-mm: SUCCESS (5.369s)
[348.865563] [087/156] (672s left) sw_sync (alloc_merge_fence)
[348.905720] Starting subtest: alloc_merge_fence
[348.908072] Subtest alloc_merge_fence: SUCCESS (0.000s)
[348.966034] [088/156] (672s left) gem_workarounds (reset-fd)
[349.027664] Starting subtest: reset-fd
[349.218959] Subtest reset-fd: SUCCESS (0.194s)
[349.338702] [089/156] (671s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[349.435467] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[349.437775] Starting dynamic subtest: pipe-A-default-mode
[350.597900] Dynamic subtest pipe-A-default-mode: SKIP (1.160s)
[350.602409] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.161s)
[350.734420] [090/156] (670s left) gem_exec_balancer (full-pulse)
[350.796400] Starting subtest: full-pulse
[355.785936] Subtest full-pulse: SUCCESS (4.988s)
[356.102411] [091/156] (665s left) kms_properties (get_properties-sanity-non-atomic)
[356.199821] Starting subtest: get_properties-sanity-non-atomic
[356.201809] Subtest get_properties-sanity-non-atomic: SUCCESS (0.001s)
[356.296650] [092/156] (665s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-rte)
[356.402165] Starting subtest: fbcpsrhdr-2p-rte
[356.406752] Subtest fbcpsrhdr-2p-rte: SKIP (0.000s)
[356.497095] [093/156] (664s left) dmabuf_sync_file (import-multiple-read-write)
[356.537257] Starting subtest: import-multiple-read-write
[356.539632] Subtest import-multiple-read-write: SUCCESS (0.003s)
[356.598548] [094/156] (664s left) kms_atomic_interruptible (atomic-setmode)
[356.687009] Starting subtest: atomic-setmode
[356.689366] Starting dynamic subtest: pipe-A-eDP-1
[363.590957] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.899s)
[363.593311] Subtest atomic-setmode: SUCCESS (6.901s)
[364.394765] [095/156] (657s left) gem_mmap_gtt (basic-wc)
[364.453672] Subtest basic-wc: SKIP (0.000s)
[364.516080] [096/156] (657s left) kms_plane (pixel-format-x-tiled-modifier)
[364.613886] Starting subtest: pixel-format-x-tiled-modifier
[364.639722] Starting dynamic subtest: pipe-A-plane-0
[365.029458] Dynamic subtest pipe-A-plane-0: SUCCESS (0.389s)
[365.040865] Starting dynamic subtest: pipe-A-plane-3
[365.351491] Dynamic subtest pipe-A-plane-3: SUCCESS (0.321s)
[365.361292] Starting dynamic subtest: pipe-A-plane-5
[365.365196] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[366.477576] Starting dynamic subtest: pipe-B-plane-0
[366.880046] Dynamic subtest pipe-B-plane-0: SUCCESS (0.411s)
[366.889958] Starting dynamic subtest: pipe-B-plane-3
[367.202051] Dynamic subtest pipe-B-plane-3: SUCCESS (0.321s)
[367.212964] Starting dynamic subtest: pipe-B-plane-5
[367.217551] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[367.511809] Subtest pixel-format-x-tiled-modifier: SUCCESS (2.897s)
[368.345395] [097/156] (653s left) gem_exec_create (forked)
[368.410669] Starting subtest: forked
[368.413091] Starting dynamic subtest: smem
[389.887274] Dynamic subtest smem: SUCCESS (21.467s)
[389.890297] Subtest forked: SUCCESS (21.469s)
[390.075321] [098/156] (631s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[390.176911] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[390.181665] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[391.298935] [099/156] (630s left) api_intel_bb (object-noreloc-purge-cache-simple)
[391.353378] Starting subtest: object-noreloc-purge-cache-simple
[391.356866] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.005s)
[391.481292] [100/156] (630s left) vgem_basic (bad-pad)
[391.520381] Starting subtest: bad-pad
[391.522276] Subtest bad-pad: SUCCESS (0.000s)
[391.590344] [101/156] (630s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[391.695069] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[399.031233] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.340s)
[399.210686] [102/156] (622s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-pgflip-blt)
[399.309619] Starting subtest: hdr-1p-primscrn-shrfb-pgflip-blt
[399.313887] Subtest hdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[399.431061] [103/156] (622s left) gem_render_tiled_blits (basic)
[399.481219] Subtest basic: SKIP (0.000s)
[399.547215] [104/156] (622s left) gem_close_race (basic-threads)
[399.598308] Starting subtest: basic-threads
[400.974116] Subtest basic-threads: SUCCESS (1.375s)
[401.177876] [105/156] (620s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[401.278550] Starting subtest: y-tiled-16bpp-rotate-0
[401.283002] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[401.373561] [106/156] (620s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[401.474003] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[401.476331] Starting dynamic subtest: pipe-A
[401.536407] Dynamic subtest pipe-A: SUCCESS (0.065s)
[401.548918] Starting dynamic subtest: pipe-B
[402.747448] Dynamic subtest pipe-B: SUCCESS (1.210s)
[402.762494] Starting dynamic subtest: pipe-C
[403.861624] Dynamic subtest pipe-C: SUCCESS (1.112s)
[403.876330] Starting dynamic subtest: pipe-D
[405.010936] Dynamic subtest pipe-D: SUCCESS (1.147s)
[405.013633] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.539s)
[406.165221] [107/156] (615s left) kms_getfb (getfb-addfb-different-handles)
[406.260578] Starting subtest: getfb-addfb-different-handles
[406.262850] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[406.362147] [108/156] (615s left) perf_pmu (semaphore-busy)
[406.404272] Starting subtest: semaphore-busy
[406.406091] Starting dynamic subtest: rcs0
[408.053763] Dynamic subtest rcs0: SUCCESS (1.646s)
[408.066700] Starting dynamic subtest: bcs0
[409.684694] Dynamic subtest bcs0: SUCCESS (1.629s)
[409.697127] Starting dynamic subtest: vcs0
[413.422313] Dynamic subtest vcs0: SUCCESS (3.681s)
[413.434833] Starting dynamic subtest: vcs1
[419.435338] Dynamic subtest vcs1: SUCCESS (6.014s)
[419.448381] Starting dynamic subtest: vecs0
[425.400964] Dynamic subtest vecs0: SUCCESS (6.015s)
[425.411538] Starting dynamic subtest: ccs0
[428.473766] Dynamic subtest ccs0: SUCCESS (3.071s)
[428.476464] Subtest semaphore-busy: SUCCESS (22.075s)
[428.591390] [109/156] (593s left) gem_eio (wait-wedge-10ms)
[428.782182] Starting subtest: wait-wedge-10ms
[429.956444] Subtest wait-wedge-10ms: SUCCESS (1.175s)
[430.091636] [110/156] (592s left) kms_cursor_legacy (forked-bo)
[430.204418] Starting subtest: forked-bo
[430.206776] Starting dynamic subtest: pipe-A
[435.718617] Dynamic subtest pipe-A: SUCCESS (5.459s)
[435.732028] Starting dynamic subtest: pipe-B
[441.188589] Dynamic subtest pipe-B: SUCCESS (5.470s)
[441.201824] Starting dynamic subtest: pipe-C
[446.664764] Dynamic subtest pipe-C: SUCCESS (5.472s)
[446.678249] Starting dynamic subtest: pipe-D
[452.135603] Dynamic subtest pipe-D: SUCCESS (5.470s)
[452.152528] Starting dynamic subtest: all-pipes
[457.635786] Dynamic subtest all-pipes: SUCCESS (5.499s)
[457.637894] Subtest forked-bo: SUCCESS (27.381s)
[457.731834] [111/156] (564s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[457.829820] Starting subtest: 4-tiled-8bpp-rotate-180
[459.066313] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.237s)
[459.190540] [112/156] (563s left) gem_partial_pwrite_pread (writes-after-reads)
[459.250518] Subtest writes-after-reads: SKIP (0.000s)
[459.325568] [113/156] (562s left) i915_pm_rpm (reg-read-ioctl)
[461.053825] Starting subtest: reg-read-ioctl
[463.343547] Subtest reg-read-ioctl: SUCCESS (2.296s)
[463.736382] [114/156] (558s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[463.835859] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[463.840256] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[463.929408] [115/156] (558s left) gem_ctx_persistence (legacy-engines-queued)
[464.000535] Starting subtest: legacy-engines-queued
[464.002786] Starting dynamic subtest: render
[464.085692] Dynamic subtest render: SUCCESS (0.087s)
[464.099761] Starting dynamic subtest: bsd1
[465.818551] Dynamic subtest bsd1: SUCCESS (1.728s)
[465.833593] Starting dynamic subtest: bsd2
[467.893819] Dynamic subtest bsd2: SUCCESS (2.044s)
[467.905050] Starting dynamic subtest: blt
[467.930147] Dynamic subtest blt: SUCCESS (0.063s)
[467.940266] Starting dynamic subtest: vebox
[469.850590] Dynamic subtest vebox: SUCCESS (1.917s)
[469.853567] Subtest legacy-engines-queued: SUCCESS (5.853s)
[469.945605] [116/156] (552s left) kms_psr (fbc-psr2-primary-blt)
[470.056824] Starting subtest: fbc-psr2-primary-blt
[470.059128] Starting dynamic subtest: eDP-1
[470.063454] Dynamic subtest eDP-1: SKIP (0.000s)
[470.067968] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[470.144150] [117/156] (552s left) syncobj_timeline (signal-array)
[470.193521] Starting subtest: signal-array
[470.195773] Subtest signal-array: SUCCESS (0.000s)
[470.309197] [118/156] (552s left) kms_plane_alpha_blend (alpha-7efc)
[470.403066] Starting subtest: alpha-7efc
[470.690506] Starting dynamic subtest: pipe-A-eDP-1
[471.296034] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.603s)
[471.325971] Starting dynamic subtest: pipe-D-eDP-1
[472.629462] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.313s)
[472.631944] Subtest alpha-7efc: SUCCESS (2.225s)
[473.487254] [119/156] (549s left) kms_getfb (getfb-handle-valid)
[473.579208] Starting subtest: getfb-handle-valid
[473.581699] Subtest getfb-handle-valid: SUCCESS (0.000s)
[473.688612] [120/156] (548s left) kms_frontbuffer_tracking (basic)
[473.797095] Starting subtest: basic
[477.126057] Subtest basic: SUCCESS (3.334s)
[477.257056] [121/156] (545s left) gem_exec_reloc (basic-gtt-cpu)
[477.321872] Subtest basic-gtt-cpu: SKIP (0.000s)
[477.408518] [122/156] (545s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[477.522334] Starting subtest: basic-busy-flip-before-cursor-atomic
[477.526433] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[477.669911] [123/156] (545s left) gem_exec_capture (capture-invisible)
[477.730087] Starting subtest: capture-invisible
[477.732045] Starting dynamic subtest: smem0
[477.735480] Dynamic subtest smem0: SKIP (0.000s)
[477.738897] Subtest capture-invisible: SKIP (0.001s)
[477.832068] [124/156] (544s left) gem_userptr_blits (sync-unmap)
[477.909095] Starting subtest: sync-unmap
[477.924431] Subtest sync-unmap: SUCCESS (0.014s)
[478.079611] [125/156] (544s left) perf_pmu (busy-idle)
[478.124992] Starting subtest: busy-idle
[478.126882] Starting dynamic subtest: rcs0
[478.816049] Dynamic subtest rcs0: SUCCESS (0.689s)
[478.830281] Starting dynamic subtest: bcs0
[479.474792] Dynamic subtest bcs0: SUCCESS (0.656s)
[479.488718] Starting dynamic subtest: vcs0
[480.893026] Dynamic subtest vcs0: SUCCESS (1.415s)
[480.904927] Starting dynamic subtest: vcs1
[482.887344] Dynamic subtest vcs1: SUCCESS (1.981s)
[482.898781] Starting dynamic subtest: vecs0
[484.860382] Dynamic subtest vecs0: SUCCESS (1.981s)
[484.872160] Starting dynamic subtest: ccs0
[485.537464] Dynamic subtest ccs0: SUCCESS (0.675s)
[485.540278] Subtest busy-idle: SUCCESS (7.412s)
[485.644490] [126/156] (537s left) drm_read (empty-nonblock)
[485.830552] Starting subtest: empty-nonblock
[485.832426] Subtest empty-nonblock: SUCCESS (0.000s)
[485.954239] [127/156] (536s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[486.059640] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[486.063803] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[486.146086] [128/156] (536s left) gem_eio (in-flight-internal-1us)
[486.346011] Starting subtest: in-flight-internal-1us
[487.928703] Subtest in-flight-internal-1us: SUCCESS (1.582s)
[488.083650] [129/156] (534s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[488.182724] Starting subtest: fbc-psr-sprite-mmap-cpu
[488.184957] Starting dynamic subtest: eDP-1
[488.188885] Dynamic subtest eDP-1: SKIP (0.000s)
[488.192862] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[488.267065] [130/156] (534s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-msflip-blt)
[488.367109] Starting subtest: psrhdr-2p-primscrn-shrfb-msflip-blt
[488.371363] Subtest psrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[488.468041] [131/156] (534s left) gem_exec_schedule (independent)
[488.550020] Starting subtest: independent
[488.552224] Starting dynamic subtest: rcs0
[489.917050] Dynamic subtest rcs0: SUCCESS (1.375s)
[489.930757] Starting dynamic subtest: vcs0
[491.924579] Dynamic subtest vcs0: SUCCESS (2.005s)
[491.938135] Starting dynamic subtest: vcs1
[493.890498] Dynamic subtest vcs1: SUCCESS (1.964s)
[493.906076] Starting dynamic subtest: vecs0
[495.879106] Dynamic subtest vecs0: SUCCESS (1.986s)
[495.891607] Starting dynamic subtest: ccs0
[497.870187] Dynamic subtest ccs0: SUCCESS (1.970s)
[497.874926] Subtest independent: SUCCESS (9.311s)
[498.154059] [132/156] (524s left) kms_getfb (getfb2-into-addfb2)
[498.249855] Starting subtest: getfb2-into-addfb2
[498.252225] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[498.354683] [133/156] (524s left) i915_drm_fdinfo (all-busy-check-all)
[498.395753] Starting subtest: all-busy-check-all
[498.399895] Subtest all-busy-check-all: SKIP (0.005s)
[498.474955] [134/156] (524s left) gem_bad_reloc (negative-reloc-lut)
[498.545814] Subtest negative-reloc-lut: SKIP (0.000s)
[498.610605] [135/156] (524s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[498.712020] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[498.716325] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[498.783928] [136/156] (524s left) gem_lmem_swapping (parallel-random)
[498.826190] Subtest parallel-random: SKIP (0.000s)
[498.876894] [137/156] (524s left) gem_partial_pwrite_pread (reads-snoop)
[498.933826] Subtest reads-snoop: SKIP (0.000s)
[499.024257] [138/156] (524s left) kms_scaling_modes (scaling-mode-center)
[499.113176] Starting subtest: scaling-mode-center
[499.115077] Starting dynamic subtest: pipe-A-eDP-1
[499.208021] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.094s)
[499.218467] Starting dynamic subtest: pipe-B-eDP-1
[500.417785] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.209s)
[500.433101] Starting dynamic subtest: pipe-C-eDP-1
[501.551857] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.131s)
[501.562844] Starting dynamic subtest: pipe-D-eDP-1
[502.680105] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[502.682493] Subtest scaling-mode-center: SUCCESS (3.568s)
[503.846343] [139/156] (519s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[503.938878] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[503.942862] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[504.035274] [140/156] (519s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[504.123820] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[504.126165] Starting dynamic subtest: pipe-A-eDP-1
[504.605352] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.454s)
[504.617647] Starting dynamic subtest: pipe-B-eDP-1
[506.085502] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.479s)
[506.099398] Starting dynamic subtest: pipe-C-eDP-1
[507.440662] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.353s)
[507.451684] Starting dynamic subtest: pipe-D-eDP-1
[508.806995] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.365s)
[508.809011] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.655s)
[509.958980] [141/156] (513s left) gem_mmap_gtt (medium-copy-odd)
[510.017849] Subtest medium-copy-odd: SKIP (0.000s)
[510.077404] [142/156] (513s left) gem_softpin (allocator-fork)
[510.151426] Starting subtest: allocator-fork
[512.408660] Subtest allocator-fork: SUCCESS (2.205s)
[512.500192] [143/156] (511s left) kms_cursor_crc (cursor-offscreen-512x170)
[512.810926] Starting subtest: cursor-offscreen-512x170
[512.819629] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[512.952876] [144/156] (510s left) prime_vgem (busy)
[513.030790] Starting subtest: busy
[513.033016] Starting dynamic subtest: rcs0
[513.106909] Dynamic subtest rcs0: SUCCESS (0.076s)
[513.117440] Starting dynamic subtest: bcs0
[513.182125] Dynamic subtest bcs0: SUCCESS (0.074s)
[513.192526] Starting dynamic subtest: vcs0
[513.226662] Dynamic subtest vcs0: SUCCESS (0.044s)
[513.237566] Starting dynamic subtest: vcs1
[513.270109] Dynamic subtest vcs1: SUCCESS (0.043s)
[513.280882] Starting dynamic subtest: vecs0
[513.315995] Dynamic subtest vecs0: SUCCESS (0.045s)
[513.326918] Starting dynamic subtest: ccs0
[513.365457] Dynamic subtest ccs0: SUCCESS (0.048s)
[513.367318] Subtest busy: SUCCESS (0.335s)
[513.506641] [145/156] (510s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-pwrite)
[513.601597] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-pwrite
[513.605262] Subtest fbchdr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[513.694816] [146/156] (509s left) gem_exec_fence (syncobj-import)
[514.102684] Starting subtest: syncobj-import
[514.108158] Subtest syncobj-import: SUCCESS (0.005s)
[515.958063] [147/156] (507s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[516.051421] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[516.741968] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.692s)
[516.911585] [148/156] (506s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[517.000609] Starting subtest: plane-lut1d-post-ctm3x4
[517.124533] Starting dynamic subtest: pipe-A-plane-0
[517.302536] Dynamic subtest pipe-A-plane-0: SUCCESS (0.178s)
[517.313387] Starting dynamic subtest: pipe-A-plane-1
[517.457522] Dynamic subtest pipe-A-plane-1: SUCCESS (0.154s)
[517.468772] Starting dynamic subtest: pipe-A-plane-2
[517.646450] Dynamic subtest pipe-A-plane-2: SUCCESS (0.188s)
[518.842381] Starting dynamic subtest: pipe-B-plane-0
[519.010781] Dynamic subtest pipe-B-plane-0: SUCCESS (0.177s)
[519.022509] Starting dynamic subtest: pipe-B-plane-1
[519.166006] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[519.176938] Starting dynamic subtest: pipe-B-plane-2
[519.321830] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[520.572310] Starting dynamic subtest: pipe-C-plane-0
[520.707202] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[520.722902] Starting dynamic subtest: pipe-C-plane-1
[520.873832] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[520.886503] Starting dynamic subtest: pipe-C-plane-2
[521.018752] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[522.234715] Starting dynamic subtest: pipe-D-plane-0
[522.378752] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[522.390527] Starting dynamic subtest: pipe-D-plane-1
[522.535678] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[522.546976] Starting dynamic subtest: pipe-D-plane-2
[522.667623] Dynamic subtest pipe-D-plane-2: SUCCESS (0.132s)
[522.955818] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.953s)
[523.801692] [149/156] (500s left) gem_exec_schedule (smoketest)
[523.990603] Starting subtest: smoketest
[523.994065] Starting dynamic subtest: rcs0
[529.594772] Dynamic subtest rcs0: SUCCESS (5.501s)
[529.607658] Starting dynamic subtest: bcs0
[535.108654] Dynamic subtest bcs0: SUCCESS (5.512s)
[535.120602] Starting dynamic subtest: vcs0
[540.600777] Dynamic subtest vcs0: SUCCESS (5.488s)
[540.614511] Starting dynamic subtest: vcs1
[546.103150] Dynamic subtest vcs1: SUCCESS (5.499s)
[546.116382] Starting dynamic subtest: vecs0
[551.615379] Dynamic subtest vecs0: SUCCESS (5.512s)
[551.628844] Starting dynamic subtest: ccs0
[557.151468] Dynamic subtest ccs0: SUCCESS (5.530s)
[557.154068] Subtest smoketest: SUCCESS (33.049s)
[557.240725] [150/156] (466s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[557.342417] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[557.346818] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[557.443074] [151/156] (466s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-render)
[557.537808] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-render
[557.542279] Subtest hdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[557.636939] [152/156] (466s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[557.738887] Starting subtest: primary-yf-tiled-reflect-x-270
[557.742942] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[557.842945] [153/156] (466s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[557.953547] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[557.957818] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[558.078846] [154/156] (465s left) gem_mmap_gtt (bad-object)
[558.136644] Subtest bad-object: SKIP (0.000s)
[558.211541] [155/156] (465s left) syncobj_timeline (wait-all-for-submit-complex)
[558.258188] Starting subtest: wait-all-for-submit-complex
[558.472777] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[558.560953] [156/156] (465s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[558.666017] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite
[558.670059] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[558.745880] Closing watchdogs
results: parsing output: 0/ for test: kms_vblank
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_flip
results: parsing output: 3/ for test: kms_vblank
results: parsing output: 4/ for test: gem_softpin
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: prime_vgem
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: prime_mmap
results: parsing output: 12/ for test: kms_chamelium_color
results: parsing output: 13/ for test: kms_chamelium_color
results: parsing output: 14/ for test: kms_psr2_sf
results: parsing output: 15/ for test: i915_hangman
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_pwrite
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_pm_rpm
results: parsing output: 21/ for test: i915_suspend
results: parsing output: 22/ for test: kms_invalid_mode
results: parsing output: 23/ for test: gem_ctx_persistence
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: api_intel_bb
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_ctx_shared
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: kms_addfb_basic
results: parsing output: 31/ for test: gem_exec_whisper
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: kms_async_flips
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: gem_userptr_blits
results: parsing output: 38/ for test: kms_lease
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: gen9_exec_parse
results: parsing output: 42/ for test: gen7_exec_parse
results: parsing output: 43/ for test: kms_plane_scaling
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: perf_pmu
results: parsing output: 47/ for test: gem_flink_race
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_cursor_legacy
results: parsing output: 52/ for test: kms_cursor_crc
results: parsing output: 53/ for test: gem_softpin
results: parsing output: 54/ for test: kms_content_protection
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: kms_plane_multiple
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_render_copy
results: parsing output: 59/ for test: gem_fenced_exec_thrash
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_pm_rpm
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: syncobj_eventfd
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: gem_compute
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_chamelium_hpd
results: parsing output: 74/ for test: syncobj_wait
results: parsing output: 75/ for test: gem_mmap_gtt
results: parsing output: 76/ for test: kms_flip
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_params
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_mmap_offset
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_draw_crc
results: parsing output: 85/ for test: gem_userptr_blits
results: parsing output: 86/ for test: sw_sync
results: parsing output: 87/ for test: gem_workarounds
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: gem_exec_balancer
results: parsing output: 90/ for test: kms_properties
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: dmabuf_sync_file
results: parsing output: 93/ for test: kms_atomic_interruptible
results: parsing output: 94/ for test: gem_mmap_gtt
results: parsing output: 95/ for test: kms_plane
results: parsing output: 96/ for test: gem_exec_create
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: api_intel_bb
results: parsing output: 99/ for test: vgem_basic
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_render_tiled_blits
results: parsing output: 103/ for test: gem_close_race
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_getfb
results: parsing output: 107/ for test: perf_pmu
results: parsing output: 108/ for test: gem_eio
results: parsing output: 109/ for test: kms_cursor_legacy
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: gem_partial_pwrite_pread
results: parsing output: 112/ for test: i915_pm_rpm
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gem_ctx_persistence
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_plane_alpha_blend
results: parsing output: 118/ for test: kms_getfb
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: gem_exec_capture
results: parsing output: 123/ for test: gem_userptr_blits
results: parsing output: 124/ for test: perf_pmu
results: parsing output: 125/ for test: drm_read
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_eio
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_exec_schedule
results: parsing output: 131/ for test: kms_getfb
results: parsing output: 132/ for test: i915_drm_fdinfo
results: parsing output: 133/ for test: gem_bad_reloc
results: parsing output: 134/ for test: kms_flip_scaled_crc
results: parsing output: 135/ for test: gem_lmem_swapping
results: parsing output: 136/ for test: gem_partial_pwrite_pread
results: parsing output: 137/ for test: kms_scaling_modes
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_ccs
results: parsing output: 140/ for test: gem_mmap_gtt
results: parsing output: 141/ for test: gem_softpin
results: parsing output: 142/ for test: kms_cursor_crc
results: parsing output: 143/ for test: prime_vgem
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: gem_exec_fence
results: parsing output: 146/ for test: kms_big_fb
results: parsing output: 147/ for test: kms_color_pipeline
results: parsing output: 148/ for test: gem_exec_schedule
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_rotation_crc
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: gem_mmap_gtt
results: parsing output: 154/ for test: syncobj_timeline
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.