[57.547774] Initializing watchdogs
[57.547819]   /dev/watchdog0
[57.562019] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.566310] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.568456] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.571822] [001/132] (960s left) gem_exec_balancer (bonded-sync)
[57.663962] Starting subtest: bonded-sync
[57.668765] Subtest bonded-sync: SKIP (0.000s)
[57.972908] [002/132] (960s left) gem_render_copy (yf-tiled-to-vebox-linear)
[58.040576] Starting subtest: yf-tiled-to-vebox-linear
[58.045354] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[58.224736] [003/132] (959s left) sw_sync (sync_busy)
[58.273697] Starting subtest: sync_busy
[58.276126] Subtest sync_busy: SUCCESS (0.002s)
[58.324173] [004/132] (959s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[58.432097] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[58.434545] Starting dynamic subtest: pipe-A
[58.453374] Dynamic subtest pipe-A: SKIP (0.018s)
[58.461206] Starting dynamic subtest: pipe-B
[58.486298] Dynamic subtest pipe-B: SKIP (0.032s)
[58.493849] Starting dynamic subtest: pipe-C
[58.519128] Dynamic subtest pipe-C: SKIP (0.033s)
[58.525944] Starting dynamic subtest: pipe-D
[58.553723] Dynamic subtest pipe-D: SKIP (0.034s)
[58.558172] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.118s)
[58.649050] [005/132] (959s left) kms_pm_rpm (drm-resources-equal)
[60.210569] Starting subtest: drm-resources-equal
[65.604386] Subtest drm-resources-equal: SUCCESS (5.373s)
[65.707165] [006/132] (952s left) perf_pmu (init-wait)
[65.747544] Starting subtest: init-wait
[65.749892] Starting dynamic subtest: rcs0
[65.752322] Dynamic subtest rcs0: SUCCESS (0.001s)
[65.761886] Starting dynamic subtest: bcs0
[65.763729] Dynamic subtest bcs0: SUCCESS (0.001s)
[65.770730] Starting dynamic subtest: vcs0
[65.772688] Dynamic subtest vcs0: SUCCESS (0.001s)
[65.779064] Starting dynamic subtest: vcs1
[65.780883] Dynamic subtest vcs1: SUCCESS (0.000s)
[65.787901] Starting dynamic subtest: vecs0
[65.790109] Dynamic subtest vecs0: SUCCESS (0.001s)
[65.797070] Starting dynamic subtest: ccs0
[65.798987] Dynamic subtest ccs0: SUCCESS (0.001s)
[65.801080] Subtest init-wait: SUCCESS (0.006s)
[65.849282] [007/132] (952s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[65.982822] Starting subtest: cursorB-vs-flipA-toggle
[65.988063] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[66.119121] [008/132] (952s left) gem_lmem_swapping (parallel-random-verify)
[66.165029] Subtest parallel-random-verify: SKIP (0.000s)
[66.205969] [009/132] (952s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[66.298678] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[66.302806] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[66.397309] [010/132] (952s left) i915_module_load (resize-bar)
[66.444437] Starting subtest: resize-bar
[66.451097] Subtest resize-bar: SKIP (0.007s)
[66.493122] [011/132] (951s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[66.586910] Starting subtest: fbc-psr2-cursor-plane-update-sf
[66.588920] Starting dynamic subtest: pipe-A-eDP-1
[66.686912] Dynamic subtest pipe-A-eDP-1: SKIP (0.102s)
[66.695898] Starting dynamic subtest: pipe-B-eDP-1
[67.804193] Dynamic subtest pipe-B-eDP-1: SKIP (1.112s)
[67.808810] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.216s)
[69.300892] [012/132] (949s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[69.363651] Subtest tiled-to-tiled: SKIP (0.000s)
[69.429946] [013/132] (949s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[69.545023] Starting subtest: short-flip-after-cursor-atomic-transitions
[70.504664] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.957s)
[71.324021] [014/132] (947s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[71.424363] Starting subtest: psr-dirtyfb-ioctl
[71.426393] Starting dynamic subtest: A-eDP-1
[75.275678] Dynamic subtest A-eDP-1: SUCCESS (3.848s)
[75.278129] Subtest psr-dirtyfb-ioctl: SUCCESS (3.850s)
[76.145638] [015/132] (942s left) kms_flip (2x-flip-vs-modeset)
[76.248222] Starting subtest: 2x-flip-vs-modeset
[76.256717] Subtest 2x-flip-vs-modeset: SKIP (0.010s)
[76.368887] [016/132] (942s left) kms_plane (pixel-format-4-tiled-modifier)
[76.465917] Starting subtest: pixel-format-4-tiled-modifier
[76.493053] Starting dynamic subtest: pipe-A-plane-0
[77.115108] Dynamic subtest pipe-A-plane-0: SUCCESS (0.622s)
[77.123428] Starting dynamic subtest: pipe-A-plane-3
[77.526358] Dynamic subtest pipe-A-plane-3: SUCCESS (0.411s)
[77.535071] Starting dynamic subtest: pipe-A-plane-5
[77.540402] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[78.638348] Starting dynamic subtest: pipe-B-plane-0
[79.264708] Dynamic subtest pipe-B-plane-0: SUCCESS (0.633s)
[79.272695] Starting dynamic subtest: pipe-B-plane-3
[79.687515] Dynamic subtest pipe-B-plane-3: SUCCESS (0.422s)
[79.694838] Starting dynamic subtest: pipe-B-plane-5
[79.698481] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[79.998658] Subtest pixel-format-4-tiled-modifier: SUCCESS (3.530s)
[80.812526] [017/132] (937s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[80.915855] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[80.920471] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[81.026403] [018/132] (937s left) syncobj_timeline (multi-wait-all-available-submitted)
[81.076671] Starting subtest: multi-wait-all-available-submitted
[81.078971] Subtest multi-wait-all-available-submitted: SUCCESS (0.001s)
[81.163748] [019/132] (937s left) gen9_exec_parse (allowed-all)
[81.227115] Subtest allowed-all: SKIP (0.000s)
[81.294531] [020/132] (937s left) intel_hwmon (hwmon-read)
[81.350583] Subtest hwmon-read: SKIP (0.000s)
[81.403103] [021/132] (937s left) fbdev (pan)
[81.443147] Starting subtest: pan
[81.472531] Subtest pan: SUCCESS (0.031s)
[81.540612] [022/132] (937s left) kms_rotation_crc (multiplane-rotation)
[81.633787] Starting subtest: multiplane-rotation
[99.053701] Subtest multiplane-rotation: SUCCESS (17.415s)
[99.164664] [023/132] (919s left) prime_vgem (wait)
[99.292412] Starting subtest: wait
[99.294638] Starting dynamic subtest: rcs0
[99.342443] Dynamic subtest rcs0: SUCCESS (0.050s)
[99.349566] Starting dynamic subtest: bcs0
[99.399556] Dynamic subtest bcs0: SUCCESS (0.056s)
[99.406626] Starting dynamic subtest: vcs0
[99.445839] Dynamic subtest vcs0: SUCCESS (0.045s)
[99.452812] Starting dynamic subtest: vcs1
[99.489993] Dynamic subtest vcs1: SUCCESS (0.043s)
[99.497296] Starting dynamic subtest: vecs0
[99.539575] Dynamic subtest vecs0: SUCCESS (0.049s)
[99.546653] Starting dynamic subtest: ccs0
[99.583655] Dynamic subtest ccs0: SUCCESS (0.044s)
[99.585538] Subtest wait: SUCCESS (0.291s)
[99.721398] [FACT prime_vgem (wait)] new: kernel.kmod_is_loaded.vgem: true
[99.724243] [024/132] (919s left) gem_exec_fence (syncobj-timeline-wait)
[100.256580] Starting subtest: syncobj-timeline-wait
[101.935673] Subtest syncobj-timeline-wait: SUCCESS (1.679s)
[102.181942] [025/132] (916s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[102.288098] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[109.481297] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.196s)
[109.614278] [026/132] (909s left) kms_flip (modeset-vs-vblank-race)
[109.707576] Starting subtest: modeset-vs-vblank-race
[109.709808] Starting dynamic subtest: A-eDP1
[114.381793] Dynamic subtest A-eDP1: SUCCESS (4.675s)
[114.392280] Starting dynamic subtest: B-eDP1
[119.123555] Dynamic subtest B-eDP1: SUCCESS (4.739s)
[119.138101] Starting dynamic subtest: C-eDP1
[123.735498] Dynamic subtest C-eDP1: SUCCESS (4.609s)
[123.747541] Starting dynamic subtest: D-eDP1
[128.376753] Dynamic subtest D-eDP1: SUCCESS (4.638s)
[128.384381] Subtest modeset-vs-vblank-race: SUCCESS (18.675s)
[129.531579] [027/132] (889s left) kms_flip (bo-too-big-interruptible)
[129.620484] Starting subtest: bo-too-big-interruptible
[129.622812] Starting dynamic subtest: A-eDP1
[130.894802] Dynamic subtest A-eDP1: SUCCESS (1.273s)
[130.902772] Starting dynamic subtest: B-eDP1
[132.129758] Dynamic subtest B-eDP1: SUCCESS (1.234s)
[132.139331] Starting dynamic subtest: C-eDP1
[133.383762] Dynamic subtest C-eDP1: SUCCESS (1.252s)
[133.391601] Starting dynamic subtest: D-eDP1
[134.628533] Dynamic subtest D-eDP1: SUCCESS (1.243s)
[134.634611] Subtest bo-too-big-interruptible: SUCCESS (5.012s)
[135.851149] [028/132] (883s left) gem_exec_schedule (timeslicing)
[135.925125] Starting subtest: timeslicing
[135.927170] Starting dynamic subtest: rcs0
[135.940408] Dynamic subtest rcs0: SUCCESS (0.021s)
[135.949994] Starting dynamic subtest: bcs0
[135.963149] Dynamic subtest bcs0: SUCCESS (0.021s)
[135.971106] Starting dynamic subtest: vcs0
[135.984355] Dynamic subtest vcs0: SUCCESS (0.020s)
[135.993337] Starting dynamic subtest: vcs1
[136.005874] Dynamic subtest vcs1: SUCCESS (0.021s)
[136.018952] Starting dynamic subtest: vecs0
[136.027433] Dynamic subtest vecs0: SUCCESS (0.019s)
[136.035064] Starting dynamic subtest: ccs0
[136.047581] Dynamic subtest ccs0: SUCCESS (0.021s)
[136.049969] Subtest timeslicing: SUCCESS (0.130s)
[137.914879] [029/132] (881s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[138.018444] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[138.022713] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[138.118087] [030/132] (881s left) gem_ctx_isolation (preservation-s3)
[138.184812] Starting subtest: preservation-S3
[138.187152] Starting dynamic subtest: rcs0
[154.365433] Dynamic subtest rcs0: SUCCESS (2.129s)
[154.374956] Starting dynamic subtest: bcs0
[170.362256] Dynamic subtest bcs0: SUCCESS (1.335s)
[170.373661] Starting dynamic subtest: vcs0
[186.384235] Dynamic subtest vcs0: SUCCESS (2.309s)
[186.395766] Starting dynamic subtest: vcs1
[204.394506] Dynamic subtest vcs1: SUCCESS (4.958s)
[204.406609] Starting dynamic subtest: vecs0
[222.379656] Dynamic subtest vecs0: SUCCESS (4.846s)
[222.389779] Starting dynamic subtest: ccs0
[240.388910] Dynamic subtest ccs0: SUCCESS (3.064s)
[240.391492] Subtest preservation-S3: SUCCESS (18.648s)
[240.617256] [031/132] (778s left) syncobj_basic (create-signaled)
[240.711489] Starting subtest: create-signaled
[240.714044] Subtest create-signaled: SUCCESS (0.000s)
[240.817964] [032/132] (778s left) kms_big_fb (linear-64bpp-rotate-270)
[240.918060] Starting subtest: linear-64bpp-rotate-270
[240.938387] Subtest linear-64bpp-rotate-270: SKIP (0.019s)
[241.054429] [033/132] (778s left) gem_close_race (basic-threads)
[241.133476] Starting subtest: basic-threads
[242.544773] Subtest basic-threads: SUCCESS (1.384s)
[242.742844] [034/132] (776s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[242.856375] Starting subtest: fbc-psr2-primary-mmap-gtt
[242.859312] Starting dynamic subtest: eDP-1
[242.864516] Dynamic subtest eDP-1: SKIP (0.000s)
[242.869868] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[242.950913] [035/132] (776s left) kms_cursor_crc (cursor-onscreen-64x21)
[243.154490] Starting subtest: cursor-onscreen-64x21
[243.204480] Subtest cursor-onscreen-64x21: SKIP (0.042s)
[243.448414] [036/132] (776s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[243.540151] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[247.350242] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.768s)
[248.554201] [037/132] (770s left) gem_spin_batch (user-each)
[248.615559] Starting subtest: user-each
[251.997100] Subtest user-each: SUCCESS (3.350s)
[252.235383] [038/132] (767s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[252.366510] Starting subtest: verify-pxp-execution-after-suspend-resume
[259.438360] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.047s)
[259.562172] [039/132] (760s left) gem_exec_balancer (parallel-contexts)
[259.828508] Starting subtest: parallel-contexts
[262.435953] Subtest parallel-contexts: SUCCESS (2.587s)
[263.296478] [040/132] (756s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[263.360095] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[263.460105] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.101s)
[263.559186] [041/132] (756s left) kms_joiner (basic-force-ultra-joiner)
[263.654228] Starting subtest: basic-force-ultra-joiner
[263.659372] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[263.719348] [042/132] (756s left) gem_workarounds (basic-read-fd)
[263.788478] Starting subtest: basic-read-fd
[263.798097] Subtest basic-read-fd: SUCCESS (0.011s)
[263.929966] [043/132] (755s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[264.040132] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[264.042458] Starting dynamic subtest: pipe-A-eDP-1
[269.922241] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.865s)
[269.933223] Starting dynamic subtest: pipe-B-eDP-1
[276.599611] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.663s)
[276.602186] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.531s)
[277.469398] [044/132] (742s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[277.578972] Starting subtest: yf-tiled-32bpp-rotate-180
[277.583918] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[277.677376] [045/132] (742s left) i915_pm_rpm (system-suspend-execbuf)
[279.307513] Starting subtest: system-suspend-execbuf
[340.360260] Subtest system-suspend-execbuf: SUCCESS (45.827s)
[340.730806] [046/132] (679s left) dmabuf_sync_file (export-wait-after-attach)
[340.772554] Starting subtest: export-wait-after-attach
[340.775049] Subtest export-wait-after-attach: SUCCESS (0.001s)
[340.831166] [047/132] (679s left) i915_drm_fdinfo (virtual-busy-hang)
[340.871506] Starting subtest: virtual-busy-hang
[340.877449] Subtest virtual-busy-hang: SKIP (0.000s)
[340.959901] [048/132] (679s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[341.063399] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[341.068671] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[341.168723] [049/132] (678s left) gem_exec_flush (basic-uc-prw-default)
[341.241055] Starting subtest: basic-uc-prw-default
[346.707640] Subtest basic-uc-prw-default: SUCCESS (5.467s)
[346.892699] [050/132] (673s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[347.003968] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[347.009242] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[347.109928] [051/132] (673s left) kms_content_protection (dp-mst-type-0-hdcp14)
[347.244070] Starting subtest: dp-mst-type-0-hdcp14
[347.248844] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[347.355609] [052/132] (672s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[347.447762] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[347.452955] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[347.542962] [053/132] (672s left) kms_flip (plain-flip-interruptible)
[347.628057] Starting subtest: plain-flip-interruptible
[347.630443] Starting dynamic subtest: A-eDP1
[349.314387] Dynamic subtest A-eDP1: SUCCESS (1.684s)
[349.323666] Starting dynamic subtest: B-eDP1
[350.943164] Dynamic subtest B-eDP1: SUCCESS (1.626s)
[350.952508] Starting dynamic subtest: C-eDP1
[352.573691] Dynamic subtest C-eDP1: SUCCESS (1.629s)
[352.583356] Starting dynamic subtest: D-eDP1
[354.212948] Dynamic subtest D-eDP1: SUCCESS (1.637s)
[354.218367] Subtest plain-flip-interruptible: SUCCESS (6.586s)
[355.387519] [054/132] (664s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[355.507489] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[355.512824] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[355.651582] [055/132] (664s left) gem_exec_schedule (preempt-queue)
[355.730802] Subtest preempt-queue: SKIP (0.000s)
[356.012083] [056/132] (664s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[356.099932] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[356.105311] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[356.191319] [057/132] (664s left) gem_ctx_exec (basic)
[356.244219] Starting subtest: basic
[356.249251] Subtest basic: SUCCESS (0.006s)
[356.427163] [058/132] (664s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[356.808766] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[357.597000] [059/132] (662s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[357.705598] Starting subtest: plane-upscale-factor-0-25-with-rotation
[357.708449] Starting dynamic subtest: pipe-A
[357.930596] Dynamic subtest pipe-A: SUCCESS (0.227s)
[357.940398] Starting dynamic subtest: pipe-B
[359.208669] Dynamic subtest pipe-B: SUCCESS (1.276s)
[359.218339] Starting dynamic subtest: pipe-C
[360.540489] Dynamic subtest pipe-C: SUCCESS (1.331s)
[360.549719] Starting dynamic subtest: pipe-D
[361.875641] Dynamic subtest pipe-D: SUCCESS (1.334s)
[361.878533] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.171s)
[363.059166] [060/132] (657s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[363.110634] Starting subtest: multi-wait-available-submitted-signaled
[363.113562] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[363.219175] [061/132] (657s left) kms_plane_multiple (tiling-yf)
[363.320395] Starting subtest: tiling-yf
[363.325563] Subtest tiling-yf: SKIP (0.000s)
[363.392811] [062/132] (657s left) kms_flip (2x-flip-vs-panning-interruptible)
[363.502078] Starting subtest: 2x-flip-vs-panning-interruptible
[363.512845] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.010s)
[363.588599] [063/132] (657s left) kms_sequence (queue-busy)
[363.692373] Starting subtest: queue-busy
[363.694910] Starting dynamic subtest: pipe-A-eDP-1
[365.617488] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.926s)
[365.630239] Starting dynamic subtest: pipe-B-eDP-1
[368.265667] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.642s)
[368.276517] Starting dynamic subtest: pipe-C-eDP-1
[370.882504] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.616s)
[370.893696] Starting dynamic subtest: pipe-D-eDP-1
[373.503646] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.618s)
[373.506741] Subtest queue-busy: SUCCESS (9.807s)
[374.312407] [064/132] (646s left) kms_flip (flip-vs-dpms-on-nop)
[374.422622] Starting subtest: flip-vs-dpms-on-nop
[374.425344] Starting dynamic subtest: A-eDP1
[376.298427] Dynamic subtest A-eDP1: SUCCESS (1.878s)
[376.309462] Starting dynamic subtest: B-eDP1
[378.145174] Dynamic subtest B-eDP1: SUCCESS (1.843s)
[378.156178] Starting dynamic subtest: C-eDP1
[379.965961] Dynamic subtest C-eDP1: SUCCESS (1.818s)
[379.977445] Starting dynamic subtest: D-eDP1
[381.797449] Dynamic subtest D-eDP1: SUCCESS (1.828s)
[381.800031] Subtest flip-vs-dpms-on-nop: SUCCESS (7.379s)
[382.982239] [065/132] (637s left) syncobj_wait (wait-delayed-signal)
[383.043944] Starting subtest: wait-delayed-signal
[383.146116] Subtest wait-delayed-signal: SUCCESS (0.102s)
[383.233578] [066/132] (637s left) gem_exercise_blt (fast-copy)
[383.284674] Starting subtest: fast-copy
[383.287481] Starting dynamic subtest: linear-smem-smem
[383.308086] Dynamic subtest linear-smem-smem: SUCCESS (0.024s)
[383.319551] Starting dynamic subtest: xmajor-smem-smem
[383.326580] Dynamic subtest xmajor-smem-smem: SUCCESS (0.018s)
[383.335649] Starting dynamic subtest: tile4-smem-smem
[383.344247] Dynamic subtest tile4-smem-smem: SUCCESS (0.017s)
[383.353587] Starting dynamic subtest: tile64-smem-smem
[383.361769] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[383.364629] Subtest fast-copy: SUCCESS (0.079s)
[383.487158] [067/132] (637s left) prime_mmap (test_forked)
[383.545445] Starting subtest: test_forked
[383.548111] Starting dynamic subtest: test_forked-smem
[383.581556] Dynamic subtest test_forked-smem: SUCCESS (0.038s)
[383.584455] Subtest test_forked: SUCCESS (0.040s)
[383.687667] [068/132] (637s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[383.812650] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[383.818290] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[383.915687] [069/132] (637s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[384.011075] Starting subtest: plane-primary-toggle-with-vblank-wait
[384.013631] Starting dynamic subtest: pipe-A-eDP-1
[384.182834] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.174s)
[384.488975] Starting dynamic subtest: pipe-B-eDP-1
[385.375631] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.897s)
[385.677545] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.667s)
[386.541410] [070/132] (634s left) kms_big_fb (4-tiled-addfb)
[386.638595] Starting subtest: 4-tiled-addfb
[386.641087] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[386.736940] [071/132] (634s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[386.831369] Starting subtest: primary-yf-tiled-reflect-x-90
[386.836275] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[386.903635] [072/132] (634s left) gem_ctx_persistence (legacy-engines-mixed-process)
[386.950897] Starting subtest: legacy-engines-mixed-process
[386.953644] Starting dynamic subtest: render
[388.090144] Dynamic subtest render: SUCCESS (1.138s)
[388.101319] Starting dynamic subtest: bsd1
[390.080634] Dynamic subtest bsd1: SUCCESS (1.988s)
[390.090649] Starting dynamic subtest: bsd2
[392.131998] Dynamic subtest bsd2: SUCCESS (2.049s)
[392.144689] Starting dynamic subtest: blt
[394.103192] Dynamic subtest blt: SUCCESS (1.969s)
[394.113539] Starting dynamic subtest: vebox
[396.153423] Dynamic subtest vebox: SUCCESS (2.050s)
[396.156412] Subtest legacy-engines-mixed-process: SUCCESS (9.203s)
[396.233561] [073/132] (624s left) gem_wait (write-busy)
[396.298648] Starting subtest: write-busy
[396.301644] Starting dynamic subtest: all-engines
[396.933967] Dynamic subtest all-engines: SUCCESS (0.635s)
[396.952301] Starting dynamic subtest: rcs0
[397.490945] Dynamic subtest rcs0: SUCCESS (0.556s)
[397.507914] Starting dynamic subtest: bcs0
[398.042915] Dynamic subtest bcs0: SUCCESS (0.551s)
[398.058148] Starting dynamic subtest: vcs0
[399.099175] Dynamic subtest vcs0: SUCCESS (1.054s)
[399.116775] Starting dynamic subtest: vcs1
[401.145429] Dynamic subtest vcs1: SUCCESS (2.043s)
[401.162269] Starting dynamic subtest: vecs0
[403.129430] Dynamic subtest vecs0: SUCCESS (1.982s)
[403.146352] Starting dynamic subtest: ccs0
[403.680643] Dynamic subtest ccs0: SUCCESS (0.550s)
[403.683670] Subtest write-busy: SUCCESS (7.385s)
[403.882213] [074/132] (617s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[403.982419] Starting subtest: fbcpsr-2p-rte
[403.987487] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[404.084396] [075/132] (617s left) kms_psr (pr-cursor-render)
[404.169785] Starting subtest: pr-cursor-render
[404.174625] Subtest pr-cursor-render: SKIP (0.000s)
[404.246017] [076/132] (617s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[404.335889] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[404.338540] Starting dynamic subtest: pipe-A-eDP-1
[404.365431] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[404.376830] Starting dynamic subtest: pipe-B-eDP-1
[404.403451] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[404.413478] Starting dynamic subtest: pipe-C-eDP-1
[404.439562] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[404.448648] Starting dynamic subtest: pipe-D-eDP-1
[404.475500] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[404.480173] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.011s)
[406.591835] [077/132] (614s left) kms_draw_crc (draw-method-render)
[406.693909] Starting subtest: draw-method-render
[406.696482] Starting dynamic subtest: xrgb8888-untiled
[406.936579] Dynamic subtest xrgb8888-untiled: SUCCESS (0.245s)
[406.949174] Starting dynamic subtest: rgb565-untiled
[407.180482] Dynamic subtest rgb565-untiled: SUCCESS (0.243s)
[407.190420] Starting dynamic subtest: xrgb2101010-untiled
[407.402925] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[407.413235] Starting dynamic subtest: xbgr16161616f-untiled
[407.658227] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.253s)
[407.668328] Starting dynamic subtest: xrgb8888-xtiled
[407.791415] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.131s)
[407.801275] Starting dynamic subtest: rgb565-xtiled
[407.913952] Dynamic subtest rgb565-xtiled: SUCCESS (0.120s)
[407.923819] Starting dynamic subtest: xrgb2101010-xtiled
[408.035654] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.120s)
[408.046177] Starting dynamic subtest: xbgr16161616f-xtiled
[408.168789] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.131s)
[408.179089] Starting dynamic subtest: xrgb8888-4tiled
[408.291458] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[408.303019] Starting dynamic subtest: rgb565-4tiled
[408.402235] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[408.412552] Starting dynamic subtest: xrgb2101010-4tiled
[408.513058] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[408.522327] Starting dynamic subtest: xbgr16161616f-4tiled
[408.646968] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[408.648956] Subtest draw-method-render: SUCCESS (1.956s)
[408.732828] [078/132] (612s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[408.824193] Starting subtest: fbc-psr-sprite-mmap-gtt
[408.826331] Starting dynamic subtest: eDP-1
[408.829939] Dynamic subtest eDP-1: SKIP (0.000s)
[408.833493] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[408.895222] [079/132] (612s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[408.987958] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[408.989957] Starting dynamic subtest: pipe-A-eDP-1
[409.131589] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.136s)
[409.143087] Starting dynamic subtest: pipe-B-eDP-1
[409.164119] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[409.173850] Starting dynamic subtest: pipe-C-eDP-1
[409.193311] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[409.202965] Starting dynamic subtest: pipe-D-eDP-1
[409.222328] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[409.224328] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.148s)
[411.465221] [080/132] (609s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[411.569484] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[411.574482] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[411.667247] [081/132] (609s left) kms_color (degamma)
[411.747972] Starting subtest: degamma
[411.750653] Starting dynamic subtest: pipe-A-eDP-1
[412.459308] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[412.469414] Starting dynamic subtest: pipe-B-eDP-1
[413.875475] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.416s)
[413.885972] Starting dynamic subtest: pipe-C-eDP-1
[415.248096] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.370s)
[415.258378] Starting dynamic subtest: pipe-D-eDP-1
[416.573991] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[416.576382] Subtest degamma: SUCCESS (4.823s)
[417.372182] [082/132] (604s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[417.489968] Starting subtest: psr-farfromfence-mmap-gtt
[417.495440] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[417.599307] [083/132] (603s left) kms_atomic_transition (plane-all-transition-nonblocking)
[417.687465] Starting subtest: plane-all-transition-nonblocking
[417.689721] Starting dynamic subtest: pipe-A-eDP-1
[420.187048] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.498s)
[420.508801] Starting dynamic subtest: pipe-B-eDP-1
[423.712249] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.212s)
[424.015518] Subtest plane-all-transition-nonblocking: SUCCESS (6.327s)
[424.845530] [084/132] (596s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[424.982344] Starting subtest: flip-vs-cursor-busy-crc-legacy
[425.796058] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.813s)
[426.659752] [085/132] (594s left) vgem_basic (create)
[426.712463] Starting subtest: create
[426.715167] Subtest create: SUCCESS (0.000s)
[426.780076] [086/132] (594s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[426.881502] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[426.886467] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[426.980172] [087/132] (594s left) gem_tiled_blits (basic)
[427.039231] Subtest basic: SKIP (0.000s)
[427.107050] [088/132] (594s left) kms_async_flips (invalid-async-flip)
[427.196370] Starting subtest: invalid-async-flip
[427.573159] Starting dynamic subtest: pipe-A-eDP-1
[428.385294] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.812s)
[428.744288] Starting dynamic subtest: pipe-B-eDP-1
[429.520699] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.784s)
[429.879526] Starting dynamic subtest: pipe-C-eDP-1
[430.744323] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.873s)
[431.103633] Starting dynamic subtest: pipe-D-eDP-1
[431.860308] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.765s)
[431.862594] Subtest invalid-async-flip: SUCCESS (4.667s)
[433.028930] [089/132] (588s left) gem_pwrite (basic-self)
[433.084291] Subtest basic-self: SKIP (0.000s)
[433.146875] [090/132] (588s left) api_intel_allocator (two-level-inception-interruptible)
[433.204319] Starting subtest: two-level-inception-interruptible
[438.612370] Subtest two-level-inception-interruptible: SUCCESS (5.406s)
[438.714019] [091/132] (583s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[438.823547] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[445.611488] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.791s)
[445.737842] [092/132] (576s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[445.823532] Starting subtest: nonblocking-crc-frame-sequence
[445.826316] Starting dynamic subtest: pipe-A-eDP-1
[446.560730] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.735s)
[446.571503] Starting dynamic subtest: pipe-B-eDP-1
[447.949224] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.391s)
[447.960369] Starting dynamic subtest: pipe-C-eDP-1
[449.376812] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.426s)
[449.390921] Starting dynamic subtest: pipe-D-eDP-1
[450.777964] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.398s)
[450.780664] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.955s)
[451.592440] [093/132] (570s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[451.691198] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[451.696465] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[451.788827] [094/132] (570s left) kms_cursor_edge_walk (64x64-top-bottom)
[451.877009] Starting subtest: 64x64-top-bottom
[451.879428] Starting dynamic subtest: pipe-A-eDP-1
[455.344569] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.468s)
[455.354820] Starting dynamic subtest: pipe-D-eDP-1
[458.480686] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.134s)
[458.483503] Subtest 64x64-top-bottom: SUCCESS (6.605s)
[459.299475] [095/132] (562s left) kms_cursor_crc (cursor-random-512x170)
[459.602380] Starting subtest: cursor-random-512x170
[459.607259] Subtest cursor-random-512x170: SKIP (0.001s)
[459.739049] [096/132] (562s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[459.831859] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[466.478331] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.648s)
[466.603861] [097/132] (555s left) kms_properties (invalid-properties-legacy)
[466.705388] Starting subtest: invalid-properties-legacy
[466.803636] Subtest invalid-properties-legacy: SUCCESS (0.100s)
[466.885299] [098/132] (555s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[466.985680] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[466.991114] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[467.081707] [099/132] (555s left) kms_big_fb (y-tiled-addfb-size-overflow)
[467.181568] Starting subtest: y-tiled-addfb-size-overflow
[467.186444] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[467.272380] [100/132] (555s left) syncobj_wait (wait-any-interrupted)
[467.326740] Starting subtest: wait-any-interrupted
[467.428722] Subtest wait-any-interrupted: SUCCESS (0.102s)
[467.504367] [101/132] (554s left) kms_lease (master-vs-lease)
[467.602406] Starting subtest: master-vs-lease
[467.605242] Subtest master-vs-lease: SUCCESS (0.002s)
[467.723765] [102/132] (554s left) perf (non-zero-reason)
[467.848565] Starting subtest: non-zero-reason
[467.851306] Starting dynamic subtest: 0-rcs0
[473.209405] Dynamic subtest 0-rcs0: SUCCESS (5.361s)
[473.225034] Starting dynamic subtest: 1-vcs1
[477.133514] Dynamic subtest 1-vcs1: SUCCESS (3.922s)
[477.136531] Subtest non-zero-reason: SUCCESS (9.285s)
[477.182747] [103/132] (545s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[477.236919] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[477.342129] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[477.418388] [104/132] (545s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[477.527086] Starting subtest: x-tiled-16bpp-rotate-270
[477.546825] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[477.648896] [105/132] (544s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[477.734460] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[477.739941] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[477.828379] [106/132] (544s left) syncobj_timeline (wait-all-for-submit-snapshot)
[477.879456] Starting subtest: wait-all-for-submit-snapshot
[477.960345] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[478.038079] [107/132] (544s left) kms_flip (wf_vblank-ts-check-interruptible)
[478.136427] Starting subtest: wf_vblank-ts-check-interruptible
[478.139158] Starting dynamic subtest: A-eDP1
[487.035697] Dynamic subtest A-eDP1: SUCCESS (8.896s)
[487.047510] Starting dynamic subtest: B-eDP1
[495.970102] Dynamic subtest B-eDP1: SUCCESS (8.931s)
[495.982778] Starting dynamic subtest: C-eDP1
[504.894452] Dynamic subtest C-eDP1: SUCCESS (8.919s)
[504.906588] Starting dynamic subtest: D-eDP1
[513.820296] Dynamic subtest D-eDP1: SUCCESS (8.921s)
[513.826493] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.680s)
[515.058566] [108/132] (507s left) kms_getfb (getfb-repeated-different-handles)
[515.157912] Starting subtest: getfb-repeated-different-handles
[515.160636] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[515.263778] [109/132] (507s left) kms_vblank (ts-continuation-dpms-rpm)
[515.366434] Starting subtest: ts-continuation-dpms-rpm
[515.368703] Starting dynamic subtest: pipe-A-eDP-1
[517.945064] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.578s)
[517.961127] Starting dynamic subtest: pipe-D-eDP-1
[520.185623] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.238s)
[520.188497] Subtest ts-continuation-dpms-rpm: SUCCESS (4.820s)
[521.064968] [110/132] (501s left) kms_chamelium_hpd (dp-hpd-fast)
[521.459153] Subtest dp-hpd-fast: SKIP (0.000s)
[522.280988] [111/132] (500s left) kms_chamelium_color (ctm-0-50)
[522.392832] Subtest ctm-0-50: SKIP (0.000s)
[522.440571] [112/132] (500s left) api_intel_bb (render-ccs)
[522.495617] Starting subtest: render-ccs
[522.911270] Subtest render-ccs: SUCCESS (0.417s)
[523.003825] [113/132] (499s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[523.092441] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[523.095118] Starting dynamic subtest: pipe-A-eDP-1
[523.122552] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[523.132655] Starting dynamic subtest: pipe-B-eDP-1
[523.159047] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[523.168690] Starting dynamic subtest: pipe-C-eDP-1
[523.194776] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[523.204234] Starting dynamic subtest: pipe-D-eDP-1
[523.230592] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[523.235573] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[525.385188] [114/132] (497s left) kms_psr (psr-cursor-blt)
[525.474298] Starting subtest: psr-cursor-blt
[525.477063] Starting dynamic subtest: eDP-1
[527.167402] Dynamic subtest eDP-1: SUCCESS (1.694s)
[527.170175] Subtest psr-cursor-blt: SUCCESS (1.695s)
[528.074867] [115/132] (494s left) prime_mmap (test_errors)
[528.136833] Starting subtest: test_errors
[528.139308] Starting dynamic subtest: test_errors-smem
[528.228778] Dynamic subtest test_errors-smem: SUCCESS (0.093s)
[528.231439] Subtest test_errors: SUCCESS (0.094s)
[528.315146] [116/132] (494s left) kms_flip (flip-vs-panning-interruptible)
[528.400098] Starting subtest: flip-vs-panning-interruptible
[528.402550] Starting dynamic subtest: A-eDP1
[537.147368] Dynamic subtest A-eDP1: SUCCESS (8.749s)
[537.158488] Starting dynamic subtest: B-eDP1
[545.883863] Dynamic subtest B-eDP1: SUCCESS (8.735s)
[545.894190] Starting dynamic subtest: C-eDP1
[554.599169] Dynamic subtest C-eDP1: SUCCESS (8.715s)
[554.610488] Starting dynamic subtest: D-eDP1
[563.335455] Dynamic subtest D-eDP1: SUCCESS (8.735s)
[563.340140] Subtest flip-vs-panning-interruptible: SUCCESS (34.947s)
[564.506586] [117/132] (458s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[564.614695] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[564.617651] Starting dynamic subtest: pipe-A-eDP-1
[564.643691] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[564.653922] Starting dynamic subtest: pipe-B-eDP-1
[564.678315] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[564.687521] Starting dynamic subtest: pipe-C-eDP-1
[564.713255] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[564.722222] Starting dynamic subtest: pipe-D-eDP-1
[564.748415] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[564.753377] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[566.770247] [118/132] (456s left) gem_create (create-massive)
[566.839275] Starting subtest: create-massive
[566.841625] Subtest create-massive: SUCCESS (0.000s)
[567.019342] [119/132] (456s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[567.124355] Starting subtest: fbc-rgb565-draw-blt
[571.245843] Subtest fbc-rgb565-draw-blt: SUCCESS (4.126s)
[572.396794] [120/132] (450s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[572.501466] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[572.507409] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[572.586713] [121/132] (450s left) kms_properties (colorop-properties-atomic)
[572.678350] Starting subtest: colorop-properties-atomic
[572.680871] Starting dynamic subtest: pipe-A-eDP-1
[573.017448] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.338s)
[573.028385] Starting dynamic subtest: pipe-B-eDP-1
[574.058483] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.036s)
[574.069400] Starting dynamic subtest: pipe-C-eDP-1
[575.100163] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.043s)
[575.117426] Starting dynamic subtest: pipe-D-eDP-1
[576.139453] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.037s)
[576.141930] Subtest colorop-properties-atomic: SUCCESS (3.459s)
[576.951832] [122/132] (446s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[577.050932] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[577.055977] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[577.132620] [123/132] (446s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[577.700403] Starting subtest: syncobj-stationary-timeline-chain-engines
[577.843454] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.143s)
[579.214895] [124/132] (444s left) gem_eio (in-flight-internal-1us)
[579.432168] Starting subtest: in-flight-internal-1us
[581.212377] Subtest in-flight-internal-1us: SUCCESS (1.780s)
[581.346625] [125/132] (442s left) sysfs_heartbeat_interval (mixed)
[581.409276] Starting subtest: mixed
[581.411687] Starting dynamic subtest: vecs0
[586.607374] Dynamic subtest vecs0: SUCCESS (5.197s)
[586.619684] Starting dynamic subtest: ccs0
[591.844386] Dynamic subtest ccs0: SUCCESS (5.235s)
[591.857642] Starting dynamic subtest: vcs0
[597.066671] Dynamic subtest vcs0: SUCCESS (5.220s)
[597.084401] Starting dynamic subtest: bcs0
[602.328627] Dynamic subtest bcs0: SUCCESS (5.258s)
[602.341063] Starting dynamic subtest: rcs0
[607.564237] Dynamic subtest rcs0: SUCCESS (5.233s)
[607.576809] Starting dynamic subtest: vcs1
[612.799628] Dynamic subtest vcs1: SUCCESS (5.232s)
[612.802581] Subtest mixed: SUCCESS (31.389s)
[612.881915] [126/132] (410s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[612.995197] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[612.997871] Starting dynamic subtest: pipe-A-eDP-1
[614.283677] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.289s)
[614.562160] Starting dynamic subtest: pipe-B-eDP-1
[615.606242] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.052s)
[615.874593] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.880s)
[616.713724] [127/132] (406s left) kms_getfb (getfb-handle-zero)
[616.805557] Starting subtest: getfb-handle-zero
[616.807939] Subtest getfb-handle-zero: SUCCESS (0.000s)
[616.892000] [128/132] (406s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[616.989136] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[616.993933] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[617.092022] [129/132] (406s left) i915_query (query-regions-sanity-check)
[617.150107] Starting subtest: query-regions-sanity-check
[617.160327] Subtest query-regions-sanity-check: SUCCESS (0.013s)
[617.250941] [130/132] (406s left) gem_exec_store (cachelines)
[617.307564] Starting subtest: cachelines
[617.310156] Starting dynamic subtest: rcs0
[617.367657] Dynamic subtest rcs0: SUCCESS (0.061s)
[617.380276] Starting dynamic subtest: bcs0
[617.416962] Dynamic subtest bcs0: SUCCESS (0.049s)
[617.427762] Starting dynamic subtest: vcs0
[617.467886] Dynamic subtest vcs0: SUCCESS (0.049s)
[617.479682] Starting dynamic subtest: vcs1
[617.517125] Dynamic subtest vcs1: SUCCESS (0.048s)
[617.527668] Starting dynamic subtest: vecs0
[617.571405] Dynamic subtest vecs0: SUCCESS (0.053s)
[617.581003] Starting dynamic subtest: ccs0
[617.630348] Dynamic subtest ccs0: SUCCESS (0.057s)
[617.633290] Subtest cachelines: SUCCESS (0.325s)
[617.814014] [131/132] (405s left) gem_mmap_offset (blt-coherency)
[617.872409] Starting subtest: blt-coherency
[617.877593] Subtest blt-coherency: SUCCESS (0.007s)
[617.995929] [132/132] (405s left) kms_pm_rpm (system-suspend-idle)
[620.219644] Starting subtest: system-suspend-idle
[639.573670] Subtest system-suspend-idle: SUCCESS (4.248s)
[639.986576] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_balancer
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: sw_sync
results: parsing output: 3/ for test: kms_plane_scaling
results: parsing output: 4/ for test: kms_pm_rpm
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: gem_lmem_swapping
results: parsing output: 8/ for test: kms_flip_scaled_crc
results: parsing output: 9/ for test: i915_module_load
results: parsing output: 10/ for test: kms_psr2_sf
results: parsing output: 11/ for test: gem_set_tiling_vs_blt
results: parsing output: 12/ for test: kms_cursor_legacy
results: parsing output: 13/ for test: kms_dirtyfb
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: kms_plane
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: gen9_exec_parse
results: parsing output: 19/ for test: intel_hwmon
results: parsing output: 20/ for test: fbdev
results: parsing output: 21/ for test: kms_rotation_crc
results: parsing output: 22/ for test: prime_vgem
results: parsing output: 23/ for test: gem_exec_fence
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: gem_exec_schedule
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_ctx_isolation
results: parsing output: 30/ for test: syncobj_basic
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: gem_close_race
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: kms_cursor_crc
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_spin_batch
results: parsing output: 37/ for test: gem_pxp
results: parsing output: 38/ for test: gem_exec_balancer
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: kms_joiner
results: parsing output: 41/ for test: gem_workarounds
results: parsing output: 42/ for test: kms_psr2_sf
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: i915_pm_rpm
results: parsing output: 45/ for test: dmabuf_sync_file
results: parsing output: 46/ for test: i915_drm_fdinfo
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: gem_exec_flush
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_content_protection
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_cursor_legacy
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: gem_ctx_exec
results: parsing output: 57/ for test: kms_chamelium_frames
results: parsing output: 58/ for test: kms_plane_scaling
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: kms_plane_multiple
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: kms_sequence
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: syncobj_wait
results: parsing output: 65/ for test: gem_exercise_blt
results: parsing output: 66/ for test: prime_mmap
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_atomic_transition
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: kms_rotation_crc
results: parsing output: 71/ for test: gem_ctx_persistence
results: parsing output: 72/ for test: gem_wait
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: kms_draw_crc
results: parsing output: 77/ for test: kms_psr
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_color
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_atomic_transition
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: vgem_basic
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_tiled_blits
results: parsing output: 87/ for test: kms_async_flips
results: parsing output: 88/ for test: gem_pwrite
results: parsing output: 89/ for test: api_intel_allocator
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_pipe_crc_basic
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_cursor_edge_walk
results: parsing output: 94/ for test: kms_cursor_crc
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_properties
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: syncobj_wait
results: parsing output: 100/ for test: kms_lease
results: parsing output: 101/ for test: perf
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: syncobj_timeline
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: kms_getfb
results: parsing output: 108/ for test: kms_vblank
results: parsing output: 109/ for test: kms_chamelium_hpd
results: parsing output: 110/ for test: kms_chamelium_color
results: parsing output: 111/ for test: api_intel_bb
results: parsing output: 112/ for test: kms_ccs
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: prime_mmap
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: gem_create
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_properties
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_exec_fence
results: parsing output: 123/ for test: gem_eio
results: parsing output: 124/ for test: sysfs_heartbeat_interval
results: parsing output: 125/ for test: kms_atomic_transition
results: parsing output: 126/ for test: kms_getfb
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: i915_query
results: parsing output: 129/ for test: gem_exec_store
results: parsing output: 130/ for test: gem_mmap_offset
results: parsing output: 131/ for test: kms_pm_rpm
Done.