[57.042364] Initializing watchdogs
[57.042419]   /dev/watchdog0
[57.057845] [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.062583] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.064995] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.068363] [001/133] (960s left) gem_exec_fence (basic-await)
[57.288045] Starting subtest: basic-await
[57.290798] Starting dynamic subtest: rcs0
[57.369797] Dynamic subtest rcs0: SUCCESS (0.081s)
[57.381151] Starting dynamic subtest: bcs0
[57.444718] Dynamic subtest bcs0: SUCCESS (0.073s)
[57.455569] Starting dynamic subtest: vcs0
[57.518314] Dynamic subtest vcs0: SUCCESS (0.073s)
[57.528139] Starting dynamic subtest: vcs1
[57.595377] Dynamic subtest vcs1: SUCCESS (0.076s)
[57.605911] Starting dynamic subtest: vecs0
[57.671796] Dynamic subtest vecs0: SUCCESS (0.076s)
[57.680987] Starting dynamic subtest: ccs0
[57.750064] Dynamic subtest ccs0: SUCCESS (0.077s)
[57.752710] Subtest basic-await: SUCCESS (0.460s)
[60.020271] [FACT gem_exec_fence (basic-await)] new: kernel.kmod_is_loaded.vgem: true
[60.023274] [002/133] (957s left) kms_big_fb (linear-8bpp-rotate-0)
[60.139939] Starting subtest: linear-8bpp-rotate-0
[61.441902] Subtest linear-8bpp-rotate-0: SUCCESS (1.298s)
[61.545976] [003/133] (956s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[61.644290] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[61.647828] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[61.736160] [004/133] (955s left) gem_exec_params (rel-constants-invalid)
[61.800125] Starting subtest: rel-constants-invalid
[61.802103] Subtest rel-constants-invalid: SUCCESS (0.000s)
[61.898600] [005/133] (955s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[61.986433] Starting subtest: 4-tiled-addfb-size-offset-overflow
[61.988295] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[62.075256] [006/133] (955s left) gem_sync (basic-store-all)
[62.146184] Starting subtest: basic-store-all
[65.186375] Subtest basic-store-all: SUCCESS (3.039s)
[65.371996] [007/133] (952s left) gem_mmap_gtt (fault-concurrent-x)
[65.425164] Subtest fault-concurrent-X: SKIP (0.000s)
[65.482540] [008/133] (952s left) kms_chamelium_color (ctm-blue-to-red)
[65.589418] Subtest ctm-blue-to-red: SKIP (0.000s)
[65.633632] [009/133] (952s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[65.721414] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[65.723803] Starting dynamic subtest: pipe-A-default-mode
[68.511056] Dynamic subtest pipe-A-default-mode: SUCCESS (2.782s)
[68.513354] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.783s)
[69.340541] [010/133] (948s left) kms_hdr (static-toggle)
[69.436917] Starting subtest: static-toggle
[69.443523] Subtest static-toggle: SKIP (0.000s)
[69.524453] [011/133] (948s left) prime_vgem (fence-wait)
[69.604353] Starting subtest: fence-wait
[69.606196] Starting dynamic subtest: rcs0
[70.638505] Dynamic subtest rcs0: SUCCESS (1.031s)
[70.646400] Starting dynamic subtest: bcs0
[71.695991] Dynamic subtest bcs0: SUCCESS (1.056s)
[71.704050] Starting dynamic subtest: vcs0
[72.740358] Dynamic subtest vcs0: SUCCESS (1.042s)
[72.750246] Starting dynamic subtest: vcs1
[73.863813] Dynamic subtest vcs1: SUCCESS (1.122s)
[73.873791] Starting dynamic subtest: vecs0
[74.908813] Dynamic subtest vecs0: SUCCESS (1.043s)
[74.918698] Starting dynamic subtest: ccs0
[75.954683] Dynamic subtest ccs0: SUCCESS (1.044s)
[75.957378] Subtest fence-wait: SUCCESS (6.344s)
[76.081902] [012/133] (941s left) gen7_exec_parse (cmd-crossing-page)
[76.160740] Subtest cmd-crossing-page: SKIP (0.000s)
[76.246577] [013/133] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[76.345384] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[76.349820] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[76.438525] [014/133] (941s left) kms_chamelium_hpd (dp-hpd-storm)
[76.823624] Subtest dp-hpd-storm: SKIP (0.000s)
[77.647255] [015/133] (940s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[77.744377] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[77.746717] Starting dynamic subtest: pipe-A-eDP-1
[78.663425] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.911s)
[78.674161] Starting dynamic subtest: pipe-B-eDP-1
[80.174489] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.508s)
[80.186770] Starting dynamic subtest: pipe-C-eDP-1
[81.724068] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.546s)
[81.733371] Starting dynamic subtest: pipe-D-eDP-1
[83.228995] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.504s)
[83.231372] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.473s)
[84.415742] [016/133] (933s left) kms_content_protection (legacy-hdcp14)
[84.564450] Starting subtest: legacy-hdcp14
[84.568839] Subtest legacy-hdcp14: SKIP (0.000s)
[84.667673] [017/133] (933s left) kms_plane_multiple (tiling-yf)
[84.756171] Starting subtest: tiling-yf
[84.760242] Subtest tiling-yf: SKIP (0.000s)
[84.833393] [018/133] (933s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[84.934278] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[84.938790] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[86.035661] [019/133] (932s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[86.136290] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[93.092270] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.958s)
[93.249843] [020/133] (925s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[93.346380] Starting subtest: invalidate-primary-flip-overlay
[104.075418] Subtest invalidate-primary-flip-overlay: SUCCESS (10.730s)
[104.181171] [021/133] (914s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[104.286371] Starting subtest: y-tiled-32bpp-rotate-180
[104.290035] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[104.381449] [022/133] (914s left) kms_lease (atomic-implicit-crtc)
[104.492072] Starting subtest: atomic-implicit-crtc
[104.493902] Starting dynamic subtest: pipe-A-eDP-1
[104.503260] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[104.511834] Starting dynamic subtest: pipe-B-eDP-1
[104.521290] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[104.530961] Starting dynamic subtest: pipe-C-eDP-1
[104.539428] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[104.546378] Starting dynamic subtest: pipe-D-eDP-1
[104.554770] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[104.556281] Subtest atomic-implicit-crtc: SUCCESS (0.035s)
[104.617051] [023/133] (913s left) kms_cursor_crc (cursor-dpms)
[104.746456] Starting subtest: cursor-dpms
[104.748338] Starting dynamic subtest: pipe-A-eDP-1
[107.823442] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.072s)
[107.831357] Starting dynamic subtest: pipe-D-eDP-1
[111.492723] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.669s)
[111.495086] Subtest cursor-dpms: SUCCESS (6.744s)
[112.338681] [024/133] (906s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[112.396788] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[112.399093] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[112.480113] [025/133] (906s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[112.596231] Starting subtest: x-tiled-32bpp-rotate-270
[112.615677] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[112.731568] [026/133] (905s left) kms_cursor_crc (cursor-tearing-position-change)
[112.840981] Starting subtest: cursor-tearing-position-change
[112.843400] Starting dynamic subtest: pipe-A-eDP-1
[113.414062] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.571s)
[113.424401] Starting dynamic subtest: pipe-D-eDP-1
[114.677140] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.263s)
[114.679504] Subtest cursor-tearing-position-change: SUCCESS (1.836s)
[115.514264] [027/133] (903s left) api_intel_allocator (execbuf-with-allocator)
[115.566056] Starting subtest: execbuf-with-allocator
[115.569066] Subtest execbuf-with-allocator: SUCCESS (0.006s)
[115.681410] [028/133] (902s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[115.774656] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[115.806999] Starting dynamic subtest: pipe-A-plane-0
[116.106386] Dynamic subtest pipe-A-plane-0: SUCCESS (0.299s)
[116.115298] Starting dynamic subtest: pipe-A-plane-3
[116.339758] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[116.348106] Starting dynamic subtest: pipe-A-plane-5
[116.352208] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[117.404505] Starting dynamic subtest: pipe-B-plane-0
[117.708468] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[117.717478] Starting dynamic subtest: pipe-B-plane-3
[117.942051] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[117.949988] Starting dynamic subtest: pipe-B-plane-5
[117.954058] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[118.253372] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.480s)
[119.092907] [029/133] (899s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[119.215350] Starting subtest: cursorB-vs-flipA-varying-size
[119.219904] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[119.392496] [030/133] (899s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[119.492286] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[119.496616] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[119.585811] [031/133] (899s left) gem_exec_reloc (basic-gtt-read-active)
[119.653578] Subtest basic-gtt-read-active: SKIP (0.000s)
[119.719289] [032/133] (899s left) kms_async_flips (crc-atomic)
[119.805075] Starting subtest: crc-atomic
[120.194908] Starting dynamic subtest: pipe-A-eDP-1
[123.095076] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.899s)
[123.470645] Starting dynamic subtest: pipe-B-eDP-1
[126.424390] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.960s)
[126.812189] Starting dynamic subtest: pipe-C-eDP-1
[129.723280] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.916s)
[130.085942] Starting dynamic subtest: pipe-D-eDP-1
[132.966972] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.890s)
[132.969167] Subtest crc-atomic: SUCCESS (13.162s)
[134.131944] [033/133] (884s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[134.229839] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[134.234150] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[134.313720] [034/133] (884s left) api_intel_bb (object-noreloc-purge-cache-simple)
[134.373436] Starting subtest: object-noreloc-purge-cache-simple
[134.378474] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.004s)
[134.497692] [035/133] (884s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[134.739745] Starting subtest: cursor-rapid-movement-256x85
[134.788018] Subtest cursor-rapid-movement-256x85: SKIP (0.042s)
[134.950089] [036/133] (884s left) syncobj_wait (wait-all-snapshot)
[135.007097] Starting subtest: wait-all-snapshot
[135.087506] Subtest wait-all-snapshot: SUCCESS (0.081s)
[135.161518] [037/133] (883s left) gem_eio (kms)
[135.437086] Starting subtest: kms
[156.206916] Subtest kms: SUCCESS (20.768s)
[157.409811] [038/133] (861s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[157.520177] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[157.524634] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[157.649818] [039/133] (861s left) gem_render_copy_redux (interruptible)
[157.701863] Starting subtest: interruptible
[158.070613] Subtest interruptible: SUCCESS (0.369s)
[158.200479] [040/133] (860s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[158.297535] Starting subtest: fbcpsr-slowdraw
[183.533210] Subtest fbcpsr-slowdraw: SUCCESS (25.237s)
[183.655569] [041/133] (835s left) syncobj_basic (bad-handle-to-fd)
[183.713402] Starting subtest: bad-handle-to-fd
[183.715607] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[183.807997] [042/133] (835s left) gem_exec_reloc (basic-gtt-read)
[183.876173] Subtest basic-gtt-read: SKIP (0.000s)
[183.957960] [043/133] (835s left) kms_atomic_transition (plane-all-modeset-transition)
[184.052550] Starting subtest: plane-all-modeset-transition
[184.057064] Subtest plane-all-modeset-transition: SKIP (0.000s)
[184.131869] [044/133] (835s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[184.235624] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[184.239818] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[184.325566] [045/133] (835s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[184.378068] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[184.481847] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s)
[184.558247] [046/133] (834s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[184.674947] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[193.834119] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.157s)
[194.677648] [047/133] (824s left) kms_chamelium_frames (hdmi-crc-multiple)
[195.062108] Subtest hdmi-crc-multiple: SKIP (0.000s)
[195.876303] [048/133] (823s left) kms_sequence (get-idle)
[195.969428] Starting subtest: get-idle
[195.971495] Starting dynamic subtest: pipe-A-eDP-1
[197.784288] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.813s)
[197.793686] Starting dynamic subtest: pipe-B-eDP-1
[200.283656] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.499s)
[200.292525] Starting dynamic subtest: pipe-C-eDP-1
[202.777463] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.493s)
[202.786962] Starting dynamic subtest: pipe-D-eDP-1
[205.210145] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.431s)
[205.212519] Subtest get-idle: SUCCESS (9.242s)
[206.016860] [049/133] (813s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[206.108702] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[206.110948] Starting dynamic subtest: pipe-A-eDP-1
[206.131449] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[206.143267] Starting dynamic subtest: pipe-B-eDP-1
[206.164950] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[206.173729] Starting dynamic subtest: pipe-C-eDP-1
[206.193219] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[206.201070] Starting dynamic subtest: pipe-D-eDP-1
[206.220701] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[206.222920] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SUCCESS (0.007s)
[208.375616] [050/133] (811s left) kms_feature_discovery (display-4x)
[208.468053] Starting subtest: display-4x
[208.472586] Subtest display-4x: SKIP (0.000s)
[208.537864] [051/133] (811s left) kms_cursor_legacy (single-move)
[208.651497] Starting subtest: single-move
[208.653471] Starting dynamic subtest: pipe-A
[214.031999] Dynamic subtest pipe-A: SUCCESS (5.379s)
[214.043470] Starting dynamic subtest: pipe-B
[219.415647] Dynamic subtest pipe-B: SUCCESS (5.380s)
[219.426036] Starting dynamic subtest: pipe-C
[224.798988] Dynamic subtest pipe-C: SUCCESS (5.380s)
[224.809352] Starting dynamic subtest: pipe-D
[230.181277] Dynamic subtest pipe-D: SUCCESS (5.379s)
[230.191944] Starting dynamic subtest: all-pipes
[235.572082] Dynamic subtest all-pipes: SUCCESS (5.387s)
[235.574584] Subtest single-move: SUCCESS (26.920s)
[235.715061] [052/133] (783s left) kms_plane_scaling (planes-scaler-unity-scaling)
[235.813290] Starting subtest: planes-scaler-unity-scaling
[235.815587] Starting dynamic subtest: pipe-A
[235.874758] Dynamic subtest pipe-A: SUCCESS (0.064s)
[235.883550] Starting dynamic subtest: pipe-B
[237.004846] Dynamic subtest pipe-B: SUCCESS (1.129s)
[237.018547] Starting dynamic subtest: pipe-C
[238.117090] Dynamic subtest pipe-C: SUCCESS (1.111s)
[238.125252] Starting dynamic subtest: pipe-D
[239.251259] Dynamic subtest pipe-D: SUCCESS (1.133s)
[239.253560] Subtest planes-scaler-unity-scaling: SUCCESS (3.441s)
[240.418446] [053/133] (779s left) gem_pxp (fail-invalid-protected-context)
[240.536653] Starting subtest: fail-invalid-protected-context
[240.539262] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[240.654908] [054/133] (779s left) sw_sync (timeline_closed_signaled)
[240.694173] Starting subtest: timeline_closed_signaled
[240.696511] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[240.750226] [055/133] (779s left) kms_addfb_basic (bad-pitch-32)
[240.816859] Starting subtest: bad-pitch-32
[240.818751] Subtest bad-pitch-32: SUCCESS (0.000s)
[240.966915] [056/133] (778s left) kms_psr2_su (page_flip-nv12)
[241.016068] Subtest page_flip-NV12: SKIP (0.000s)
[241.076459] [057/133] (778s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-render)
[241.169449] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-render
[248.023542] Subtest psr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.855s)
[248.178897] [058/133] (771s left) kms_pm_rpm (modeset-non-lpsp)
[250.007504] Starting subtest: modeset-non-lpsp
[251.152104] Subtest modeset-non-lpsp: SKIP (1.146s)
[251.579647] [059/133] (768s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[251.634628] Starting subtest: multi-wait-all-for-submit-available-submitted
[251.637197] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[251.741804] [060/133] (768s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[251.823339] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[251.825581] Starting dynamic subtest: A-eDP1
[255.514323] Dynamic subtest A-eDP1: SUCCESS (3.689s)
[255.523053] Starting dynamic subtest: B-eDP1
[258.808051] Dynamic subtest B-eDP1: SUCCESS (3.293s)
[258.816387] Starting dynamic subtest: C-eDP1
[261.926869] Dynamic subtest C-eDP1: SUCCESS (3.118s)
[261.938179] Starting dynamic subtest: D-eDP1
[265.144024] Dynamic subtest D-eDP1: SUCCESS (3.213s)
[265.146172] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.320s)
[265.950683] [061/133] (754s left) gem_workarounds (basic-read)
[266.028607] Starting subtest: basic-read
[266.040303] Subtest basic-read: SUCCESS (0.011s)
[266.166445] [062/133] (754s left) i915_drm_fdinfo (virtual-idle)
[266.204028] Starting subtest: virtual-idle
[269.270040] Subtest virtual-idle: SUCCESS (3.066s)
[269.358932] [063/133] (750s left) kms_vrr (negative-basic)
[269.454872] Starting subtest: negative-basic
[269.461231] Starting dynamic subtest: pipe-A-eDP-1
[274.879293] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.416s)
[275.178650] Subtest negative-basic: SUCCESS (5.724s)
[275.990447] [064/133] (744s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[276.132405] Starting subtest: yf-tiled-8bpp-rotate-0
[276.137058] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[276.236392] [065/133] (744s left) kms_psr (fbc-psr-primary-blt)
[276.326189] Starting subtest: fbc-psr-primary-blt
[276.328430] Starting dynamic subtest: eDP-1
[276.332525] Dynamic subtest eDP-1: SKIP (0.000s)
[276.336603] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[276.400411] [066/133] (743s left) gem_exec_schedule (manyslice)
[276.464484] Starting subtest: manyslice
[276.466379] Starting dynamic subtest: rcs0
[281.598422] Dynamic subtest rcs0: SUCCESS (5.140s)
[281.609644] Starting dynamic subtest: bcs0
[288.247154] Dynamic subtest bcs0: SUCCESS (6.646s)
[288.257873] Starting dynamic subtest: vcs0
[293.332479] Dynamic subtest vcs0: SUCCESS (5.084s)
[293.346866] Starting dynamic subtest: vcs1
[299.953586] Dynamic subtest vcs1: SUCCESS (6.619s)
[299.966089] Starting dynamic subtest: vecs0
[305.543144] Dynamic subtest vecs0: SUCCESS (5.588s)
[305.552471] Starting dynamic subtest: ccs0
[312.248596] Dynamic subtest ccs0: SUCCESS (6.702s)
[312.251041] Subtest manyslice: SUCCESS (35.791s)
[312.551298] [067/133] (707s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[312.650695] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[312.655152] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[312.744394] [068/133] (707s left) kms_fbcon_fbt (fbc)
[312.809081] Starting subtest: fbc
[319.113626] Subtest fbc: SUCCESS (6.305s)
[320.188301] [069/133] (700s left) gem_create (create-ext-cpu-access-big)
[320.357494] Starting subtest: create-ext-cpu-access-big
[320.362421] Subtest create-ext-cpu-access-big: SKIP (0.000s)
[320.430731] [070/133] (700s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[320.516369] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[320.518733] Starting dynamic subtest: pipe-A-eDP-1
[320.577300] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[320.584887] Starting dynamic subtest: pipe-B-eDP-1
[320.642482] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[320.652290] Starting dynamic subtest: pipe-C-eDP-1
[320.709314] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[320.719106] Starting dynamic subtest: pipe-D-eDP-1
[320.776952] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[320.781038] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[322.763133] [071/133] (697s left) gem_ctx_isolation (clean)
[322.827813] Starting subtest: clean
[322.830037] Starting dynamic subtest: rcs0
[322.890677] Dynamic subtest rcs0: SUCCESS (0.063s)
[322.899724] Starting dynamic subtest: bcs0
[322.955897] Dynamic subtest bcs0: SUCCESS (0.064s)
[322.964286] Starting dynamic subtest: vcs0
[323.011469] Dynamic subtest vcs0: SUCCESS (0.054s)
[323.022449] Starting dynamic subtest: vcs1
[324.905345] Dynamic subtest vcs1: SUCCESS (1.893s)
[324.916253] Starting dynamic subtest: vecs0
[326.931501] Dynamic subtest vecs0: SUCCESS (2.025s)
[326.940726] Starting dynamic subtest: ccs0
[328.944261] Dynamic subtest ccs0: SUCCESS (2.012s)
[328.946580] Subtest clean: SUCCESS (6.117s)
[329.172693] [072/133] (691s left) kms_rotation_crc (sprite-rotation-180)
[329.290983] Starting subtest: sprite-rotation-180
[336.192470] Subtest sprite-rotation-180: SUCCESS (6.904s)
[337.422114] [073/133] (683s left) gem_exec_whisper (basic-sync-all)
[337.489904] Starting subtest: basic-sync-all
[344.993478] Subtest basic-sync-all: SUCCESS (7.501s)
[345.192721] [074/133] (675s left) i915_pm_rpm (sysfs-read)
[347.022671] Starting subtest: sysfs-read
[348.183080] Subtest sysfs-read: SUCCESS (1.160s)
[348.561912] [075/133] (672s left) kms_plane (plane-panning-bottom-right-suspend)
[348.663240] Starting subtest: plane-panning-bottom-right-suspend
[348.665418] Starting dynamic subtest: pipe-A
[366.452686] Dynamic subtest pipe-A: SUCCESS (3.403s)
[366.462938] Starting dynamic subtest: pipe-B
[385.461777] Dynamic subtest pipe-B: SUCCESS (3.919s)
[385.464047] Subtest plane-panning-bottom-right-suspend: SUCCESS (7.326s)
[391.346417] [076/133] (634s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[391.478547] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[391.482930] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[391.584399] [077/133] (634s left) kms_vblank (wait-busy-hang)
[391.687186] Starting subtest: wait-busy-hang
[391.689468] Starting dynamic subtest: pipe-A-eDP-1
[418.198860] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.305s)
[418.214358] Starting dynamic subtest: pipe-D-eDP-1
[433.151412] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.946s)
[433.153852] Subtest wait-busy-hang: SUCCESS (41.259s)
[434.043761] [078/133] (591s left) i915_selftest (mock)
[435.778516] Starting subtest: mock
[435.781412] Starting dynamic subtest: sanitycheck
[436.437058] Dynamic subtest sanitycheck: SUCCESS (0.658s)
[436.446737] Starting dynamic subtest: shmem
[437.098871] Dynamic subtest shmem: SUCCESS (0.660s)
[437.108795] Starting dynamic subtest: fence
[444.505448] Dynamic subtest fence: SUCCESS (7.402s)
[444.514184] Starting dynamic subtest: scatterlist
[446.171608] Dynamic subtest scatterlist: SUCCESS (1.663s)
[446.180779] Starting dynamic subtest: syncmap
[449.373140] Dynamic subtest syncmap: SUCCESS (3.198s)
[449.382964] Starting dynamic subtest: uncore
[450.054905] Dynamic subtest uncore: SUCCESS (0.680s)
[450.064976] Starting dynamic subtest: ring
[450.731645] Dynamic subtest ring: SUCCESS (0.675s)
[450.741636] Starting dynamic subtest: engine
[451.404165] Dynamic subtest engine: SUCCESS (0.670s)
[451.414168] Starting dynamic subtest: timelines
[455.200263] Dynamic subtest timelines: SUCCESS (3.793s)
[455.210182] Starting dynamic subtest: requests
[459.042787] Dynamic subtest requests: SUCCESS (3.838s)
[459.052815] Starting dynamic subtest: objects
[459.802901] Dynamic subtest objects: SUCCESS (0.759s)
[459.812699] Starting dynamic subtest: phys
[460.554563] Dynamic subtest phys: SUCCESS (0.749s)
[460.564437] Starting dynamic subtest: dmabuf
[461.304782] Dynamic subtest dmabuf: SUCCESS (0.748s)
[461.314432] Starting dynamic subtest: vma
[467.039932] Dynamic subtest vma: SUCCESS (5.731s)
[467.049667] Starting dynamic subtest: evict
[468.460674] Dynamic subtest evict: SUCCESS (1.420s)
[468.470148] Starting dynamic subtest: gtt
[471.758742] Dynamic subtest gtt: SUCCESS (3.295s)
[471.768556] Starting dynamic subtest: hugepages
[472.622909] Dynamic subtest hugepages: SUCCESS (0.863s)
[472.632667] Starting dynamic subtest: memory_region
[475.103566] Dynamic subtest memory_region: SUCCESS (2.477s)
[475.106553] Subtest mock: SUCCESS (39.313s)
[475.133848] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[475.134620] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[475.138069] [079/133] (550s left) gem_readwrite (write-bad-handle)
[476.660484] Subtest write-bad-handle: SKIP (0.000s)
[476.720521] [FACT gem_readwrite (write-bad-handle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[476.722341] [FACT gem_readwrite (write-bad-handle)] new: kernel.kmod_is_loaded.i915: true
[476.726187] [080/133] (549s left) syncobj_timeline (reset-during-wait-for-submit)
[476.784852] Starting subtest: reset-during-wait-for-submit
[476.888844] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[476.964016] [081/133] (549s left) fbdev (write)
[477.004314] Starting subtest: write
[477.365535] Subtest write: SUCCESS (0.359s)
[477.439929] [082/133] (548s left) gem_eio (in-flight-1us)
[477.579486] Starting subtest: in-flight-1us
[484.989851] Subtest in-flight-1us: SUCCESS (7.405s)
[485.140840] [083/133] (540s left) syncobj_wait (invalid-wait-bad-flags)
[485.201465] Starting subtest: invalid-wait-bad-flags
[485.203373] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[485.288532] [084/133] (540s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[485.377824] Starting subtest: primary-x-tiled-reflect-x-180
[492.613783] Subtest primary-x-tiled-reflect-x-180: SUCCESS (7.238s)
[494.025390] [085/133] (532s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[494.122980] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[494.126877] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[494.230407] [086/133] (531s left) perf_pmu (enable-race)
[494.268850] Starting subtest: enable-race
[494.270664] Starting dynamic subtest: rcs0
[507.038114] Dynamic subtest rcs0: SUCCESS (12.773s)
[507.047974] Starting dynamic subtest: bcs0
[519.780528] Dynamic subtest bcs0: SUCCESS (12.740s)
[519.790436] Starting dynamic subtest: vcs0
[535.348773] Dynamic subtest vcs0: SUCCESS (15.562s)
[535.360917] Starting dynamic subtest: vcs1
[551.349729] Dynamic subtest vcs1: SUCCESS (15.995s)
[551.361903] Starting dynamic subtest: vecs0
[567.350745] Dynamic subtest vecs0: SUCCESS (15.994s)
[567.362685] Starting dynamic subtest: ccs0
[580.096904] Dynamic subtest ccs0: SUCCESS (12.741s)
[580.099352] Subtest enable-race: SUCCESS (85.821s)
[580.187112] [087/133] (446s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[580.294215] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[580.298304] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[580.402824] [088/133] (445s left) gem_exec_schedule (implicit-boths)
[580.481047] Starting subtest: implicit-boths
[580.483403] Starting dynamic subtest: rcs0
[580.595483] Dynamic subtest rcs0: SUCCESS (0.122s)
[580.608902] Starting dynamic subtest: vcs0
[580.685023] Dynamic subtest vcs0: SUCCESS (0.092s)
[580.698292] Starting dynamic subtest: vcs1
[580.773647] Dynamic subtest vcs1: SUCCESS (0.088s)
[580.789463] Starting dynamic subtest: vecs0
[580.855483] Dynamic subtest vecs0: SUCCESS (0.080s)
[580.867105] Starting dynamic subtest: ccs0
[580.953110] Dynamic subtest ccs0: SUCCESS (0.097s)
[580.955901] Subtest implicit-boths: SUCCESS (0.483s)
[582.483656] [089/133] (443s left) gem_exec_schedule (u-fairslice)
[582.551606] Starting subtest: u-fairslice
[582.554259] Starting dynamic subtest: rcs0
[584.567885] Dynamic subtest rcs0: SUCCESS (2.024s)
[584.578833] Starting dynamic subtest: bcs0
[586.591880] Dynamic subtest bcs0: SUCCESS (2.023s)
[586.602180] Starting dynamic subtest: vcs0
[588.616439] Dynamic subtest vcs0: SUCCESS (2.022s)
[588.627634] Starting dynamic subtest: vcs1
[590.638595] Dynamic subtest vcs1: SUCCESS (2.022s)
[590.650853] Starting dynamic subtest: vecs0
[592.663359] Dynamic subtest vecs0: SUCCESS (2.020s)
[592.673649] Starting dynamic subtest: ccs0
[594.683754] Dynamic subtest ccs0: SUCCESS (2.021s)
[594.685669] Subtest u-fairslice: SUCCESS (12.137s)
[594.989339] [090/133] (431s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[595.115128] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[595.944896] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.829s)
[596.114845] [091/133] (430s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[596.207135] Starting subtest: compare-crc-sanitycheck-xr24
[596.209105] Starting dynamic subtest: pipe-A-eDP-1
[596.876892] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.671s)
[596.887044] Starting dynamic subtest: pipe-B-eDP-1
[598.232853] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.352s)
[598.245988] Starting dynamic subtest: pipe-C-eDP-1
[599.502945] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.269s)
[599.513368] Starting dynamic subtest: pipe-D-eDP-1
[600.734923] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.229s)
[600.736795] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.527s)
[601.547495] [092/133] (424s left) kms_properties (plane-properties-atomic)
[601.636411] Starting subtest: plane-properties-atomic
[601.638546] Starting dynamic subtest: pipe-A-eDP-1
[602.199431] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.562s)
[602.209264] Starting dynamic subtest: pipe-B-eDP-1
[603.438062] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.236s)
[603.448233] Starting dynamic subtest: pipe-C-eDP-1
[604.678349] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.238s)
[604.688211] Starting dynamic subtest: pipe-D-eDP-1
[605.923412] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.246s)
[605.925534] Subtest plane-properties-atomic: SUCCESS (4.287s)
[606.738213] [093/133] (419s left) gem_ringfill (legacy-basic)
[612.409042] Starting subtest: legacy-basic
[612.411590] Starting dynamic subtest: default
[612.467759] Dynamic subtest default: SUCCESS (0.058s)
[612.479992] Starting dynamic subtest: render
[612.509537] Dynamic subtest render: SUCCESS (0.040s)
[612.522279] Starting dynamic subtest: bsd
[612.551763] Dynamic subtest bsd: SUCCESS (0.040s)
[612.561998] Starting dynamic subtest: bsd1
[612.591576] Dynamic subtest bsd1: SUCCESS (0.037s)
[612.604510] Starting dynamic subtest: bsd2
[612.634627] Dynamic subtest bsd2: SUCCESS (0.040s)
[612.644799] Starting dynamic subtest: blt
[612.680534] Dynamic subtest blt: SUCCESS (0.044s)
[612.691584] Starting dynamic subtest: vebox
[612.723570] Dynamic subtest vebox: SUCCESS (0.041s)
[612.725916] Subtest legacy-basic: SUCCESS (0.317s)
[612.804731] [094/133] (413s left) gem_mmap_wc (read)
[612.862598] Subtest read: SKIP (0.000s)
[612.923115] [095/133] (413s left) kms_psr (psr2-cursor-mmap-gtt)
[613.024601] Starting subtest: psr2-cursor-mmap-gtt
[613.026872] Starting dynamic subtest: eDP-1
[614.704965] Dynamic subtest eDP-1: SUCCESS (1.678s)
[614.707355] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.680s)
[615.515523] [096/133] (411s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[615.608364] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[615.612616] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[615.720058] [097/133] (410s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[615.819984] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[615.823781] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[615.924772] [098/133] (410s left) gem_exec_balancer (full-pulse)
[615.991504] Starting subtest: full-pulse
[620.210748] Subtest full-pulse: SUCCESS (4.220s)
[620.556271] [099/133] (406s left) gem_exec_whisper (basic-fds-priority-all)
[620.619302] Starting subtest: basic-fds-priority-all
[644.367696] Subtest basic-fds-priority-all: SUCCESS (23.748s)
[644.552406] [100/133] (382s left) kms_pm_backlight (basic-brightness)
[644.653534] Starting subtest: basic-brightness
[644.706525] Starting dynamic subtest: eDP-1
[645.014395] Dynamic subtest eDP-1: SUCCESS (0.307s)
[645.016925] Subtest basic-brightness: SUCCESS (0.362s)
[645.826694] [101/133] (381s left) gem_ctx_persistence (legacy-engines-mixed-process)
[645.879760] Starting subtest: legacy-engines-mixed-process
[645.881998] Starting dynamic subtest: render
[647.380464] Dynamic subtest render: SUCCESS (1.502s)
[647.392248] Starting dynamic subtest: bsd1
[649.412693] Dynamic subtest bsd1: SUCCESS (2.031s)
[649.423931] Starting dynamic subtest: bsd2
[651.382640] Dynamic subtest bsd2: SUCCESS (1.968s)
[651.394118] Starting dynamic subtest: blt
[653.367649] Dynamic subtest blt: SUCCESS (1.984s)
[653.386127] Starting dynamic subtest: vebox
[655.421355] Dynamic subtest vebox: SUCCESS (2.050s)
[655.423816] Subtest legacy-engines-mixed-process: SUCCESS (9.546s)
[655.521087] [102/133] (371s left) gem_exec_reloc (basic-wc-gtt-active)
[655.585742] Subtest basic-wc-gtt-active: SKIP (0.000s)
[655.660219] [103/133] (371s left) gem_wait (await)
[655.730081] Starting subtest: await
[655.732028] Starting dynamic subtest: all-engines
[656.858364] Dynamic subtest all-engines: SUCCESS (1.131s)
[656.868784] Starting dynamic subtest: rcs0
[657.939788] Dynamic subtest rcs0: SUCCESS (1.079s)
[657.956893] Starting dynamic subtest: bcs0
[659.006232] Dynamic subtest bcs0: SUCCESS (1.064s)
[659.024407] Starting dynamic subtest: vcs0
[660.065716] Dynamic subtest vcs0: SUCCESS (1.057s)
[660.082801] Starting dynamic subtest: vcs1
[661.121447] Dynamic subtest vcs1: SUCCESS (1.054s)
[661.138674] Starting dynamic subtest: vecs0
[662.176313] Dynamic subtest vecs0: SUCCESS (1.053s)
[662.194052] Starting dynamic subtest: ccs0
[663.234359] Dynamic subtest ccs0: SUCCESS (1.056s)
[663.237284] Subtest await: SUCCESS (7.508s)
[663.438494] [104/133] (363s left) kms_psr (fbc-psr2-sprite-blt)
[663.526796] Starting subtest: fbc-psr2-sprite-blt
[663.529110] Starting dynamic subtest: eDP-1
[663.533399] Dynamic subtest eDP-1: SKIP (0.000s)
[663.537925] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[663.604138] [105/133] (363s left) i915_drm_fdinfo (virtual-busy-hang)
[663.648035] Starting subtest: virtual-busy-hang
[663.652301] Subtest virtual-busy-hang: SKIP (0.000s)
[663.713762] [106/133] (363s left) gem_exec_create (madvise)
[663.774689] Starting subtest: madvise
[663.776962] Starting dynamic subtest: smem
[685.426289] Dynamic subtest smem: SUCCESS (21.649s)
[685.429195] Subtest madvise: SUCCESS (21.650s)
[686.241005] [107/133] (340s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[686.349972] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[686.353876] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[686.451914] [108/133] (340s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[686.545215] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[686.549402] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[686.625532] [109/133] (340s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[686.718318] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[686.722462] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[686.814037] [110/133] (340s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[686.911278] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[693.522189] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.615s)
[693.647091] [111/133] (333s left) gem_exec_fence (syncobj-timeline-repeat)
[694.217897] Starting subtest: syncobj-timeline-repeat
[694.460817] Subtest syncobj-timeline-repeat: SUCCESS (0.245s)
[695.428223] [112/133] (331s left) gem_mmap_gtt (basic-write-read)
[695.489538] Subtest basic-write-read: SKIP (0.000s)
[695.553887] [113/133] (331s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[695.647944] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[695.652326] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[695.756995] [114/133] (331s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[695.810758] Starting subtest: single-wait-all-for-submit-signaled
[695.812889] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[695.905463] [115/133] (331s left) kms_async_flips (async-flip-suspend-resume)
[695.989944] Starting subtest: async-flip-suspend-resume
[696.377212] Starting dynamic subtest: pipe-A-eDP-1
[714.039947] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.316s)
[714.429773] Starting dynamic subtest: pipe-B-eDP-1
[733.066783] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.303s)
[733.450133] Starting dynamic subtest: pipe-C-eDP-1
[752.055633] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.281s)
[752.422806] Starting dynamic subtest: pipe-D-eDP-1
[771.056755] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.293s)
[771.059977] Subtest async-flip-suspend-resume: SUCCESS (14.698s)
[780.258238] [116/133] (255s left) kms_flip (2x-flip-vs-dpms-on-nop)
[780.374082] Starting subtest: 2x-flip-vs-dpms-on-nop
[780.388304] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.012s)
[780.469490] [117/133] (255s left) prime_vgem (basic-fence-read)
[780.540699] Starting subtest: basic-fence-read
[780.561743] Subtest basic-fence-read: SKIP (0.021s)
[780.680050] [118/133] (254s left) gem_unref_active_buffers
[780.882766] [119/133] (254s left) kms_psr (pr-primary-mmap-gtt)
[780.970609] Starting subtest: pr-primary-mmap-gtt
[780.975817] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[781.061192] [120/133] (254s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[781.110235] Starting subtest: etime-single-wait-available-unsubmitted
[781.219856] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.106s)
[781.327716] [121/133] (254s left) syncobj_wait (single-wait-for-submit-signaled)
[781.384831] Starting subtest: single-wait-for-submit-signaled
[781.387517] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[781.487513] [122/133] (254s left) gem_ctx_bad_destroy (invalid-ctx)
[781.552968] Starting subtest: invalid-ctx
[781.555896] Subtest invalid-ctx: SUCCESS (0.000s)
[781.633462] [123/133] (254s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[781.763310] Starting subtest: protected-encrypted-src-copy-not-readible
[781.912843] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.147s)
[782.027846] [124/133] (253s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[782.134794] Starting subtest: 2x-absolute-wf_vblank-interruptible
[782.146808] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[782.225626] [125/133] (253s left) gem_userptr_blits (relocations)
[782.287699] Starting subtest: relocations
[782.292614] Subtest relocations: SKIP (0.001s)
[782.413284] [126/133] (253s left) kms_properties (colorop-properties-legacy)
[782.506147] Starting subtest: colorop-properties-legacy
[782.508592] Starting dynamic subtest: pipe-A-eDP-1
[782.887720] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.372s)
[782.898963] Starting dynamic subtest: pipe-B-eDP-1
[783.993542] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.085s)
[784.005521] Starting dynamic subtest: pipe-C-eDP-1
[785.037246] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.024s)
[785.048986] Starting dynamic subtest: pipe-D-eDP-1
[786.073762] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.020s)
[786.076868] Subtest colorop-properties-legacy: SUCCESS (3.507s)
[786.889092] [127/133] (249s left) gem_mmap_gtt (hang-user)
[786.950222] Subtest hang-user: SKIP (0.000s)
[787.011723] [128/133] (249s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[787.101377] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[791.637520] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.499s)
[792.847248] [129/133] (243s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[792.953812] Starting subtest: plane-lut1d-ctm3x4
[793.100835] Starting dynamic subtest: pipe-A-plane-0
[793.496240] Dynamic subtest pipe-A-plane-0: FAIL (0.194s)
[793.509702] Starting dynamic subtest: pipe-A-plane-1
[793.763607] Dynamic subtest pipe-A-plane-1: FAIL (0.170s)
[793.774655] Starting dynamic subtest: pipe-A-plane-2
[794.024462] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[794.858514] Starting dynamic subtest: pipe-B-plane-0
[795.340432] Dynamic subtest pipe-B-plane-0: FAIL (0.163s)
[795.351880] Starting dynamic subtest: pipe-B-plane-1
[795.717933] Dynamic subtest pipe-B-plane-1: FAIL (0.167s)
[795.728953] Starting dynamic subtest: pipe-B-plane-2
[796.038469] Dynamic subtest pipe-B-plane-2: FAIL (0.691s)
[797.070717] Starting dynamic subtest: pipe-C-plane-0
[797.649497] Dynamic subtest pipe-C-plane-0: FAIL (0.153s)
[797.661524] Starting dynamic subtest: pipe-C-plane-1
[798.096940] Dynamic subtest pipe-C-plane-1: FAIL (0.724s)
[798.108240] Starting dynamic subtest: pipe-C-plane-2
[798.485910] Dynamic subtest pipe-C-plane-2: FAIL (0.172s)
[799.317558] Starting dynamic subtest: pipe-D-plane-0
[799.996492] Dynamic subtest pipe-D-plane-0: FAIL (0.140s)
[800.007813] Starting dynamic subtest: pipe-D-plane-1
[800.534429] Dynamic subtest pipe-D-plane-1: FAIL (0.688s)
[800.545221] Starting dynamic subtest: pipe-D-plane-2
[801.071812] Dynamic subtest pipe-D-plane-2: FAIL (0.652s)
[801.100208] Subtest plane-lut1d-ctm3x4: FAIL (8.118s)
[801.915868] [130/133] (234s left) sysfs_timeslice_duration (duration)
[801.974061] Starting subtest: duration
[801.976711] Starting dynamic subtest: vecs0
[815.718088] Dynamic subtest vecs0: SUCCESS (13.728s)
[815.731537] Starting dynamic subtest: ccs0
[828.869377] Dynamic subtest ccs0: SUCCESS (13.148s)
[828.882683] Starting dynamic subtest: vcs0
[842.661534] Dynamic subtest vcs0: SUCCESS (13.784s)
[842.672987] Starting dynamic subtest: bcs0
[855.820484] Dynamic subtest bcs0: SUCCESS (13.152s)
[855.838602] Starting dynamic subtest: rcs0
[868.971668] Dynamic subtest rcs0: SUCCESS (13.143s)
[868.988765] Starting dynamic subtest: vcs1
[882.725306] Dynamic subtest vcs1: SUCCESS (13.748s)
[882.728254] Subtest duration: SUCCESS (80.721s)
[882.809949] [131/133] (153s left) gem_spin_batch (resubmit-new)
[882.874612] Starting subtest: resubmit-new
[882.876926] Starting dynamic subtest: rcs0
[882.890493] Dynamic subtest rcs0: SUCCESS (0.015s)
[882.901997] Starting dynamic subtest: bcs0
[882.904807] Dynamic subtest bcs0: SUCCESS (0.008s)
[882.914840] Starting dynamic subtest: vcs0
[882.917177] Dynamic subtest vcs0: SUCCESS (0.009s)
[882.926823] Starting dynamic subtest: vcs1
[882.929586] Dynamic subtest vcs1: SUCCESS (0.009s)
[882.939658] Starting dynamic subtest: vecs0
[882.942465] Dynamic subtest vecs0: SUCCESS (0.007s)
[882.952589] Starting dynamic subtest: ccs0
[882.955058] Dynamic subtest ccs0: SUCCESS (0.012s)
[882.957694] Subtest resubmit-new: SUCCESS (0.067s)
[884.738028] [132/133] (151s left) gem_userptr_blits (create-destroy-sync)
[884.813352] Starting subtest: create-destroy-sync
[889.826959] Subtest create-destroy-sync: SUCCESS (5.016s)
[889.972848] [133/133] (146s left) dmabuf_sync_file (export-multiwait)
[890.024996] Starting subtest: export-multiwait
[890.028499] Subtest export-multiwait: SUCCESS (0.001s)
[890.079738] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_fence
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_exec_params
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: gem_sync
results: parsing output: 6/ for test: gem_mmap_gtt
results: parsing output: 7/ for test: kms_chamelium_color
results: parsing output: 8/ for test: kms_flip_scaled_crc
results: parsing output: 9/ for test: kms_hdr
results: parsing output: 10/ for test: prime_vgem
results: parsing output: 11/ for test: gen7_exec_parse
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_chamelium_hpd
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: kms_content_protection
results: parsing output: 16/ for test: kms_plane_multiple
results: parsing output: 17/ for test: kms_ccs
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_psr_stress_test
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: kms_lease
results: parsing output: 22/ for test: kms_cursor_crc
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_cursor_crc
results: parsing output: 26/ for test: api_intel_allocator
results: parsing output: 27/ for test: kms_plane
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_exec_reloc
results: parsing output: 31/ for test: kms_async_flips
results: parsing output: 32/ for test: kms_flip_scaled_crc
results: parsing output: 33/ for test: api_intel_bb
results: parsing output: 34/ for test: kms_cursor_crc
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_eio
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: gem_render_copy_redux
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: syncobj_basic
results: parsing output: 41/ for test: gem_exec_reloc
results: parsing output: 42/ for test: kms_atomic_transition
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_chamelium_frames
results: parsing output: 47/ for test: kms_sequence
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_feature_discovery
results: parsing output: 50/ for test: kms_cursor_legacy
results: parsing output: 51/ for test: kms_plane_scaling
results: parsing output: 52/ for test: gem_pxp
results: parsing output: 53/ for test: sw_sync
results: parsing output: 54/ for test: kms_addfb_basic
results: parsing output: 55/ for test: kms_psr2_su
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_pm_rpm
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: gem_workarounds
results: parsing output: 61/ for test: i915_drm_fdinfo
results: parsing output: 62/ for test: kms_vrr
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: kms_psr
results: parsing output: 65/ for test: gem_exec_schedule
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_fbcon_fbt
results: parsing output: 68/ for test: gem_create
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: gem_ctx_isolation
results: parsing output: 71/ for test: kms_rotation_crc
results: parsing output: 72/ for test: gem_exec_whisper
results: parsing output: 73/ for test: i915_pm_rpm
results: parsing output: 74/ for test: kms_plane
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_vblank
results: parsing output: 77/ for test: i915_selftest
results: parsing output: 78/ for test: gem_readwrite
results: parsing output: 79/ for test: syncobj_timeline
results: parsing output: 80/ for test: fbdev
results: parsing output: 81/ for test: gem_eio
results: parsing output: 82/ for test: syncobj_wait
results: parsing output: 83/ for test: kms_rotation_crc
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_exec_schedule
results: parsing output: 88/ for test: gem_exec_schedule
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: kms_pipe_crc_basic
results: parsing output: 91/ for test: kms_properties
results: parsing output: 92/ for test: gem_ringfill
results: parsing output: 93/ for test: gem_mmap_wc
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: gem_exec_balancer
results: parsing output: 98/ for test: gem_exec_whisper
results: parsing output: 99/ for test: kms_pm_backlight
results: parsing output: 100/ for test: gem_ctx_persistence
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: gem_wait
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: i915_drm_fdinfo
results: parsing output: 105/ for test: gem_exec_create
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_exec_fence
results: parsing output: 111/ for test: gem_mmap_gtt
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_async_flips
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: prime_vgem
results: parsing output: 117/ for test: gem_unref_active_buffers
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: syncobj_wait
results: parsing output: 121/ for test: gem_ctx_bad_destroy
results: parsing output: 122/ for test: gem_pxp
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: gem_userptr_blits
results: parsing output: 125/ for test: kms_properties
results: parsing output: 126/ for test: gem_mmap_gtt
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_color_pipeline
results: parsing output: 129/ for test: sysfs_timeslice_duration
results: parsing output: 130/ for test: gem_spin_batch
results: parsing output: 131/ for test: gem_userptr_blits
results: parsing output: 132/ for test: dmabuf_sync_file
Done.