[57.823411] Initializing watchdogs
[57.823486]   /dev/watchdog0
[57.837806] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[57.841766] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.844428] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.847748] [001/133] (960s left) syncobj_timeline (invalid-transfer-bad-pad)
[57.924102] Starting subtest: invalid-transfer-bad-pad
[57.926077] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[58.053718] [002/133] (960s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[58.312970] Starting subtest: parallel-dmabuf-import-out-fence
[59.355986] Subtest parallel-dmabuf-import-out-fence: SUCCESS (1.043s)
[59.459731] [003/133] (958s left) api_intel_allocator (reloc-allocator)
[59.515885] Starting subtest: reloc-allocator
[59.517938] Starting dynamic subtest: basic
[59.520568] Dynamic subtest basic: SUCCESS (0.006s)
[59.529812] Starting dynamic subtest: parallel-one
[59.532142] Dynamic subtest parallel-one: SUCCESS (0.009s)
[59.538870] Starting dynamic subtest: print
[59.541205] Dynamic subtest print: SUCCESS (0.000s)
[59.548075] Starting dynamic subtest: reuse
[59.549957] Dynamic subtest reuse: SUCCESS (0.000s)
[59.556194] Starting dynamic subtest: fork-reopen-allocator
[59.558068] Dynamic subtest fork-reopen-allocator: SUCCESS (0.010s)
[59.560057] Subtest reloc-allocator: SUCCESS (0.025s)
[59.653683] [004/133] (958s left) i915_selftest (live)
[61.291031] Starting subtest: live
[61.293908] Starting dynamic subtest: sanitycheck
[65.596713] Dynamic subtest sanitycheck: SUCCESS (4.306s)
[65.604139] Starting dynamic subtest: uncore
[68.585069] Dynamic subtest uncore: SUCCESS (2.987s)
[68.594355] Starting dynamic subtest: workarounds
[71.609062] Dynamic subtest workarounds: SUCCESS (3.023s)
[71.617975] Starting dynamic subtest: gt_engines
[74.665835] Dynamic subtest gt_engines: SUCCESS (3.056s)
[74.673921] Starting dynamic subtest: gt_timelines
[89.672423] Dynamic subtest gt_timelines: SUCCESS (15.005s)
[89.681202] Starting dynamic subtest: gt_contexts
[93.940364] Dynamic subtest gt_contexts: SUCCESS (4.267s)
[93.948691] Starting dynamic subtest: gt_lrc
[98.250521] Dynamic subtest gt_lrc: SUCCESS (4.309s)
[98.258963] Starting dynamic subtest: gt_mocs
[101.155078] Dynamic subtest gt_mocs: SUCCESS (2.904s)
[101.162884] Starting dynamic subtest: gt_pm
[107.909588] Dynamic subtest gt_pm: SUCCESS (6.753s)
[107.918222] Starting dynamic subtest: gt_heartbeat
[112.183837] Dynamic subtest gt_heartbeat: SUCCESS (4.273s)
[112.193065] Starting dynamic subtest: gt_tlb
[121.448286] Dynamic subtest gt_tlb: SUCCESS (9.265s)
[121.456058] Starting dynamic subtest: requests
[134.840440] Dynamic subtest requests: SUCCESS (13.390s)
[134.849781] Starting dynamic subtest: migrate
[140.537781] Dynamic subtest migrate: SUCCESS (5.696s)
[140.546622] Starting dynamic subtest: active
[144.837781] Dynamic subtest active: SUCCESS (4.299s)
[144.847223] Starting dynamic subtest: objects
[149.465944] Dynamic subtest objects: SUCCESS (4.627s)
[149.474941] Starting dynamic subtest: mman
[152.777219] Dynamic subtest mman: SUCCESS (3.310s)
[152.784666] Starting dynamic subtest: dmabuf
[155.439372] Dynamic subtest dmabuf: SUCCESS (2.660s)
[155.447349] Starting dynamic subtest: vma
[159.726521] Dynamic subtest vma: SUCCESS (4.286s)
[159.735520] Starting dynamic subtest: coherency
[164.757292] Dynamic subtest coherency: SUCCESS (5.029s)
[164.766599] Starting dynamic subtest: gtt
[175.888339] Dynamic subtest gtt: SUCCESS (11.129s)
[175.896251] Starting dynamic subtest: gem
[181.512430] Dynamic subtest gem: SUCCESS (5.623s)
[181.520609] Starting dynamic subtest: evict
[185.386044] Dynamic subtest evict: SUCCESS (3.872s)
[185.395317] Starting dynamic subtest: hugepages
[194.152736] Dynamic subtest hugepages: SUCCESS (8.765s)
[194.164634] Starting dynamic subtest: gem_contexts
[222.363411] Dynamic subtest gem_contexts: SUCCESS (28.209s)
[222.372534] Starting dynamic subtest: client
[226.685419] Dynamic subtest client: SUCCESS (4.321s)
[226.694574] Starting dynamic subtest: gem_migrate
[230.929301] Dynamic subtest gem_migrate: SUCCESS (4.242s)
[230.943744] Starting dynamic subtest: reset
[235.272617] Dynamic subtest reset: SUCCESS (4.342s)
[235.285408] Starting dynamic subtest: memory_region
[239.540982] Dynamic subtest memory_region: SUCCESS (4.267s)
[239.550302] Starting dynamic subtest: hangcheck
[254.129346] Dynamic subtest hangcheck: SUCCESS (14.588s)
[254.138477] Starting dynamic subtest: execlists
[258.411588] Dynamic subtest execlists: SUCCESS (4.281s)
[258.420879] Starting dynamic subtest: ring_submission
[262.722767] Dynamic subtest ring_submission: SUCCESS (4.310s)
[262.730484] Starting dynamic subtest: perf
[267.015644] Dynamic subtest perf: SUCCESS (4.291s)
[267.024567] Starting dynamic subtest: slpc
[279.114199] Dynamic subtest slpc: SUCCESS (12.096s)
[279.123013] Starting dynamic subtest: guc
[282.998294] Dynamic subtest guc: SUCCESS (3.853s)
[283.007961] Starting dynamic subtest: guc_multi_lrc
[287.309023] Dynamic subtest guc_multi_lrc: SUCCESS (4.338s)
[287.317813] Starting dynamic subtest: guc_hang
[292.148841] Dynamic subtest guc_hang: SUCCESS (4.838s)
[292.158206] Starting dynamic subtest: late_gt_pm
[294.903056] Dynamic subtest late_gt_pm: SUCCESS (2.752s)
[294.905909] Subtest live: SUCCESS (233.606s)
[294.932953] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[294.935969] [005/133] (723s left) kms_rotation_crc (multiplane-rotation)
[296.401777] Starting subtest: multiplane-rotation
[313.761567] Subtest multiplane-rotation: SUCCESS (17.361s)
[313.865101] [FACT kms_rotation_crc (multiplane-rotation)] new: kernel.kmod_is_loaded.i915: true
[313.868804] [006/133] (704s left) gem_userptr_blits (create-destroy-sync)
[313.940890] Starting subtest: create-destroy-sync
[318.951959] Subtest create-destroy-sync: SUCCESS (5.009s)
[319.087616] [007/133] (699s left) sw_sync (sync_merge)
[319.132923] Starting subtest: sync_merge
[319.135277] Subtest sync_merge: SUCCESS (0.000s)
[319.186746] [008/133] (699s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[319.258245] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[319.260411] Starting dynamic subtest: pipe-A-eDP-1
[319.280435] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[319.289504] Starting dynamic subtest: pipe-B-eDP-1
[319.308917] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[319.315785] Starting dynamic subtest: pipe-C-eDP-1
[319.334308] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[319.340948] Starting dynamic subtest: pipe-D-eDP-1
[319.359664] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[319.361501] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[321.539276] [009/133] (697s left) gem_exec_schedule (u-submit-golden-slice)
[321.618405] Starting subtest: u-submit-golden-slice
[321.620803] Starting dynamic subtest: rcs0
[321.647094] Dynamic subtest rcs0: SUCCESS (0.035s)
[321.657185] Starting dynamic subtest: bcs0
[321.676753] Dynamic subtest bcs0: SUCCESS (0.029s)
[321.684840] Starting dynamic subtest: vcs0
[321.705633] Dynamic subtest vcs0: SUCCESS (0.028s)
[321.714617] Starting dynamic subtest: vcs1
[321.736058] Dynamic subtest vcs1: SUCCESS (0.030s)
[321.745009] Starting dynamic subtest: vecs0
[321.764392] Dynamic subtest vecs0: SUCCESS (0.028s)
[321.772552] Starting dynamic subtest: ccs0
[321.794303] Dynamic subtest ccs0: SUCCESS (0.029s)
[321.796656] Subtest u-submit-golden-slice: SUCCESS (0.183s)
[322.920870] [010/133] (695s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[322.979787] Subtest basic-cpu-wc-noreloc: SKIP (0.000s)
[323.069432] [011/133] (695s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[323.186411] Starting subtest: basic-flip-before-cursor-atomic
[324.130061] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.943s)
[324.971429] [012/133] (693s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[325.052726] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[325.056955] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[325.120691] [013/133] (693s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[325.190599] Starting subtest: single-wait-all-for-submit-available-signaled
[325.192945] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[325.300187] [014/133] (693s left) i915_pm_rpm (gem-execbuf-stress)
[327.013554] Starting subtest: gem-execbuf-stress
[327.016071] Starting dynamic subtest: smem0
[339.753640] Dynamic subtest smem0: SUCCESS (12.740s)
[339.765227] Starting dynamic subtest: extra-wait-smem0
[402.029830] Dynamic subtest extra-wait-smem0: SUCCESS (62.271s)
[402.032840] Subtest gem-execbuf-stress: SUCCESS (75.017s)
[402.400367] [015/133] (616s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[402.473747] Starting subtest: drrs-dirtyfb-ioctl
[402.486103] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s)
[402.571423] [016/133] (616s left) kms_hdr (static-toggle-suspend)
[402.631408] Starting subtest: static-toggle-suspend
[402.638156] Subtest static-toggle-suspend: SKIP (0.000s)
[402.700644] [017/133] (616s left) kms_content_protection (mei-interface)
[402.813845] Starting subtest: mei-interface
[402.818292] Subtest mei-interface: SKIP (0.001s)
[402.920154] [018/133] (616s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[402.996821] Starting subtest: psr-rgb565-draw-mmap-cpu
[409.770369] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.776s)
[409.868406] [019/133] (609s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[409.957511] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[409.961678] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[410.046159] [020/133] (609s left) gem_exec_fence (submit-chain)
[410.218649] Starting subtest: submit-chain
[410.235423] Subtest submit-chain: SUCCESS (0.018s)
[411.959877] [FACT gem_exec_fence (submit-chain)] new: kernel.kmod_is_loaded.vgem: true
[411.963752] [021/133] (607s left) gem_userptr_blits (readonly-pwrite-unsync)
[412.031440] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[412.163756] [022/133] (606s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[412.255131] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[412.260050] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[413.359634] [023/133] (605s left) gem_exec_nop (basic-sequential)
[413.438895] Starting subtest: basic-sequential
[429.965812] Subtest basic-sequential: SUCCESS (16.536s)
[430.190118] [024/133] (589s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[430.304140] Starting subtest: fbc-psr2-cursor-plane-update-sf
[430.306424] Starting dynamic subtest: pipe-A-eDP-1
[430.439405] Dynamic subtest pipe-A-eDP-1: SKIP (0.133s)
[430.449221] Starting dynamic subtest: pipe-B-eDP-1
[431.551747] Dynamic subtest pipe-B-eDP-1: SKIP (1.107s)
[431.556448] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.247s)
[432.736938] [025/133] (586s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[432.792341] Subtest writes-after-reads-uncached: SKIP (0.000s)
[432.856317] [026/133] (586s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[432.940514] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[432.945076] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[433.058377] [027/133] (586s left) kms_flip (modeset-vs-vblank-race-interruptible)
[433.129432] Starting subtest: modeset-vs-vblank-race-interruptible
[433.131773] Starting dynamic subtest: A-eDP1
[437.878215] Dynamic subtest A-eDP1: SUCCESS (4.747s)
[437.889176] Starting dynamic subtest: B-eDP1
[442.510756] Dynamic subtest B-eDP1: SUCCESS (4.629s)
[442.521644] Starting dynamic subtest: C-eDP1
[447.023338] Dynamic subtest C-eDP1: SUCCESS (4.510s)
[447.033211] Starting dynamic subtest: D-eDP1
[451.570858] Dynamic subtest D-eDP1: SUCCESS (4.546s)
[451.573334] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.443s)
[452.722188] [028/133] (566s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[452.800994] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[459.569338] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.772s)
[459.679893] [029/133] (559s left) kms_pm_rpm (legacy-planes-dpms)
[462.004392] Starting subtest: legacy-planes-dpms
[462.007231] Starting dynamic subtest: plane-65
[470.273026] Dynamic subtest plane-65: SUCCESS (8.268s)
[470.283918] Starting dynamic subtest: plane-95
[478.587541] Dynamic subtest plane-95: SUCCESS (8.312s)
[478.598611] Starting dynamic subtest: plane-125
[486.864817] Dynamic subtest plane-125: SUCCESS (8.275s)
[486.876266] Starting dynamic subtest: plane-135
[495.152770] Dynamic subtest plane-135: SUCCESS (8.285s)
[495.155370] Subtest legacy-planes-dpms: SUCCESS (33.150s)
[495.517201] [030/133] (523s left) perf (sysctl-defaults)
[495.591874] Starting subtest: sysctl-defaults
[495.594786] Subtest sysctl-defaults: SUCCESS (0.000s)
[495.682970] [031/133] (523s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[495.750378] Starting subtest: multi-wait-available-unsubmitted-signaled
[495.752693] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[495.858769] [032/133] (523s left) kms_getfb (getfb-handle-closed)
[495.936712] Starting subtest: getfb-handle-closed
[495.939038] Subtest getfb-handle-closed: SUCCESS (0.000s)
[496.043839] [033/133] (523s left) gem_exec_reloc (basic-gtt-active)
[496.111575] Subtest basic-gtt-active: SKIP (0.000s)
[496.178128] [034/133] (523s left) gem_exec_fence (syncobj-timeline-wait)
[496.708812] Starting subtest: syncobj-timeline-wait
[497.832725] Subtest syncobj-timeline-wait: SUCCESS (1.124s)
[499.947327] [035/133] (519s left) kms_getfb (getfb2-handle-protection)
[500.039163] Starting subtest: getfb2-handle-protection
[500.059484] Subtest getfb2-handle-protection: SUCCESS (0.021s)
[500.180030] [036/133] (519s left) kms_cursor_crc (cursor-onscreen-512x512)
[500.431770] Starting subtest: cursor-onscreen-512x512
[500.436233] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[500.595854] [037/133] (519s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[500.671040] Starting subtest: coverage-vs-premult-vs-constant
[500.963181] Starting dynamic subtest: pipe-A-eDP-1
[501.536338] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.571s)
[501.544726] Starting dynamic subtest: pipe-D-eDP-1
[502.845306] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.307s)
[502.847688] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.175s)
[503.704340] [038/133] (516s left) gem_exec_schedule (preempt-user)
[503.777972] Starting subtest: preempt-user
[503.780070] Starting dynamic subtest: rcs0
[503.792334] Dynamic subtest rcs0: SUCCESS (0.024s)
[503.802552] Starting dynamic subtest: bcs0
[503.814906] Dynamic subtest bcs0: SUCCESS (0.022s)
[503.823289] Starting dynamic subtest: vcs0
[503.836889] Dynamic subtest vcs0: SUCCESS (0.021s)
[503.845420] Starting dynamic subtest: vcs1
[503.858579] Dynamic subtest vcs1: SUCCESS (0.021s)
[503.868225] Starting dynamic subtest: vecs0
[503.878392] Dynamic subtest vecs0: SUCCESS (0.019s)
[503.886774] Starting dynamic subtest: ccs0
[503.899966] Dynamic subtest ccs0: SUCCESS (0.021s)
[503.902373] Subtest preempt-user: SUCCESS (0.133s)
[505.887174] [039/133] (513s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[505.963322] Starting subtest: plane-lut1d-ctm3x4
[506.111569] Starting dynamic subtest: pipe-A-plane-0
[506.255215] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[506.263412] Starting dynamic subtest: pipe-A-plane-1
[506.399808] Dynamic subtest pipe-A-plane-1: SUCCESS (0.144s)
[506.408420] Starting dynamic subtest: pipe-A-plane-2
[506.545355] Dynamic subtest pipe-A-plane-2: SUCCESS (0.144s)
[507.725991] Starting dynamic subtest: pipe-B-plane-0
[507.863792] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[507.876028] Starting dynamic subtest: pipe-B-plane-1
[508.008652] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s)
[508.017304] Starting dynamic subtest: pipe-B-plane-2
[508.153060] Dynamic subtest pipe-B-plane-2: SUCCESS (0.144s)
[509.317928] Starting dynamic subtest: pipe-C-plane-0
[509.455422] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[509.469970] Starting dynamic subtest: pipe-C-plane-1
[509.599912] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[509.611908] Starting dynamic subtest: pipe-C-plane-2
[509.743851] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[510.950154] Starting dynamic subtest: pipe-D-plane-0
[511.076872] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[511.085517] Starting dynamic subtest: pipe-D-plane-1
[511.221171] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[511.230086] Starting dynamic subtest: pipe-D-plane-2
[511.388359] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[511.674244] Subtest plane-lut1d-ctm3x4: SUCCESS (5.711s)
[512.519268] [040/133] (507s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[512.592313] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[519.081901] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.492s)
[519.208195] [041/133] (500s left) kms_hdmi_inject (inject-audio)
[519.266106] Subtest inject-audio: SKIP (0.000s)
[519.341365] [042/133] (500s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[519.412523] Starting subtest: y-tiled-16bpp-rotate-270
[519.416652] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[519.509226] [043/133] (500s left) gem_render_copy (y-tiled-to-vebox-linear)
[519.576675] Starting subtest: y-tiled-to-vebox-linear
[519.581256] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[519.761208] [044/133] (500s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[519.831338] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[519.833732] Starting dynamic subtest: pipe-A
[519.895059] Dynamic subtest pipe-A: SUCCESS (0.065s)
[519.905266] Starting dynamic subtest: pipe-B
[521.009338] Dynamic subtest pipe-B: SUCCESS (1.113s)
[521.018692] Starting dynamic subtest: pipe-C
[522.113891] Dynamic subtest pipe-C: SUCCESS (1.102s)
[522.123607] Starting dynamic subtest: pipe-D
[523.236366] Dynamic subtest pipe-D: SUCCESS (1.122s)
[523.238732] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.408s)
[524.403680] [045/133] (495s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[524.473130] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[524.475378] Starting dynamic subtest: pipe-A-eDP-1
[524.965450] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.463s)
[524.974368] Starting dynamic subtest: pipe-B-eDP-1
[526.397489] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.431s)
[526.408033] Starting dynamic subtest: pipe-C-eDP-1
[527.800826] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.404s)
[527.810992] Starting dynamic subtest: pipe-D-eDP-1
[529.125395] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[529.127666] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.627s)
[530.329514] [046/133] (489s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[530.420939] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[530.425173] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.000s)
[530.500396] [047/133] (489s left) i915_hangman (hangcheck-unterminated)
[530.568086] Starting subtest: hangcheck-unterminated
[540.791848] Subtest hangcheck-unterminated: SUCCESS (10.222s)
[540.940064] [048/133] (479s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[541.312064] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[542.131781] [049/133] (478s left) kms_properties (crtc-properties-atomic)
[542.215383] Starting subtest: crtc-properties-atomic
[542.217606] Starting dynamic subtest: pipe-A-eDP-1
[542.667453] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.450s)
[542.679624] Starting dynamic subtest: pipe-B-eDP-1
[543.813267] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.145s)
[543.822218] Starting dynamic subtest: pipe-C-eDP-1
[544.921019] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[544.930215] Starting dynamic subtest: pipe-D-eDP-1
[546.045070] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[546.047289] Subtest crtc-properties-atomic: SUCCESS (3.831s)
[546.861951] [050/133] (473s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[546.950706] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[553.723421] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.775s)
[553.853554] [051/133] (466s left) gem_mmap_gtt (big-bo-tiledx)
[553.920112] Subtest big-bo-tiledX: SKIP (0.000s)
[553.987610] [052/133] (466s left) kms_plane_cursor (viewport)
[554.066517] Starting subtest: viewport
[554.071098] Starting dynamic subtest: pipe-A-eDP-1-size-64
[556.530060] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.466s)
[556.589461] Starting dynamic subtest: pipe-A-eDP-1-size-128
[559.030886] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.449s)
[559.091388] Starting dynamic subtest: pipe-A-eDP-1-size-256
[561.554540] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.471s)
[561.906802] Starting dynamic subtest: pipe-B-eDP-1-size-64
[565.066974] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.172s)
[565.124291] Starting dynamic subtest: pipe-B-eDP-1-size-128
[567.579537] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.460s)
[567.638336] Starting dynamic subtest: pipe-B-eDP-1-size-256
[570.058066] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.427s)
[570.408082] Starting dynamic subtest: pipe-C-eDP-1-size-64
[573.257741] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.861s)
[573.295581] Starting dynamic subtest: pipe-C-eDP-1-size-128
[575.380457] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.091s)
[575.416214] Starting dynamic subtest: pipe-C-eDP-1-size-256
[577.558242] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.147s)
[577.891680] Starting dynamic subtest: pipe-D-eDP-1-size-64
[580.714450] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.832s)
[580.751857] Starting dynamic subtest: pipe-D-eDP-1-size-128
[582.816551] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.069s)
[582.852187] Starting dynamic subtest: pipe-D-eDP-1-size-256
[584.948510] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.103s)
[585.273979] Subtest viewport: SUCCESS (31.208s)
[586.110111] [053/133] (434s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[586.189393] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[590.683350] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.499s)
[591.800225] [054/133] (428s left) gem_mmap_gtt (bad-object)
[591.875890] Subtest bad-object: SKIP (0.000s)
[591.939730] [055/133] (428s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[592.033851] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[592.038333] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[592.123866] [056/133] (428s left) gem_vm_create (invalid-destroy)
[592.199058] Starting subtest: invalid-destroy
[592.201421] Subtest invalid-destroy: SUCCESS (0.002s)
[592.302774] [057/133] (428s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[592.387987] Starting subtest: modeset-transition-nonblocking-fencing
[592.389937] Starting dynamic subtest: 1x-outputs
[603.261294] Dynamic subtest 1x-outputs: SUCCESS (10.873s)
[603.264197] Subtest modeset-transition-nonblocking-fencing: SUCCESS (10.875s)
[604.125922] [058/133] (416s left) syncobj_basic (bad-destroy)
[604.179168] Starting subtest: bad-destroy
[604.181363] Subtest bad-destroy: SUCCESS (0.000s)
[604.268264] [059/133] (416s left) kms_psr (fbc-psr-primary-mmap-cpu)
[604.342229] Starting subtest: fbc-psr-primary-mmap-cpu
[604.344779] Starting dynamic subtest: eDP-1
[604.348820] Dynamic subtest eDP-1: SKIP (0.000s)
[604.352897] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s)
[604.419997] [060/133] (416s left) kms_draw_crc (draw-method-render)
[604.491995] Starting subtest: draw-method-render
[604.494395] Starting dynamic subtest: xrgb8888-untiled
[604.735066] Dynamic subtest xrgb8888-untiled: SUCCESS (0.243s)
[604.746653] Starting dynamic subtest: rgb565-untiled
[604.979264] Dynamic subtest rgb565-untiled: SUCCESS (0.242s)
[604.988236] Starting dynamic subtest: xrgb2101010-untiled
[605.212035] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.232s)
[605.220769] Starting dynamic subtest: xbgr16161616f-untiled
[605.445469] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[605.453794] Starting dynamic subtest: xrgb8888-xtiled
[605.567852] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[605.576104] Starting dynamic subtest: rgb565-xtiled
[605.679225] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[605.689774] Starting dynamic subtest: xrgb2101010-xtiled
[605.801050] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[605.809842] Starting dynamic subtest: xbgr16161616f-xtiled
[605.934412] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[605.942705] Starting dynamic subtest: xrgb8888-4tiled
[606.056672] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[606.065287] Starting dynamic subtest: rgb565-4tiled
[606.167739] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[606.178543] Starting dynamic subtest: xrgb2101010-4tiled
[606.289971] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s)
[606.298804] Starting dynamic subtest: xbgr16161616f-4tiled
[606.423295] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[606.425132] Subtest draw-method-render: SUCCESS (1.933s)
[606.530119] [061/133] (414s left) kms_flip (2x-flip-vs-modeset)
[606.610039] Starting subtest: 2x-flip-vs-modeset
[606.615927] Subtest 2x-flip-vs-modeset: SKIP (0.007s)
[606.719853] [062/133] (414s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[606.784859] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[606.893060] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.107s)
[606.967683] [063/133] (413s left) kms_sequence (get-forked-busy)
[607.048542] Starting subtest: get-forked-busy
[607.050807] Starting dynamic subtest: pipe-A-eDP-1
[608.994467] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.943s)
[609.004689] Starting dynamic subtest: pipe-B-eDP-1
[611.601753] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.607s)
[611.611998] Starting dynamic subtest: pipe-C-eDP-1
[614.227422] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.625s)
[614.236868] Starting dynamic subtest: pipe-D-eDP-1
[616.778344] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.549s)
[616.780343] Subtest get-forked-busy: SUCCESS (9.729s)
[617.659276] [064/133] (403s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[617.725713] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[617.804545] [065/133] (403s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[617.884499] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[617.886814] Starting dynamic subtest: pipe-A-eDP-1
[617.909011] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[617.918261] Starting dynamic subtest: pipe-B-eDP-1
[617.939779] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[617.948479] Starting dynamic subtest: pipe-C-eDP-1
[617.970217] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[617.978399] Starting dynamic subtest: pipe-D-eDP-1
[618.000231] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[618.004251] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[620.042377] [066/133] (400s left) kms_lease (lease-invalid-crtc)
[620.116826] Starting subtest: lease-invalid-crtc
[620.118987] Starting dynamic subtest: pipe-A-eDP-1
[620.121376] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[620.130509] Starting dynamic subtest: pipe-B-eDP-1
[620.132360] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[620.140916] Starting dynamic subtest: pipe-C-eDP-1
[620.143078] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[620.151545] Starting dynamic subtest: pipe-D-eDP-1
[620.153417] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[620.155286] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[620.193284] [067/133] (400s left) gem_exec_await (wide-contexts)
[620.404137] Starting subtest: wide-contexts
[643.981701] Subtest wide-contexts: SUCCESS (23.575s)
[644.173110] [068/133] (376s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[644.295602] Starting subtest: fbc-psr2-primary-mmap-gtt
[644.297892] Starting dynamic subtest: eDP-1
[644.301823] Dynamic subtest eDP-1: SKIP (0.000s)
[644.305760] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[644.367702] [069/133] (376s left) gem_tiled_pread_pwrite
[644.469958] [070/133] (376s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[644.543120] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[644.547142] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[644.656034] [071/133] (376s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[645.014903] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[645.852570] [072/133] (375s left) perf (invalid-oa-metric-set-id)
[645.964723] Starting subtest: invalid-oa-metric-set-id
[645.978167] Subtest invalid-oa-metric-set-id: SUCCESS (0.012s)
[646.017656] [073/133] (375s left) i915_drm_fdinfo (busy)
[646.057507] Starting subtest: busy
[646.059970] Starting dynamic subtest: rcs0
[646.077059] Dynamic subtest rcs0: SKIP (0.018s)
[646.087722] Starting dynamic subtest: bcs0
[646.092439] Dynamic subtest bcs0: SKIP (0.013s)
[646.100955] Starting dynamic subtest: vcs0
[646.105163] Dynamic subtest vcs0: SKIP (0.000s)
[646.113740] Starting dynamic subtest: vcs1
[646.118340] Dynamic subtest vcs1: SKIP (0.000s)
[646.126481] Starting dynamic subtest: vecs0
[646.130644] Dynamic subtest vecs0: SKIP (0.000s)
[646.138714] Starting dynamic subtest: ccs0
[646.142864] Dynamic subtest ccs0: SKIP (0.000s)
[646.147058] Subtest busy: SKIP (0.036s)
[646.183409] [074/133] (375s left) gem_exec_flush (basic-wb-pro-default)
[646.244543] Starting subtest: basic-wb-pro-default
[651.696386] Subtest basic-wb-pro-default: SUCCESS (5.453s)
[651.885023] [075/133] (369s left) perf_pmu (semaphore-busy)
[651.935329] Starting subtest: semaphore-busy
[651.937764] Starting dynamic subtest: rcs0
[653.551046] Dynamic subtest rcs0: SUCCESS (1.617s)
[653.564575] Starting dynamic subtest: bcs0
[655.182853] Dynamic subtest bcs0: SUCCESS (1.630s)
[655.195390] Starting dynamic subtest: vcs0
[659.347751] Dynamic subtest vcs0: SUCCESS (4.162s)
[659.358930] Starting dynamic subtest: vcs1
[665.364589] Dynamic subtest vcs1: SUCCESS (6.014s)
[665.376092] Starting dynamic subtest: vecs0
[671.380394] Dynamic subtest vecs0: SUCCESS (6.013s)
[671.391467] Starting dynamic subtest: ccs0
[674.463308] Dynamic subtest ccs0: SUCCESS (3.079s)
[674.465887] Subtest semaphore-busy: SUCCESS (22.530s)
[674.574589] [076/133] (346s left) gem_userptr_blits (usage-restrictions)
[674.640765] Starting subtest: usage-restrictions
[674.642825] Subtest usage-restrictions: SUCCESS (0.000s)
[674.756914] [077/133] (346s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[674.836100] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[681.971760] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.134s)
[682.105366] [078/133] (339s left) kms_addfb_basic (bad-pitch-128)
[682.167218] Starting subtest: bad-pitch-128
[682.169716] Subtest bad-pitch-128: SUCCESS (0.000s)
[682.278974] [079/133] (339s left) intel_hwmon (hwmon-read)
[682.341324] Subtest hwmon-read: SKIP (0.000s)
[682.403439] [080/133] (339s left) device_reset (unbind-reset-rebind)
[682.452876] Starting subtest: unbind-reset-rebind
[685.467981] Subtest unbind-reset-rebind: SUCCESS (3.013s)
[685.545578] [081/133] (336s left) fbdev (nullptr)
[685.601715] Starting subtest: nullptr
[685.603981] Subtest nullptr: SUCCESS (0.000s)
[685.658401] [082/133] (335s left) kms_pm_backlight (fade-with-dpms)
[685.735971] Starting subtest: fade-with-dpms
[687.923730] Starting dynamic subtest: eDP-1
[690.393779] Dynamic subtest eDP-1: SUCCESS (2.480s)
[690.396354] Subtest fade-with-dpms: SUCCESS (4.660s)
[691.218030] [083/133] (330s left) syncobj_timeline (etime-single-wait-submitted)
[691.272745] Starting subtest: etime-single-wait-submitted
[691.381075] Subtest etime-single-wait-submitted: SUCCESS (0.109s)
[691.454070] [084/133] (330s left) api_intel_bb (render)
[691.499974] Starting subtest: render
[691.501814] Starting dynamic subtest: render-none-512
[691.601740] Dynamic subtest render-none-512: SUCCESS (0.102s)
[691.613584] Starting dynamic subtest: render-none-1024
[691.990778] Dynamic subtest render-none-1024: SUCCESS (0.386s)
[692.001833] Starting dynamic subtest: render-x-512
[692.073063] Dynamic subtest render-x-512: SUCCESS (0.082s)
[692.084437] Starting dynamic subtest: render-x-1024
[692.406913] Dynamic subtest render-x-1024: SUCCESS (0.332s)
[692.418801] Starting dynamic subtest: render-y-512
[692.506264] Dynamic subtest render-y-512: SUCCESS (0.097s)
[692.518664] Starting dynamic subtest: render-y-1024
[692.946088] Dynamic subtest render-y-1024: SUCCESS (0.438s)
[692.948803] Subtest render: SUCCESS (1.447s)
[693.074092] [085/133] (328s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[693.153235] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[693.157112] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[693.253165] [086/133] (328s left) gem_render_copy (yf-tiled)
[693.377680] Starting subtest: yf-tiled
[693.382541] Subtest yf-tiled: SKIP (0.000s)
[693.594661] [087/133] (328s left) gem_exec_balancer (full-late-pulse)
[693.814744] Starting subtest: full-late-pulse
[698.657116] Subtest full-late-pulse: SUCCESS (4.842s)
[698.944223] [088/133] (322s left) gem_exec_schedule (pi-distinct-iova)
[699.133196] Starting subtest: pi-distinct-iova
[699.135613] Starting dynamic subtest: rcs0
[699.251449] Dynamic subtest rcs0: SUCCESS (0.121s)
[699.264456] Starting dynamic subtest: vcs0
[699.335387] Dynamic subtest vcs0: SUCCESS (0.084s)
[699.348385] Starting dynamic subtest: vcs1
[699.418978] Dynamic subtest vcs1: SUCCESS (0.083s)
[699.435068] Starting dynamic subtest: vecs0
[699.500951] Dynamic subtest vecs0: SUCCESS (0.081s)
[699.514548] Starting dynamic subtest: ccs0
[699.601141] Dynamic subtest ccs0: SUCCESS (0.100s)
[699.603664] Subtest pi-distinct-iova: SUCCESS (0.474s)
[700.925510] [089/133] (321s left) kms_psr (pr-cursor-mmap-cpu)
[701.016121] Starting subtest: pr-cursor-mmap-cpu
[701.020615] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[701.089919] [090/133] (320s left) gem_request_retire (retire-vma-not-inactive)
[701.147329] Starting subtest: retire-vma-not-inactive
[701.209039] Subtest retire-vma-not-inactive: SUCCESS (0.059s)
[701.348274] [091/133] (320s left) gem_ctx_create (basic-files)
[701.420599] Starting subtest: basic-files
[703.429881] Subtest basic-files: SUCCESS (2.010s)
[704.912029] [092/133] (317s left) kms_flip (2x-blocking-wf_vblank)
[704.997085] Starting subtest: 2x-blocking-wf_vblank
[705.003812] Subtest 2x-blocking-wf_vblank: SKIP (0.008s)
[705.096191] [093/133] (317s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[705.172719] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[705.174854] Starting dynamic subtest: pipe-A-default-mode
[706.371766] Dynamic subtest pipe-A-default-mode: SKIP (1.195s)
[706.376063] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.196s)
[706.516351] [094/133] (315s left) gem_ctx_persistence (engines-hostile)
[706.568381] Starting subtest: engines-hostile
[706.570753] Starting dynamic subtest: rcs0
[706.694820] Dynamic subtest rcs0: SUCCESS (0.126s)
[706.708093] Starting dynamic subtest: bcs0
[706.731874] Dynamic subtest bcs0: SUCCESS (0.035s)
[706.741853] Starting dynamic subtest: vcs0
[706.826227] Dynamic subtest vcs0: SUCCESS (0.093s)
[706.838577] Starting dynamic subtest: vcs1
[706.918713] Dynamic subtest vcs1: SUCCESS (0.091s)
[706.930958] Starting dynamic subtest: vecs0
[707.013039] Dynamic subtest vecs0: SUCCESS (0.092s)
[707.028564] Starting dynamic subtest: ccs0
[707.106687] Dynamic subtest ccs0: SUCCESS (0.091s)
[707.109216] Subtest engines-hostile: SUCCESS (0.540s)
[707.186559] [095/133] (315s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[707.270975] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[707.274325] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[707.379492] [096/133] (314s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[707.466847] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[707.470499] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[707.563168] [097/133] (314s left) gem_lmem_swapping (parallel-random)
[707.607423] Subtest parallel-random: SKIP (0.000s)
[707.648134] [098/133] (314s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[707.732340] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[712.284068] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.556s)
[713.445348] [099/133] (308s left) perf_pmu (init-wait)
[713.488961] Starting subtest: init-wait
[713.491265] Starting dynamic subtest: rcs0
[713.493543] Dynamic subtest rcs0: SUCCESS (0.001s)
[713.503188] Starting dynamic subtest: bcs0
[713.505164] Dynamic subtest bcs0: SUCCESS (0.001s)
[713.514067] Starting dynamic subtest: vcs0
[713.515988] Dynamic subtest vcs0: SUCCESS (0.001s)
[713.525275] Starting dynamic subtest: vcs1
[713.527422] Dynamic subtest vcs1: SUCCESS (0.001s)
[713.536630] Starting dynamic subtest: vecs0
[713.538529] Dynamic subtest vecs0: SUCCESS (0.001s)
[713.547790] Starting dynamic subtest: ccs0
[713.549976] Dynamic subtest ccs0: SUCCESS (0.000s)
[713.552111] Subtest init-wait: SUCCESS (0.008s)
[713.591255] [100/133] (308s left) vgem_basic (dmabuf-fence)
[713.635499] Starting subtest: dmabuf-fence
[713.637793] Subtest dmabuf-fence: SUCCESS (0.000s)
[713.693881] [101/133] (308s left) gem_fence_thrash (bo-copy)
[713.734870] Starting subtest: bo-copy
[713.759486] Subtest bo-copy: SKIP (0.022s)
[713.820275] [102/133] (308s left) kms_psr (psr2-cursor-plane-move)
[713.896998] Starting subtest: psr2-cursor-plane-move
[713.899231] Starting dynamic subtest: eDP-1
[715.607095] Dynamic subtest eDP-1: SUCCESS (1.709s)
[715.609363] Subtest psr2-cursor-plane-move: SUCCESS (1.711s)
[716.405303] [103/133] (306s left) kms_vblank (ts-continuation-dpms-suspend)
[716.485241] Starting subtest: ts-continuation-dpms-suspend
[716.487528] Starting dynamic subtest: pipe-A-eDP-1
[733.791181] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.987s)
[733.808558] Starting dynamic subtest: pipe-D-eDP-1
[751.807291] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.528s)
[751.809610] Subtest ts-continuation-dpms-suspend: SUCCESS (4.519s)
[752.618008] [104/133] (269s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[752.694738] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[752.722356] Starting dynamic subtest: pipe-A-plane-0
[753.011643] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[753.024920] Starting dynamic subtest: pipe-A-plane-3
[753.245332] Dynamic subtest pipe-A-plane-3: SUCCESS (0.231s)
[754.310900] Starting dynamic subtest: pipe-B-plane-0
[754.645908] Dynamic subtest pipe-B-plane-0: SUCCESS (0.344s)
[754.656380] Starting dynamic subtest: pipe-B-plane-3
[754.879682] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[755.206789] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.506s)
[756.013820] [105/133] (266s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[756.091958] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[756.096347] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[756.188020] [106/133] (266s left) gen9_exec_parse (bb-oversize)
[756.241443] Subtest bb-oversize: SKIP (0.000s)
[756.314604] [107/133] (266s left) gem_mmap_gtt (basic-write-gtt)
[756.371974] Subtest basic-write-gtt: SKIP (0.000s)
[756.430839] [108/133] (266s left) kms_panel_fitting (legacy)
[756.499829] Starting subtest: legacy
[756.502122] Starting dynamic subtest: pipe-A-eDP-1
[757.056635] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.558s)
[757.086925] Starting dynamic subtest: pipe-B-eDP-1
[758.664449] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.585s)
[758.692405] Starting dynamic subtest: pipe-C-eDP-1
[760.174343] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.487s)
[760.201452] Starting dynamic subtest: pipe-D-eDP-1
[761.741632] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.547s)
[761.743777] Subtest legacy: SUCCESS (5.237s)
[762.980123] [109/133] (259s left) syncobj_timeline (wait-delayed-signal)
[763.040400] Starting subtest: wait-delayed-signal
[763.141906] Subtest wait-delayed-signal: SUCCESS (0.102s)
[763.221776] [110/133] (259s left) perf_pmu (busy-accuracy-2)
[763.267960] Starting subtest: busy-accuracy-2
[763.270309] Starting dynamic subtest: rcs0
[766.317173] Dynamic subtest rcs0: SUCCESS (3.042s)
[766.328572] Starting dynamic subtest: bcs0
[769.376264] Dynamic subtest bcs0: SUCCESS (3.054s)
[769.388800] Starting dynamic subtest: vcs0
[772.424490] Dynamic subtest vcs0: SUCCESS (3.045s)
[772.436987] Starting dynamic subtest: vcs1
[775.471947] Dynamic subtest vcs1: SUCCESS (3.042s)
[775.485126] Starting dynamic subtest: vecs0
[778.516152] Dynamic subtest vecs0: SUCCESS (3.040s)
[778.527156] Starting dynamic subtest: ccs0
[781.572936] Dynamic subtest ccs0: SUCCESS (3.054s)
[781.575231] Subtest busy-accuracy-2: SUCCESS (18.294s)
[781.691843] [111/133] (241s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[781.808504] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[781.810675] Starting dynamic subtest: pipe-A-eDP-1
[783.061838] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.255s)
[783.074926] Starting dynamic subtest: pipe-B-eDP-1
[785.340195] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.273s)
[785.351609] Starting dynamic subtest: pipe-C-eDP-1
[787.579992] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.238s)
[787.591912] Starting dynamic subtest: pipe-D-eDP-1
[789.839684] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.257s)
[789.842177] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.032s)
[790.994377] [112/133] (231s left) gem_ctx_persistence (heartbeat-close)
[791.047491] Starting subtest: heartbeat-close
[791.052172] Subtest heartbeat-close: SKIP (0.000s)
[791.169092] [113/133] (231s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[791.247786] Starting subtest: x-tiled-64bpp-rotate-180
[792.750147] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.502s)
[792.910781] [114/133] (230s left) gem_ctx_param (invalid-ctx-get)
[792.968793] Starting subtest: invalid-ctx-get
[792.971042] Subtest invalid-ctx-get: SUCCESS (0.000s)
[793.050400] [115/133] (230s left) kms_cursor_crc (cursor-random-32x32)
[793.171212] Starting subtest: cursor-random-32x32
[793.238058] Subtest cursor-random-32x32: SKIP (0.063s)
[793.500774] [116/133] (229s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[793.601399] Starting subtest: basic-flip-before-cursor-varying-size
[794.530384] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.929s)
[795.330033] [117/133] (227s left) gem_userptr_blits (relocations)
[795.389483] Starting subtest: relocations
[795.393468] Subtest relocations: SKIP (0.000s)
[795.531265] [118/133] (227s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[795.611600] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[796.638193] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.024s)
[796.785323] [119/133] (226s left) gem_spin_batch (user-each)
[796.840436] Starting subtest: user-each
[800.116853] Subtest user-each: SUCCESS (3.266s)
[800.700242] [120/133] (222s left) kms_cursor_edge_walk (256x256-top-edge)
[800.773250] Starting subtest: 256x256-top-edge
[800.775693] Starting dynamic subtest: pipe-A-eDP-1
[804.217180] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.441s)
[804.228417] Starting dynamic subtest: pipe-D-eDP-1
[807.334520] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.116s)
[807.336950] Subtest 256x256-top-edge: SUCCESS (6.560s)
[808.135129] [121/133] (215s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[808.217780] Starting subtest: 4-tiled-32bpp-rotate-180
[809.654220] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.436s)
[809.779639] [122/133] (213s left) gem_mmap_offset (clear-via-pagefault)
[809.832027] Starting subtest: clear-via-pagefault
[809.833845] Starting dynamic subtest: smem0
[835.764155] Dynamic subtest smem0: SUCCESS (25.917s)
[835.766642] Subtest clear-via-pagefault: SUCCESS (25.920s)
[835.894428] [123/133] (187s left) gem_userptr_blits (invalid-null-pointer)
[835.962485] Starting subtest: invalid-null-pointer
[835.964845] Subtest invalid-null-pointer: SUCCESS (0.001s)
[836.099960] [124/133] (187s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[836.188411] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[836.192649] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[836.279765] [125/133] (187s left) gem_ctx_shared (detached-shared-gtt)
[836.334803] Starting subtest: detached-shared-gtt
[838.488610] Subtest detached-shared-gtt: SUCCESS (2.152s)
[838.874772] [126/133] (184s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[838.968333] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[840.047336] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.078s)
[840.202184] [127/133] (183s left) kms_psr (fbc-pr-no-drrs)
[840.282979] Starting subtest: fbc-pr-no-drrs
[840.286467] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[840.371140] [128/133] (183s left) gem_create (create-ext-placement-sanity-check)
[840.535624] Starting subtest: create-ext-placement-sanity-check
[840.538409] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[840.632262] [129/133] (183s left) kms_properties (colorop-properties-legacy)
[840.711670] Starting subtest: colorop-properties-legacy
[840.714144] Starting dynamic subtest: pipe-A-eDP-1
[841.047065] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.335s)
[841.058237] Starting dynamic subtest: pipe-B-eDP-1
[842.122122] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.074s)
[842.133168] Starting dynamic subtest: pipe-C-eDP-1
[843.158706] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.035s)
[843.171799] Starting dynamic subtest: pipe-D-eDP-1
[844.193751] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.032s)
[844.196099] Subtest colorop-properties-legacy: SUCCESS (3.482s)
[844.999845] [130/133] (178s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[845.087439] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[849.476632] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.398s)
[850.593729] [131/133] (173s left) gem_ppgtt (shrink-vs-evict-pinned)
[850.647392] Starting subtest: shrink-vs-evict-pinned
[856.644926] Subtest shrink-vs-evict-pinned: SUCCESS (6.000s)
[856.718325] [132/133] (167s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[856.799606] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[856.803717] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[856.883363] [133/133] (167s left) kms_plane (plane-panning-bottom-right-suspend)
[856.956258] Starting subtest: plane-panning-bottom-right-suspend
[856.958173] Starting dynamic subtest: pipe-A
[874.624080] Dynamic subtest pipe-A: SUCCESS (2.895s)
[874.636083] Starting dynamic subtest: pipe-B
[893.619722] Dynamic subtest pipe-B: SUCCESS (3.523s)
[893.622422] Subtest plane-panning-bottom-right-suspend: SUCCESS (6.421s)
[894.454255] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: api_intel_allocator
results: parsing output: 3/ for test: i915_selftest
results: parsing output: 4/ for test: kms_rotation_crc
results: parsing output: 5/ for test: gem_userptr_blits
results: parsing output: 6/ for test: sw_sync
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: gem_exec_schedule
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: kms_cursor_legacy
results: parsing output: 11/ for test: kms_flip_scaled_crc
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: i915_pm_rpm
results: parsing output: 14/ for test: kms_dirtyfb
results: parsing output: 15/ for test: kms_hdr
results: parsing output: 16/ for test: kms_content_protection
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_fence
results: parsing output: 20/ for test: gem_userptr_blits
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: gem_exec_nop
results: parsing output: 23/ for test: kms_psr2_sf
results: parsing output: 24/ for test: gem_partial_pwrite_pread
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_pm_rpm
results: parsing output: 29/ for test: perf
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_getfb
results: parsing output: 32/ for test: gem_exec_reloc
results: parsing output: 33/ for test: gem_exec_fence
results: parsing output: 34/ for test: kms_getfb
results: parsing output: 35/ for test: kms_cursor_crc
results: parsing output: 36/ for test: kms_plane_alpha_blend
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: kms_color_pipeline
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_hdmi_inject
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: gem_render_copy
results: parsing output: 43/ for test: kms_plane_scaling
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_flip_scaled_crc
results: parsing output: 46/ for test: i915_hangman
results: parsing output: 47/ for test: kms_chamelium_edid
results: parsing output: 48/ for test: kms_properties
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_mmap_gtt
results: parsing output: 51/ for test: kms_plane_cursor
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_mmap_gtt
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: gem_vm_create
results: parsing output: 56/ for test: kms_atomic_transition
results: parsing output: 57/ for test: syncobj_basic
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: kms_draw_crc
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_sequence
results: parsing output: 63/ for test: gem_exec_reloc
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: kms_lease
results: parsing output: 66/ for test: gem_exec_await
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: gem_tiled_pread_pwrite
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_chamelium_hpd
results: parsing output: 71/ for test: perf
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: gem_exec_flush
results: parsing output: 74/ for test: perf_pmu
results: parsing output: 75/ for test: gem_userptr_blits
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_addfb_basic
results: parsing output: 78/ for test: intel_hwmon
results: parsing output: 79/ for test: device_reset
results: parsing output: 80/ for test: fbdev
results: parsing output: 81/ for test: kms_pm_backlight
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: api_intel_bb
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_render_copy
results: parsing output: 86/ for test: gem_exec_balancer
results: parsing output: 87/ for test: gem_exec_schedule
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: gem_request_retire
results: parsing output: 90/ for test: gem_ctx_create
results: parsing output: 91/ for test: kms_flip
results: parsing output: 92/ for test: kms_flip_scaled_crc
results: parsing output: 93/ for test: gem_ctx_persistence
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_lmem_swapping
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: perf_pmu
results: parsing output: 99/ for test: vgem_basic
results: parsing output: 100/ for test: gem_fence_thrash
results: parsing output: 101/ for test: kms_psr
results: parsing output: 102/ for test: kms_vblank
results: parsing output: 103/ for test: kms_plane
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gen9_exec_parse
results: parsing output: 106/ for test: gem_mmap_gtt
results: parsing output: 107/ for test: kms_panel_fitting
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: perf_pmu
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: gem_ctx_persistence
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: gem_ctx_param
results: parsing output: 114/ for test: kms_cursor_crc
results: parsing output: 115/ for test: kms_cursor_legacy
results: parsing output: 116/ for test: gem_userptr_blits
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: gem_spin_batch
results: parsing output: 119/ for test: kms_cursor_edge_walk
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: gem_mmap_offset
results: parsing output: 122/ for test: gem_userptr_blits
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_ctx_shared
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: gem_create
results: parsing output: 128/ for test: kms_properties
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_ppgtt
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_plane
Done.