[54.896689] Initializing watchdogs
[54.896739]   /dev/watchdog0
[54.912180] [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]
[54.918115] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.920388] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.923596] [001/131] (960s left) i915_hangman (gt-engine-hang)
[55.009370] Starting subtest: gt-engine-hang
[55.011557] Starting dynamic subtest: rcs0
[56.886742] Dynamic subtest rcs0: SUCCESS (1.894s)
[56.895556] Starting dynamic subtest: bcs0
[58.868570] Dynamic subtest bcs0: SUCCESS (1.995s)
[58.878240] Starting dynamic subtest: vcs0
[60.852109] Dynamic subtest vcs0: SUCCESS (1.994s)
[60.862391] Starting dynamic subtest: vcs1
[62.902895] Dynamic subtest vcs1: SUCCESS (2.058s)
[62.912987] Starting dynamic subtest: vecs0
[64.883056] Dynamic subtest vecs0: SUCCESS (1.986s)
[64.892240] Starting dynamic subtest: ccs0
[66.867365] Dynamic subtest ccs0: SUCCESS (1.988s)
[66.869718] Subtest gt-engine-hang: SUCCESS (11.922s)
[66.958146] [002/131] (948s left) gem_exec_schedule (submit-golden-slice)
[67.030651] Starting subtest: submit-golden-slice
[67.033088] Starting dynamic subtest: rcs0
[67.081036] Dynamic subtest rcs0: SUCCESS (0.057s)
[67.087947] Starting dynamic subtest: bcs0
[67.121129] Dynamic subtest bcs0: SUCCESS (0.040s)
[67.128904] Starting dynamic subtest: vcs0
[67.170726] Dynamic subtest vcs0: SUCCESS (0.049s)
[67.182893] Starting dynamic subtest: vcs1
[67.216430] Dynamic subtest vcs1: SUCCESS (0.046s)
[67.223194] Starting dynamic subtest: vecs0
[67.255796] Dynamic subtest vecs0: SUCCESS (0.039s)
[67.265129] Starting dynamic subtest: ccs0
[67.301826] Dynamic subtest ccs0: SUCCESS (0.045s)
[67.304228] Subtest submit-golden-slice: SUCCESS (0.279s)
[68.999515] [003/131] (946s left) kms_vblank (wait-forked)
[69.115691] Starting subtest: wait-forked
[69.117562] Starting dynamic subtest: pipe-A-eDP-1
[70.962140] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.848s)
[70.975647] Starting dynamic subtest: pipe-D-eDP-1
[73.481974] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.522s)
[73.484471] Subtest wait-forked: SUCCESS (4.372s)
[74.340475] [004/131] (941s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[74.455149] Starting subtest: cursorA-vs-flipB-toggle
[74.459760] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[74.610952] [005/131] (940s left) gem_exec_flush (basic-uc-pro-default)
[74.675820] Starting subtest: basic-uc-pro-default
[80.163882] Subtest basic-uc-pro-default: SUCCESS (5.462s)
[80.331570] [006/131] (935s left) api_intel_allocator (fork-simple-stress-signal)
[80.391189] Starting subtest: fork-simple-stress-signal
[85.845599] Subtest fork-simple-stress-signal: SUCCESS (5.405s)
[85.933993] [007/131] (929s left) gem_exec_balancer (invalid-balancer)
[86.005145] Starting subtest: invalid-balancer
[87.101686] Subtest invalid-balancer: SUCCESS (1.095s)
[87.454941] [008/131] (928s left) kms_psr (pr-primary-mmap-cpu)
[87.567242] Starting subtest: pr-primary-mmap-cpu
[87.571673] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[87.653081] [009/131] (928s left) perf (i915-ref-count)
[87.730798] Starting subtest: i915-ref-count
[87.803780] Subtest i915-ref-count: SUCCESS (0.071s)
[87.872271] [010/131] (927s left) gem_linear_blits (interruptible)
[88.223778] Starting subtest: interruptible
[93.033134] Subtest interruptible: SUCCESS (4.756s)
[93.147857] [011/131] (922s left) gem_pxp (create-protected-buffer)
[93.321944] Starting subtest: create-protected-buffer
[93.324192] Subtest create-protected-buffer: SUCCESS (0.000s)
[93.462831] [012/131] (922s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[93.565945] Starting subtest: psr-indfb-scaledprimary
[99.260652] Subtest psr-indfb-scaledprimary: SUCCESS (5.697s)
[99.428721] [013/131] (916s left) prime_mmap (test_correct_cpu_write)
[99.484067] Starting subtest: test_correct_cpu_write
[99.486471] Starting dynamic subtest: test_correct_cpu_write-smem
[99.488750] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[99.491107] Subtest test_correct_cpu_write: SUCCESS (0.000s)
[99.590154] [014/131] (916s left) gem_ctx_param (invalid-ctx-set)
[99.642996] Starting subtest: invalid-ctx-set
[99.645412] Subtest invalid-ctx-set: SUCCESS (0.001s)
[99.761904] [015/131] (916s left) syncobj_timeline (wait-delayed-signal)
[99.819380] Starting subtest: wait-delayed-signal
[99.923484] Subtest wait-delayed-signal: SUCCESS (0.102s)
[100.018981] [016/131] (916s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[100.080878] Starting subtest: etime-multi-wait-for-submit-submitted
[100.185927] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.103s)
[100.284396] [017/131] (915s left) sw_sync (alloc_timeline)
[100.316193] Starting subtest: alloc_timeline
[100.318522] Subtest alloc_timeline: SUCCESS (0.000s)
[100.378388] [018/131] (915s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[100.472763] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[100.475059] Starting dynamic subtest: A-eDP1
[104.083768] Dynamic subtest A-eDP1: SUCCESS (3.610s)
[104.092660] Starting dynamic subtest: B-eDP1
[107.292357] Dynamic subtest B-eDP1: SUCCESS (3.207s)
[107.300225] Starting dynamic subtest: C-eDP1
[110.392977] Dynamic subtest C-eDP1: SUCCESS (3.101s)
[110.403122] Starting dynamic subtest: D-eDP1
[113.507208] Dynamic subtest D-eDP1: SUCCESS (3.112s)
[113.509435] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.038s)
[114.312352] [019/131] (901s left) kms_flip (2x-absolute-wf_vblank)
[114.412619] Starting subtest: 2x-absolute-wf_vblank
[114.422056] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[114.539356] [020/131] (901s left) gem_mmap_gtt (medium-copy)
[114.604965] Subtest medium-copy: SKIP (0.000s)
[114.668739] [021/131] (901s left) perf (stress-open-close)
[114.776836] Starting subtest: stress-open-close
[114.779241] Starting dynamic subtest: 0-rcs0
[116.943223] Dynamic subtest 0-rcs0: SUCCESS (2.167s)
[116.950732] Starting dynamic subtest: 1-vcs0
[119.106536] Dynamic subtest 1-vcs0: SUCCESS (2.163s)
[119.108569] Subtest stress-open-close: SUCCESS (4.330s)
[119.147555] [022/131] (897s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[119.248834] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[119.252593] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[119.385829] [023/131] (896s left) kms_addfb_basic (master-rmfb)
[119.446196] Starting subtest: master-rmfb
[119.448147] Subtest master-rmfb: SUCCESS (0.001s)
[119.596589] [024/131] (896s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[119.647255] Starting subtest: multi-wait-available-unsubmitted-signaled
[119.648960] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[119.785862] [025/131] (896s left) kms_vblank (ts-continuation-idle-hang)
[119.879105] Starting subtest: ts-continuation-idle-hang
[119.880742] Starting dynamic subtest: pipe-A-eDP-1
[147.669321] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.789s)
[147.681759] Starting dynamic subtest: pipe-D-eDP-1
[162.709986] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.035s)
[162.712327] Subtest ts-continuation-idle-hang: SUCCESS (42.827s)
[163.560887] [026/131] (852s left) kms_feature_discovery (chamelium)
[163.645398] Starting subtest: chamelium
[163.649397] Subtest chamelium: SKIP (0.000s)
[163.738300] [027/131] (852s left) kms_plane_cursor (primary)
[163.835041] Starting subtest: primary
[163.839567] Starting dynamic subtest: pipe-A-eDP-1-size-64
[166.534603] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.701s)
[166.558884] Starting dynamic subtest: pipe-A-eDP-1-size-128
[169.280547] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.727s)
[169.304992] Starting dynamic subtest: pipe-A-eDP-1-size-256
[172.037921] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.738s)
[172.358365] Starting dynamic subtest: pipe-B-eDP-1-size-64
[175.824592] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.475s)
[175.848642] Starting dynamic subtest: pipe-B-eDP-1-size-128
[178.580974] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.739s)
[178.604345] Starting dynamic subtest: pipe-B-eDP-1-size-256
[181.270746] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.671s)
[181.587502] Starting dynamic subtest: pipe-C-eDP-1-size-64
[184.647200] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.071s)
[184.672217] Starting dynamic subtest: pipe-C-eDP-1-size-128
[186.993643] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.325s)
[187.017751] Starting dynamic subtest: pipe-C-eDP-1-size-256
[189.337545] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.324s)
[189.652327] Starting dynamic subtest: pipe-D-eDP-1-size-64
[192.771092] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.128s)
[192.796626] Starting dynamic subtest: pipe-D-eDP-1-size-128
[195.116023] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.323s)
[195.147213] Starting dynamic subtest: pipe-D-eDP-1-size-256
[197.404524] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.268s)
[197.714232] Subtest primary: SUCCESS (33.880s)
[198.587426] [028/131] (817s left) gem_exec_params (secure-non-root)
[198.650226] Starting subtest: secure-non-root
[198.654737] Subtest secure-non-root: SKIP (0.000s)
[198.764146] [029/131] (817s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[198.867609] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[198.869865] Starting dynamic subtest: pipe-A-eDP-1
[198.928512] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[198.937856] Starting dynamic subtest: pipe-B-eDP-1
[198.996500] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[199.005904] Starting dynamic subtest: pipe-C-eDP-1
[199.064323] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[199.072684] Starting dynamic subtest: pipe-D-eDP-1
[199.129313] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[199.133463] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[201.147479] [030/131] (815s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[206.134076] Starting subtest: fbc-psr2-cursor-plane-update-sf
[206.136360] Starting dynamic subtest: pipe-A-eDP-1
[206.917629] Dynamic subtest pipe-A-eDP-1: SKIP (0.783s)
[206.925695] Starting dynamic subtest: pipe-B-eDP-1
[208.060072] Dynamic subtest pipe-B-eDP-1: SKIP (1.137s)
[208.064371] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.926s)
[209.255335] [031/131] (807s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[209.362237] Starting subtest: yf-tiled-16bpp-rotate-0
[209.366959] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[209.469420] [032/131] (807s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[209.565803] Starting subtest: 4-tiled-64bpp-rotate-0
[211.112085] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.545s)
[211.310977] [033/131] (805s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[211.424278] Starting subtest: short-flip-before-cursor-atomic-transitions
[212.379404] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.952s)
[213.184424] [034/131] (803s left) api_intel_bb (delta-check)
[213.244029] Starting subtest: delta-check
[213.250034] Subtest delta-check: SUCCESS (0.006s)
[213.420381] [035/131] (803s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[213.530135] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[213.534539] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[213.661699] [036/131] (803s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[213.752055] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[213.754188] Starting dynamic subtest: pipe-A-default-mode
[214.044506] Dynamic subtest pipe-A-default-mode: SKIP (0.289s)
[214.049206] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.290s)
[214.905472] [037/131] (801s left) gem_softpin (evict-prime-sanity-check)
[214.968933] Starting subtest: evict-prime-sanity-check
[214.971434] Starting dynamic subtest: rcs0
[216.173512] Dynamic subtest rcs0: SUCCESS (1.203s)
[216.184564] Starting dynamic subtest: bcs0
[217.301337] Dynamic subtest bcs0: SUCCESS (1.126s)
[217.312509] Starting dynamic subtest: vcs0
[218.900298] Dynamic subtest vcs0: SUCCESS (1.596s)
[218.908080] Starting dynamic subtest: vcs1
[220.867718] Dynamic subtest vcs1: SUCCESS (1.965s)
[220.878351] Starting dynamic subtest: vecs0
[222.916128] Dynamic subtest vecs0: SUCCESS (2.046s)
[222.926077] Starting dynamic subtest: ccs0
[224.085856] Dynamic subtest ccs0: SUCCESS (1.169s)
[224.096046] Starting dynamic subtest: all
[225.231540] Dynamic subtest all: SUCCESS (1.143s)
[225.234155] Subtest evict-prime-sanity-check: SUCCESS (10.263s)
[225.324064] [038/131] (791s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[225.389652] Subtest writes-after-reads-snoop: SKIP (0.000s)
[225.452141] [039/131] (791s left) kms_psr (fbc-psr2-primary-page-flip)
[225.556909] Starting subtest: fbc-psr2-primary-page-flip
[225.559233] Starting dynamic subtest: eDP-1
[225.563552] Dynamic subtest eDP-1: SKIP (0.000s)
[225.567818] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[225.638465] [040/131] (791s left) kms_cursor_crc (cursor-random-128x42)
[225.856713] Starting subtest: cursor-random-128x42
[225.905963] Subtest cursor-random-128x42: SKIP (0.044s)
[226.163409] [041/131] (790s left) gem_set_tiling_vs_gtt
[226.289976] [042/131] (790s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[226.386674] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[226.388551] Starting dynamic subtest: pipe-A-eDP-1
[226.407168] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[226.415570] Starting dynamic subtest: pipe-B-eDP-1
[226.434489] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[226.441905] Starting dynamic subtest: pipe-C-eDP-1
[226.459770] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[226.466951] Starting dynamic subtest: pipe-D-eDP-1
[226.484592] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[226.487897] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[228.669435] [043/131] (788s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[233.656992] Starting subtest: pr-overlay-plane-move-continuous-sf
[233.661611] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.002s)
[234.531508] [044/131] (782s left) kms_fbcon_fbt (psr-suspend)
[234.594601] Starting subtest: psr-suspend
[285.774396] Subtest psr-suspend: SUCCESS (20.625s)
[286.820036] [045/131] (731s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[286.940300] Starting subtest: cursorA-vs-flipA-toggle
[297.981220] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.142s)
[298.792493] [046/131] (719s left) i915_pm_rpm (system-suspend)
[300.535280] Starting subtest: system-suspend
[319.501124] Subtest system-suspend: SUCCESS (3.273s)
[325.924357] [047/131] (698s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[326.044304] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[326.048663] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[326.196801] [048/131] (698s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[326.321151] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[326.325039] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[326.427191] [049/131] (697s left) perf_pmu (busy)
[326.473262] Starting subtest: busy
[326.475584] Starting dynamic subtest: rcs0
[327.093265] Dynamic subtest rcs0: SUCCESS (0.625s)
[327.105338] Starting dynamic subtest: bcs0
[327.665605] Dynamic subtest bcs0: SUCCESS (0.578s)
[327.677356] Starting dynamic subtest: vcs0
[329.106958] Dynamic subtest vcs0: SUCCESS (1.456s)
[329.115981] Starting dynamic subtest: vcs1
[331.091058] Dynamic subtest vcs1: SUCCESS (2.002s)
[331.100419] Starting dynamic subtest: vecs0
[333.138701] Dynamic subtest vecs0: SUCCESS (2.062s)
[333.150793] Starting dynamic subtest: ccs0
[333.704123] Dynamic subtest ccs0: SUCCESS (0.568s)
[333.706564] Subtest busy: SUCCESS (7.307s)
[333.807191] [050/131] (690s left) kms_psr (psr2-sprite-plane-onoff)
[333.913821] Starting subtest: psr2-sprite-plane-onoff
[333.916070] Starting dynamic subtest: eDP-1
[335.695408] Dynamic subtest eDP-1: SUCCESS (1.792s)
[335.697834] Subtest psr2-sprite-plane-onoff: SUCCESS (1.793s)
[336.548431] [051/131] (687s left) kms_chamelium_frames (dp-frame-dump)
[336.936132] Subtest dp-frame-dump: SKIP (0.000s)
[337.742326] [052/131] (686s left) syncobj_wait (multi-wait-for-submit-submitted)
[337.799356] Starting subtest: multi-wait-for-submit-submitted
[337.902623] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[337.991959] [053/131] (686s left) kms_properties (invalid-properties-atomic)
[338.093924] Starting subtest: invalid-properties-atomic
[338.349393] Subtest invalid-properties-atomic: SUCCESS (0.257s)
[338.430750] [054/131] (686s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[338.531571] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[338.535775] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[338.654283] [055/131] (685s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[338.755479] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[343.228710] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.490s)
[344.351152] [056/131] (680s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[344.456114] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[344.460540] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[344.579921] [057/131] (680s left) kms_busy (extended-modeset-hang-newfb)
[344.666847] Starting subtest: extended-modeset-hang-newfb
[344.668703] Starting dynamic subtest: pipe-A
[379.026373] Dynamic subtest pipe-A: SUCCESS (34.366s)
[379.035624] Starting dynamic subtest: pipe-D
[401.984816] Dynamic subtest pipe-D: SUCCESS (22.960s)
[401.987221] Subtest extended-modeset-hang-newfb: SUCCESS (57.333s)
[402.800195] [058/131] (621s left) gem_exec_fence (basic-wait)
[402.983427] Starting subtest: basic-wait
[402.986002] Starting dynamic subtest: rcs0
[403.046816] Dynamic subtest rcs0: SUCCESS (0.064s)
[403.058387] Starting dynamic subtest: bcs0
[403.101124] Dynamic subtest bcs0: SUCCESS (0.052s)
[403.112594] Starting dynamic subtest: vcs0
[403.160820] Dynamic subtest vcs0: SUCCESS (0.057s)
[403.171821] Starting dynamic subtest: vcs1
[403.226636] Dynamic subtest vcs1: SUCCESS (0.064s)
[403.237556] Starting dynamic subtest: vecs0
[403.277687] Dynamic subtest vecs0: SUCCESS (0.049s)
[403.287707] Starting dynamic subtest: ccs0
[403.337413] Dynamic subtest ccs0: SUCCESS (0.057s)
[403.340015] Subtest basic-wait: SUCCESS (0.356s)
[405.234096] [FACT gem_exec_fence (basic-wait)] new: kernel.kmod_is_loaded.vgem: true
[405.237586] [059/131] (619s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[405.352205] Starting subtest: x-tiled-8bpp-rotate-0
[406.443873] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.091s)
[406.613138] [060/131] (618s left) kms_invalid_mode (int-max-clock)
[406.721406] Starting subtest: int-max-clock
[406.723281] Starting dynamic subtest: pipe-A-eDP-1
[407.019209] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.298s)
[407.029449] Starting dynamic subtest: pipe-B-eDP-1
[407.031426] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[407.039752] Starting dynamic subtest: pipe-C-eDP-1
[407.042065] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[407.050584] Starting dynamic subtest: pipe-D-eDP-1
[407.052856] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[407.055017] Subtest int-max-clock: SUCCESS (0.304s)
[407.840554] [061/131] (616s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[407.931768] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[407.934129] Starting dynamic subtest: pipe-A-eDP-1
[407.957005] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[407.968478] Starting dynamic subtest: pipe-B-eDP-1
[407.990437] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[407.999618] Starting dynamic subtest: pipe-C-eDP-1
[408.021087] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[408.030525] Starting dynamic subtest: pipe-D-eDP-1
[408.052168] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[408.056487] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[410.200004] [062/131] (614s left) gem_exec_whisper (basic-sync)
[410.269016] Starting subtest: basic-sync
[416.899096] Subtest basic-sync: SUCCESS (6.634s)
[417.110981] [063/131] (607s left) gem_mmap_wc (copy)
[417.172879] Subtest copy: SKIP (0.000s)
[417.244056] [064/131] (607s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[417.334371] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[417.346665] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[417.478651] [065/131] (607s left) perf (invalid-oa-metric-set-id)
[417.572625] Starting subtest: invalid-oa-metric-set-id
[417.581866] Subtest invalid-oa-metric-set-id: SUCCESS (0.008s)
[417.626217] [066/131] (607s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[417.725811] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[417.727976] Starting dynamic subtest: pipe-A-default-mode
[418.013278] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[418.018076] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.286s)
[418.831423] [067/131] (606s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[418.918907] Starting subtest: plane-upscale-20x20-with-pixel-format
[418.921191] Starting dynamic subtest: pipe-A
[419.965792] Dynamic subtest pipe-A: SUCCESS (1.050s)
[419.979957] Starting dynamic subtest: pipe-B
[422.126566] Dynamic subtest pipe-B: SUCCESS (2.161s)
[422.136754] Starting dynamic subtest: pipe-C
[423.326776] Dynamic subtest pipe-C: SUCCESS (1.199s)
[423.338585] Starting dynamic subtest: pipe-D
[424.553841] Dynamic subtest pipe-D: SUCCESS (1.226s)
[424.556180] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.638s)
[425.778166] [068/131] (599s left) kms_flip (modeset-vs-vblank-race)
[425.869344] Starting subtest: modeset-vs-vblank-race
[425.871644] Starting dynamic subtest: A-eDP1
[430.581417] Dynamic subtest A-eDP1: SUCCESS (4.709s)
[430.593490] Starting dynamic subtest: B-eDP1
[435.309161] Dynamic subtest B-eDP1: SUCCESS (4.724s)
[435.319482] Starting dynamic subtest: C-eDP1
[439.938631] Dynamic subtest C-eDP1: SUCCESS (4.627s)
[439.950249] Starting dynamic subtest: D-eDP1
[444.596510] Dynamic subtest D-eDP1: SUCCESS (4.654s)
[444.598948] Subtest modeset-vs-vblank-race: SUCCESS (18.726s)
[445.775945] [069/131] (579s left) vgem_basic (dmabuf-fence-before)
[445.818228] Starting subtest: dmabuf-fence-before
[445.820471] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[445.899411] [070/131] (579s left) kms_plane_alpha_blend (constant-alpha-max)
[445.990893] Starting subtest: constant-alpha-max
[446.281505] Starting dynamic subtest: pipe-A-eDP-1
[447.209768] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.927s)
[447.220062] Starting dynamic subtest: pipe-D-eDP-1
[448.848358] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.635s)
[448.850626] Subtest constant-alpha-max: SUCCESS (2.856s)
[449.748885] [071/131] (575s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[449.847602] Starting subtest: psr-2p-primscrn-spr-indfb-move
[449.852113] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[449.998244] [072/131] (575s left) kms_pm_rpm (modeset-stress-extra-wait)
[452.329502] Starting subtest: modeset-stress-extra-wait
[506.658843] Subtest modeset-stress-extra-wait: SUCCESS (54.285s)
[506.710928] [073/131] (518s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[506.797556] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[514.114717] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.320s)
[514.287886] [074/131] (511s left) kms_psr (fbc-psr-sprite-plane-onoff)
[514.374790] Starting subtest: fbc-psr-sprite-plane-onoff
[514.377035] Starting dynamic subtest: eDP-1
[514.381335] Dynamic subtest eDP-1: SKIP (0.000s)
[514.385547] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[514.472850] [075/131] (510s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[514.580525] Starting subtest: fbcpsr-indfb-scaledprimary
[520.345882] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.768s)
[520.515153] [076/131] (504s left) gem_mmap_gtt (isolation)
[520.581013] Subtest isolation: SKIP (0.000s)
[520.641549] [077/131] (504s left) perf_pmu (idle)
[520.679178] Starting subtest: idle
[520.681655] Starting dynamic subtest: rcs0
[521.235347] Dynamic subtest rcs0: SUCCESS (0.556s)
[521.246401] Starting dynamic subtest: bcs0
[521.756247] Dynamic subtest bcs0: SUCCESS (0.513s)
[521.767218] Starting dynamic subtest: vcs0
[522.281008] Dynamic subtest vcs0: SUCCESS (0.526s)
[522.293427] Starting dynamic subtest: vcs1
[522.808317] Dynamic subtest vcs1: SUCCESS (0.524s)
[522.820769] Starting dynamic subtest: vecs0
[523.339776] Dynamic subtest vecs0: SUCCESS (0.529s)
[523.352237] Starting dynamic subtest: ccs0
[523.868766] Dynamic subtest ccs0: SUCCESS (0.527s)
[523.871666] Subtest idle: SUCCESS (3.193s)
[524.008704] [078/131] (501s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[524.115647] Starting subtest: y-tiled-64bpp-rotate-90
[524.120313] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[524.251301] [079/131] (501s left) kms_psr (fbc-pr-sprite-render)
[524.346678] Starting subtest: fbc-pr-sprite-render
[524.350849] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[524.448870] [080/131] (501s left) kms_cursor_legacy (single-move)
[524.560279] Starting subtest: single-move
[524.562559] Starting dynamic subtest: pipe-A
[529.994572] Dynamic subtest pipe-A: SUCCESS (5.380s)
[530.005888] Starting dynamic subtest: pipe-B
[535.375716] Dynamic subtest pipe-B: SUCCESS (5.381s)
[535.387031] Starting dynamic subtest: pipe-C
[540.757264] Dynamic subtest pipe-C: SUCCESS (5.380s)
[540.768701] Starting dynamic subtest: pipe-D
[546.139145] Dynamic subtest pipe-D: SUCCESS (5.380s)
[546.149877] Starting dynamic subtest: all-pipes
[551.527787] Dynamic subtest all-pipes: SUCCESS (5.387s)
[551.529936] Subtest single-move: SUCCESS (26.921s)
[551.646675] [081/131] (474s left) kms_tv_load_detect (load-detect)
[551.702280] Subtest load-detect: SKIP (0.000s)
[551.775258] [082/131] (473s left) gem_exec_schedule (preempt)
[551.850339] Starting subtest: preempt
[551.852337] Starting dynamic subtest: rcs0
[551.876418] Dynamic subtest rcs0: SUCCESS (0.033s)
[551.885995] Starting dynamic subtest: bcs0
[551.895842] Dynamic subtest bcs0: SUCCESS (0.019s)
[551.905040] Starting dynamic subtest: vcs0
[551.915658] Dynamic subtest vcs0: SUCCESS (0.019s)
[551.925389] Starting dynamic subtest: vcs1
[551.937437] Dynamic subtest vcs1: SUCCESS (0.021s)
[551.946745] Starting dynamic subtest: vecs0
[551.957903] Dynamic subtest vecs0: SUCCESS (0.020s)
[551.967132] Starting dynamic subtest: ccs0
[551.977428] Dynamic subtest ccs0: SUCCESS (0.019s)
[551.979562] Subtest preempt: SUCCESS (0.135s)
[553.226665] [083/131] (472s left) gem_exec_whisper (basic-forked-all)
[553.282235] Starting subtest: basic-forked-all
[567.059385] Subtest basic-forked-all: SUCCESS (13.770s)
[567.258009] [084/131] (458s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[567.358262] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[574.720945] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.364s)
[574.904542] [085/131] (450s left) i915_drm_fdinfo (busy-idle-check-all)
[574.943385] Starting subtest: busy-idle-check-all
[574.945699] Starting dynamic subtest: rcs0
[574.950313] Dynamic subtest rcs0: SKIP (0.006s)
[574.961571] Starting dynamic subtest: bcs0
[574.966013] Dynamic subtest bcs0: SKIP (0.001s)
[574.975315] Starting dynamic subtest: vcs0
[574.979408] Dynamic subtest vcs0: SKIP (0.000s)
[574.988627] Starting dynamic subtest: vcs1
[574.992941] Dynamic subtest vcs1: SKIP (0.000s)
[575.001880] Starting dynamic subtest: vecs0
[575.006298] Dynamic subtest vecs0: SKIP (0.000s)
[575.015288] Starting dynamic subtest: ccs0
[575.019680] Dynamic subtest ccs0: SKIP (0.000s)
[575.023971] Subtest busy-idle-check-all: SKIP (0.015s)
[575.061963] [086/131] (450s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[575.190309] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[575.194574] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[575.303979] [087/131] (450s left) gem_ctx_persistence (heartbeat-many)
[575.371473] Starting subtest: heartbeat-many
[575.376031] Subtest heartbeat-many: SKIP (0.000s)
[575.504200] [088/131] (450s left) i915_query (test-query-geometry-subslices)
[575.568792] Starting subtest: test-query-geometry-subslices
[575.571253] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[575.686413] [089/131] (450s left) gem_ctx_shared (q-promotion)
[575.747548] Starting subtest: Q-promotion
[575.749631] Starting dynamic subtest: rcs0
[575.866654] Dynamic subtest rcs0: SUCCESS (0.116s)
[575.884660] Starting dynamic subtest: vcs0
[575.951717] Dynamic subtest vcs0: SUCCESS (0.085s)
[575.970892] Starting dynamic subtest: vcs1
[576.030513] Dynamic subtest vcs1: SUCCESS (0.077s)
[576.047584] Starting dynamic subtest: vecs0
[576.107660] Dynamic subtest vecs0: SUCCESS (0.076s)
[576.124790] Starting dynamic subtest: ccs0
[576.187802] Dynamic subtest ccs0: SUCCESS (0.079s)
[576.190170] Subtest Q-promotion: SUCCESS (0.439s)
[577.261379] [090/131] (448s left) kms_chamelium_audio (dp-audio-edid)
[577.654123] Subtest dp-audio-edid: SKIP (0.000s)
[578.431781] [091/131] (447s left) gem_eio (in-flight-immediate)
[578.678135] Starting subtest: in-flight-immediate
[587.234132] Subtest in-flight-immediate: SUCCESS (8.552s)
[587.418148] [092/131] (438s left) perf_pmu (interrupts)
[587.455411] Starting subtest: interrupts
[588.565768] Subtest interrupts: SUCCESS (1.109s)
[588.703216] [093/131] (437s left) drm_read (short-buffer-wakeup)
[588.869663] Starting subtest: short-buffer-wakeup
[621.090124] Subtest short-buffer-wakeup: SUCCESS (32.215s)
[621.247707] [094/131] (404s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[621.298378] Starting subtest: yf-tiled-to-vebox-y-tiled
[621.303006] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[621.492782] [095/131] (404s left) kms_color (legacy-gamma)
[621.578996] Starting subtest: legacy-gamma
[621.581176] Starting dynamic subtest: pipe-A-eDP-1
[622.285508] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.706s)
[622.298469] Starting dynamic subtest: pipe-B-eDP-1
[623.665639] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.380s)
[623.676530] Starting dynamic subtest: pipe-C-eDP-1
[625.032846] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.367s)
[625.043753] Starting dynamic subtest: pipe-D-eDP-1
[626.349507] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.316s)
[626.352007] Subtest legacy-gamma: SUCCESS (4.773s)
[627.192186] [096/131] (399s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[627.301164] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[628.257921] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.956s)
[628.420043] [097/131] (397s left) kms_pm_dc (dc9-dpms)
[629.510879] Starting subtest: dc9-dpms
[630.844131] Subtest dc9-dpms: SUCCESS (1.332s)
[630.930477] [098/131] (395s left) gem_exec_fence (keep-in-fence)
[631.124503] Starting subtest: keep-in-fence
[631.126945] Starting dynamic subtest: rcs0
[631.214968] Dynamic subtest rcs0: SUCCESS (0.090s)
[631.232841] Starting dynamic subtest: bcs0
[631.269037] Dynamic subtest bcs0: SUCCESS (0.052s)
[631.281398] Starting dynamic subtest: vcs0
[633.107674] Dynamic subtest vcs0: SUCCESS (1.834s)
[633.120503] Starting dynamic subtest: vcs1
[635.093615] Dynamic subtest vcs1: SUCCESS (1.984s)
[635.106445] Starting dynamic subtest: vecs0
[637.200619] Dynamic subtest vecs0: SUCCESS (2.044s)
[637.212705] Starting dynamic subtest: ccs0
[637.354637] Dynamic subtest ccs0: SUCCESS (0.214s)
[637.357252] Subtest keep-in-fence: SUCCESS (6.232s)
[639.245794] [099/131] (387s left) gem_exec_schedule (fifo)
[639.321920] Starting subtest: fifo
[639.323786] Starting dynamic subtest: rcs0
[639.492090] Dynamic subtest rcs0: SUCCESS (0.176s)
[639.505155] Starting dynamic subtest: vcs0
[639.608171] Dynamic subtest vcs0: SUCCESS (0.117s)
[639.620580] Starting dynamic subtest: vcs1
[639.720471] Dynamic subtest vcs1: SUCCESS (0.111s)
[639.734579] Starting dynamic subtest: vecs0
[639.828141] Dynamic subtest vecs0: SUCCESS (0.105s)
[639.840503] Starting dynamic subtest: ccs0
[639.961635] Dynamic subtest ccs0: SUCCESS (0.132s)
[639.964220] Subtest fifo: SUCCESS (0.648s)
[641.241520] [100/131] (385s left) kms_addfb_basic (bad-pitch-32)
[641.292485] Starting subtest: bad-pitch-32
[641.294294] Subtest bad-pitch-32: SUCCESS (0.000s)
[641.458829] [101/131] (385s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[641.563541] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[648.445779] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.884s)
[648.594058] [102/131] (377s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[648.991459] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[649.794713] [103/131] (376s left) kms_cursor_crc (cursor-size-hints)
[649.912907] Starting subtest: cursor-size-hints
[649.917830] Starting dynamic subtest: pipe-A-eDP-1
[650.543637] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.628s)
[650.554028] Starting dynamic subtest: pipe-D-eDP-1
[651.794159] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.249s)
[651.796454] Subtest cursor-size-hints: SUCCESS (1.882s)
[652.601206] [104/131] (374s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[652.704568] Starting subtest: plane-primary-overlay-mutable-zpos
[656.133186] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.428s)
[657.341445] [105/131] (369s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[657.438216] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[657.440261] Starting dynamic subtest: pipe-A
[657.674728] Dynamic subtest pipe-A: SUCCESS (0.241s)
[657.686318] Starting dynamic subtest: pipe-B
[659.010820] Dynamic subtest pipe-B: SUCCESS (1.335s)
[659.021402] Starting dynamic subtest: pipe-C
[660.338852] Dynamic subtest pipe-C: SUCCESS (1.327s)
[660.348941] Starting dynamic subtest: pipe-D
[661.677681] Dynamic subtest pipe-D: SUCCESS (1.338s)
[661.680054] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.244s)
[662.889009] [106/131] (363s left) gem_ctx_persistence (engines-mixed)
[662.954666] Starting subtest: engines-mixed
[662.957195] Starting dynamic subtest: rcs0
[663.064686] Dynamic subtest rcs0: SUCCESS (0.109s)
[663.077600] Starting dynamic subtest: bcs0
[663.143047] Dynamic subtest bcs0: SUCCESS (0.076s)
[663.156205] Starting dynamic subtest: vcs0
[663.202421] Dynamic subtest vcs0: SUCCESS (0.057s)
[663.212799] Starting dynamic subtest: vcs1
[663.281829] Dynamic subtest vcs1: SUCCESS (0.077s)
[663.294515] Starting dynamic subtest: vecs0
[663.344791] Dynamic subtest vecs0: SUCCESS (0.061s)
[663.356967] Starting dynamic subtest: ccs0
[663.407078] Dynamic subtest ccs0: SUCCESS (0.060s)
[663.409784] Subtest engines-mixed: SUCCESS (0.453s)
[663.514973] [107/131] (363s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[663.636275] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[694.767990] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (31.134s)
[695.599320] [108/131] (331s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[695.704500] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[699.801428] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.101s)
[701.002015] [109/131] (325s left) kms_cursor_crc (cursor-offscreen-max-size)
[701.317412] Starting subtest: cursor-offscreen-max-size
[701.324285] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[701.485577] [110/131] (325s left) gem_exec_params (no-vebox)
[701.543978] Starting subtest: no-vebox
[701.546211] Subtest no-vebox: SUCCESS (0.002s)
[703.244212] [111/131] (323s left) gem_wait (busy)
[703.311719] Starting subtest: busy
[703.313966] Starting dynamic subtest: all-engines
[705.106618] Dynamic subtest all-engines: SUCCESS (1.796s)
[705.119279] Starting dynamic subtest: rcs0
[705.648463] Dynamic subtest rcs0: SUCCESS (0.540s)
[705.661365] Starting dynamic subtest: bcs0
[706.189488] Dynamic subtest bcs0: SUCCESS (0.540s)
[706.201626] Starting dynamic subtest: vcs0
[708.118079] Dynamic subtest vcs0: SUCCESS (1.926s)
[708.130678] Starting dynamic subtest: vcs1
[710.099093] Dynamic subtest vcs1: SUCCESS (1.979s)
[710.111437] Starting dynamic subtest: vecs0
[712.083198] Dynamic subtest vecs0: SUCCESS (1.982s)
[712.096065] Starting dynamic subtest: ccs0
[712.625251] Dynamic subtest ccs0: SUCCESS (0.541s)
[712.627944] Subtest busy: SUCCESS (9.317s)
[712.830045] [112/131] (314s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[712.923691] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[712.925895] Starting dynamic subtest: pipe-A-eDP-1
[712.946313] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s)
[712.957899] Starting dynamic subtest: pipe-B-eDP-1
[712.978002] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.021s)
[712.987965] Starting dynamic subtest: pipe-C-eDP-1
[713.008067] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[713.017869] Starting dynamic subtest: pipe-D-eDP-1
[713.038030] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[713.040230] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.093s)
[715.268624] [113/131] (311s left) gem_ccs (block-multicopy-inplace)
[715.333916] Starting subtest: block-multicopy-inplace
[715.338156] Subtest block-multicopy-inplace: SKIP (0.000s)
[715.465348] [114/131] (311s left) gem_tiled_wb
[715.595044] [115/131] (311s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[715.688246] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[719.251552] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.568s)
[720.457956] [116/131] (306s left) prime_mmap (test_errors)
[720.509702] Starting subtest: test_errors
[720.512142] Starting dynamic subtest: test_errors-smem
[720.608061] Dynamic subtest test_errors-smem: SUCCESS (0.100s)
[720.610636] Subtest test_errors: SUCCESS (0.101s)
[720.733617] [117/131] (306s left) gen9_exec_parse (bb-chained)
[720.783673] Subtest bb-chained: SKIP (0.000s)
[720.862607] [118/131] (306s left) gem_eio (throttle)
[721.049620] Starting subtest: throttle
[721.327612] Subtest throttle: SUCCESS (0.276s)
[721.492090] [119/131] (305s left) gem_render_copy (linear-to-vebox-y-tiled)
[721.552004] Starting subtest: linear-to-vebox-y-tiled
[721.555824] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[721.749071] [120/131] (305s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[721.839329] Starting subtest: fbc-rgb565-draw-render
[726.024037] Subtest fbc-rgb565-draw-render: SUCCESS (4.189s)
[727.164889] [121/131] (300s left) gem_cs_tlb (engines)
[727.223104] Starting subtest: engines
[727.225279] Starting dynamic subtest: rcs0
[729.432248] Dynamic subtest rcs0: SUCCESS (2.152s)
[729.444561] Starting dynamic subtest: bcs0
[731.532518] Dynamic subtest bcs0: SUCCESS (2.152s)
[731.544265] Starting dynamic subtest: vcs0
[733.737785] Dynamic subtest vcs0: SUCCESS (2.153s)
[733.751021] Starting dynamic subtest: vcs1
[735.892040] Dynamic subtest vcs1: SUCCESS (2.153s)
[735.904459] Starting dynamic subtest: vecs0
[738.043806] Dynamic subtest vecs0: SUCCESS (2.151s)
[738.056198] Starting dynamic subtest: ccs0
[740.199394] Dynamic subtest ccs0: SUCCESS (2.155s)
[740.201409] Subtest engines: SUCCESS (12.925s)
[740.392218] [122/131] (287s left) syncobj_timeline (wait-zero-handles)
[740.556920] Starting subtest: wait-zero-handles
[740.559659] Subtest wait-zero-handles: SUCCESS (0.000s)
[740.724717] [123/131] (286s left) syncobj_timeline (invalid-transfer-bad-pad)
[740.833047] Starting subtest: invalid-transfer-bad-pad
[740.835332] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[740.985847] [124/131] (286s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-render)
[741.265427] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-render
[748.120828] Subtest psr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.859s)
[748.282993] [125/131] (279s left) gem_exec_flush (basic-batch-kernel-default-wb)
[748.352313] Starting subtest: basic-batch-kernel-default-wb
[755.018422] Subtest basic-batch-kernel-default-wb: SUCCESS (6.667s)
[755.220577] [126/131] (272s left) kms_lease (lease-unleased-connector)
[755.314592] Starting subtest: lease-unleased-connector
[755.316927] Starting dynamic subtest: pipe-A-eDP-1
[755.322186] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[755.333062] Starting dynamic subtest: pipe-B-eDP-1
[755.335216] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[755.345782] Starting dynamic subtest: pipe-C-eDP-1
[755.348055] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.008s)
[755.357678] Starting dynamic subtest: pipe-D-eDP-1
[755.359955] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[755.362087] Subtest lease-unleased-connector: SUCCESS (0.035s)
[755.488415] [127/131] (272s left) kms_psr (psr-sprite-mmap-gtt)
[755.577833] Starting subtest: psr-sprite-mmap-gtt
[755.580000] Starting dynamic subtest: eDP-1
[756.985669] Dynamic subtest eDP-1: SKIP (1.406s)
[756.990908] Subtest psr-sprite-mmap-gtt: SKIP (1.408s)
[757.192099] [128/131] (270s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[757.253192] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[757.359717] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.106s)
[757.457751] [129/131] (270s left) gem_media_fill (media-fill)
[757.517837] Subtest media-fill: SKIP (0.000s)
[757.644687] [130/131] (270s left) prime_mmap_kms (buffer-sharing)
[757.744895] Starting subtest: buffer-sharing
[758.178680] Subtest buffer-sharing: SUCCESS (0.435s)
[759.051748] [131/131] (268s left) gem_userptr_blits (create-destroy-unsync)
[759.116569] Subtest create-destroy-unsync: SKIP (0.000s)
[759.254378] Closing watchdogs
results: parsing output: 0/ for test: i915_hangman
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: kms_vblank
results: parsing output: 3/ for test: kms_cursor_legacy
results: parsing output: 4/ for test: gem_exec_flush
results: parsing output: 5/ for test: api_intel_allocator
results: parsing output: 6/ for test: gem_exec_balancer
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: perf
results: parsing output: 9/ for test: gem_linear_blits
results: parsing output: 10/ for test: gem_pxp
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: prime_mmap
results: parsing output: 13/ for test: gem_ctx_param
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: sw_sync
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: gem_mmap_gtt
results: parsing output: 20/ for test: perf
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_addfb_basic
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: kms_vblank
results: parsing output: 25/ for test: kms_feature_discovery
results: parsing output: 26/ for test: kms_plane_cursor
results: parsing output: 27/ for test: gem_exec_params
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_cursor_legacy
results: parsing output: 33/ for test: api_intel_bb
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_flip_scaled_crc
results: parsing output: 36/ for test: gem_softpin
results: parsing output: 37/ for test: gem_partial_pwrite_pread
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: gem_set_tiling_vs_gtt
results: parsing output: 41/ for test: kms_ccs
results: parsing output: 42/ for test: kms_psr2_sf
results: parsing output: 43/ for test: kms_fbcon_fbt
results: parsing output: 44/ for test: kms_cursor_legacy
results: parsing output: 45/ for test: i915_pm_rpm
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: perf_pmu
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_chamelium_frames
results: parsing output: 51/ for test: syncobj_wait
results: parsing output: 52/ for test: kms_properties
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_busy
results: parsing output: 57/ for test: gem_exec_fence
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_invalid_mode
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: gem_exec_whisper
results: parsing output: 62/ for test: gem_mmap_wc
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: perf
results: parsing output: 65/ for test: kms_flip_scaled_crc
results: parsing output: 66/ for test: kms_plane_scaling
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: vgem_basic
results: parsing output: 69/ for test: kms_plane_alpha_blend
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_pm_rpm
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_mmap_gtt
results: parsing output: 76/ for test: perf_pmu
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: kms_tv_load_detect
results: parsing output: 81/ for test: gem_exec_schedule
results: parsing output: 82/ for test: gem_exec_whisper
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: i915_drm_fdinfo
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: i915_query
results: parsing output: 88/ for test: gem_ctx_shared
results: parsing output: 89/ for test: kms_chamelium_audio
results: parsing output: 90/ for test: gem_eio
results: parsing output: 91/ for test: perf_pmu
results: parsing output: 92/ for test: drm_read
results: parsing output: 93/ for test: gem_render_copy
results: parsing output: 94/ for test: kms_color
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_pm_dc
results: parsing output: 97/ for test: gem_exec_fence
results: parsing output: 98/ for test: gem_exec_schedule
results: parsing output: 99/ for test: kms_addfb_basic
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_chamelium_hpd
results: parsing output: 102/ for test: kms_cursor_crc
results: parsing output: 103/ for test: kms_atomic
results: parsing output: 104/ for test: kms_plane_scaling
results: parsing output: 105/ for test: gem_ctx_persistence
results: parsing output: 106/ for test: kms_cursor_legacy
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_cursor_crc
results: parsing output: 109/ for test: gem_exec_params
results: parsing output: 110/ for test: gem_wait
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: gem_ccs
results: parsing output: 113/ for test: gem_tiled_wb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: prime_mmap
results: parsing output: 116/ for test: gen9_exec_parse
results: parsing output: 117/ for test: gem_eio
results: parsing output: 118/ for test: gem_render_copy
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_cs_tlb
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_exec_flush
results: parsing output: 125/ for test: kms_lease
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: syncobj_timeline
results: parsing output: 128/ for test: gem_media_fill
results: parsing output: 129/ for test: prime_mmap_kms
results: parsing output: 130/ for test: gem_userptr_blits
Done.