[57.589486] Initializing watchdogs
[57.589534]   /dev/watchdog0
[57.603363] [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.607513] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.609761] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.613133] [001/132] (960s left) kms_cursor_crc (cursor-onscreen-512x170)
[57.925518] Starting subtest: cursor-onscreen-512x170
[57.929644] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[58.068901] [002/132] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[58.170264] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[58.174247] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[58.261286] [003/132] (959s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[58.316712] Starting subtest: etime-single-wait-all-available-unsubmitted
[58.418321] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[58.483707] [004/132] (959s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[58.582746] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[65.126493] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.584s)
[66.329863] [005/132] (951s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[66.437897] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[73.137082] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.716s)
[73.259856] [006/132] (945s left) kms_flip (dpms-off-confusion-interruptible)
[73.345182] Starting subtest: dpms-off-confusion-interruptible
[73.347537] Starting dynamic subtest: A-eDP1
[82.019548] Dynamic subtest A-eDP1: SUCCESS (8.675s)
[82.026726] Starting dynamic subtest: B-eDP1
[90.647068] Dynamic subtest B-eDP1: SUCCESS (8.624s)
[90.654640] Starting dynamic subtest: C-eDP1
[99.263539] Dynamic subtest C-eDP1: SUCCESS (8.613s)
[99.271313] Starting dynamic subtest: D-eDP1
[107.889343] Dynamic subtest D-eDP1: SUCCESS (8.623s)
[107.891836] Subtest dpms-off-confusion-interruptible: SUCCESS (34.545s)
[109.052820] [007/132] (909s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[109.153538] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[115.727966] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.577s)
[115.851522] [008/132] (902s left) perf_pmu (semaphore-busy)
[115.890729] Starting subtest: semaphore-busy
[115.893184] Starting dynamic subtest: rcs0
[117.532872] Dynamic subtest rcs0: SUCCESS (1.637s)
[117.543651] Starting dynamic subtest: bcs0
[119.164952] Dynamic subtest bcs0: SUCCESS (1.630s)
[119.175778] Starting dynamic subtest: vcs0
[123.409975] Dynamic subtest vcs0: SUCCESS (4.244s)
[123.420336] Starting dynamic subtest: vcs1
[129.426056] Dynamic subtest vcs1: SUCCESS (6.014s)
[129.436629] Starting dynamic subtest: vecs0
[135.443259] Dynamic subtest vecs0: SUCCESS (6.015s)
[135.453316] Starting dynamic subtest: ccs0
[138.518937] Dynamic subtest ccs0: SUCCESS (3.074s)
[138.522047] Subtest semaphore-busy: SUCCESS (22.623s)
[138.628496] [009/132] (879s left) kms_plane (pixel-format-yf-tiled-modifier)
[138.719966] Starting subtest: pixel-format-yf-tiled-modifier
[138.724236] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[138.786943] [010/132] (879s left) gem_lmem_swapping (smem-oom)
[138.840367] Subtest smem-oom: SKIP (0.000s)
[138.901305] [011/132] (879s left) kms_vrr (flipline)
[139.006804] Starting subtest: flipline
[139.012991] Subtest flipline: SKIP (0.000s)
[139.075729] [012/132] (879s left) kms_dsc (dsc-with-formats)
[139.168021] Starting subtest: dsc-with-formats
[139.207869] Subtest dsc-with-formats: SKIP (0.001s)
[139.244955] [013/132] (879s left) vgem_basic (unload)
[139.283077] Starting subtest: unload
[168.347619] Subtest unload: SUCCESS (29.062s)
[168.446169] [FACT vgem_basic (unload)] new: kernel.kmod_is_loaded.vgem: true
[168.450117] [014/132] (850s left) gem_exec_whisper (basic-contexts-forked-all)
[168.521840] Starting subtest: basic-contexts-forked-all
[190.739515] Subtest basic-contexts-forked-all: SUCCESS (22.215s)
[190.918857] [015/132] (827s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[191.013542] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[191.015853] Starting dynamic subtest: pipe-A-eDP-1
[191.274801] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.230s)
[191.284169] Starting dynamic subtest: pipe-B-eDP-1
[192.539540] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.260s)
[192.548129] Starting dynamic subtest: pipe-C-eDP-1
[193.755130] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.216s)
[193.765353] Starting dynamic subtest: pipe-D-eDP-1
[194.980811] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[194.982846] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.937s)
[196.111014] [016/132] (822s left) i915_drm_fdinfo (busy-idle-check-all)
[196.153044] Starting subtest: busy-idle-check-all
[196.155576] Starting dynamic subtest: rcs0
[196.160433] Dynamic subtest rcs0: SKIP (0.004s)
[196.171752] Starting dynamic subtest: bcs0
[196.175857] Dynamic subtest bcs0: SKIP (0.000s)
[196.183850] Starting dynamic subtest: vcs0
[196.188763] Dynamic subtest vcs0: SKIP (0.000s)
[196.198756] Starting dynamic subtest: vcs1
[196.202979] Dynamic subtest vcs1: SKIP (0.000s)
[196.211807] Starting dynamic subtest: vecs0
[196.216096] Dynamic subtest vecs0: SKIP (0.000s)
[196.223574] Starting dynamic subtest: ccs0
[196.227679] Dynamic subtest ccs0: SKIP (0.000s)
[196.231993] Subtest busy-idle-check-all: SKIP (0.008s)
[196.267362] [017/132] (822s left) gem_ctx_shared (single-timeline)
[196.357580] Starting subtest: single-timeline
[196.378224] Subtest single-timeline: SUCCESS (0.014s)
[196.587230] [018/132] (822s left) gem_exec_balancer (bonded-true-hang)
[196.831799] Starting subtest: bonded-true-hang
[196.837291] Subtest bonded-true-hang: SKIP (0.000s)
[196.936390] [019/132] (821s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[197.023968] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[197.028125] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.000s)
[197.103219] [020/132] (821s left) kms_cursor_crc (cursor-sliding-32x10)
[197.251937] Starting subtest: cursor-sliding-32x10
[197.296694] Subtest cursor-sliding-32x10: SKIP (0.039s)
[197.542599] [021/132] (821s left) kms_color (ctm-blue-to-red)
[197.623822] Starting subtest: ctm-blue-to-red
[197.626087] Starting dynamic subtest: pipe-A-eDP-1
[198.311177] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[198.320731] Starting dynamic subtest: pipe-B-eDP-1
[199.677480] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.365s)
[199.686795] Starting dynamic subtest: pipe-C-eDP-1
[200.994057] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.316s)
[201.003180] Starting dynamic subtest: pipe-D-eDP-1
[202.343203] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.349s)
[202.345246] Subtest ctm-blue-to-red: SUCCESS (4.719s)
[203.137629] [022/132] (815s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[203.227960] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[203.230342] Starting dynamic subtest: pipe-A
[203.611261] Dynamic subtest pipe-A: SUCCESS (0.385s)
[203.620967] Starting dynamic subtest: pipe-B
[205.076673] Dynamic subtest pipe-B: SUCCESS (1.464s)
[205.085409] Starting dynamic subtest: pipe-C
[206.493726] Dynamic subtest pipe-C: SUCCESS (1.415s)
[206.503556] Starting dynamic subtest: pipe-D
[207.924399] Dynamic subtest pipe-D: SUCCESS (1.430s)
[207.926711] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.699s)
[209.085452] [023/132] (809s left) gem_ctx_bad_destroy (invalid-default-ctx)
[209.141804] Starting subtest: invalid-default-ctx
[209.144359] Subtest invalid-default-ctx: SUCCESS (0.000s)
[209.241745] [024/132] (809s left) kms_chamelium_edid (hdmi-edid-read)
[209.637730] Subtest hdmi-edid-read: SKIP (0.000s)
[210.493190] [025/132] (808s left) vgem_basic (dmabuf-fence)
[210.542303] Starting subtest: dmabuf-fence
[210.544595] Subtest dmabuf-fence: SUCCESS (0.001s)
[210.596446] [026/132] (808s left) kms_psr (fbc-psr2-no-drrs)
[210.684190] Starting subtest: fbc-psr2-no-drrs
[210.686492] Starting dynamic subtest: eDP-1
[210.690932] Dynamic subtest eDP-1: SKIP (0.000s)
[210.695439] Subtest fbc-psr2-no-drrs: SKIP (0.001s)
[210.794236] [027/132] (808s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[210.880570] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[210.882825] Starting dynamic subtest: pipe-A-eDP-1
[227.469922] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.336s)
[227.479865] Starting dynamic subtest: pipe-B-eDP-1
[244.395883] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.438s)
[244.407266] Starting dynamic subtest: pipe-C-eDP-1
[261.387836] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.376s)
[261.397256] Starting dynamic subtest: pipe-D-eDP-1
[278.409105] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.372s)
[278.411239] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (9.527s)
[279.577223] [028/132] (739s left) gem_blits (basic)
[279.637920] Starting subtest: basic
[280.996860] Subtest basic: SUCCESS (1.403s)
[281.107992] [029/132] (738s left) kms_psr (fbc-pr-basic)
[281.196753] Starting subtest: fbc-pr-basic
[281.200654] Subtest fbc-pr-basic: SKIP (0.000s)
[281.265337] [030/132] (738s left) i915_module_load (resize-bar)
[281.307330] Starting subtest: resize-bar
[281.313880] Subtest resize-bar: SKIP (0.006s)
[281.353052] [031/132] (737s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[281.404992] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[281.407386] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[281.500694] [032/132] (737s left) prime_vgem (coherency-gtt)
[281.565229] Starting subtest: coherency-gtt
[281.569631] Subtest coherency-gtt: SKIP (0.000s)
[281.663582] [033/132] (737s left) kms_cdclk (plane-scaling)
[281.755246] Starting subtest: plane-scaling
[281.757459] Starting dynamic subtest: pipe-A-eDP-1
[282.049584] Dynamic subtest pipe-A-eDP-1: SKIP (0.300s)
[282.060237] Starting dynamic subtest: pipe-B-eDP-1
[282.064795] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[282.074723] Starting dynamic subtest: pipe-C-eDP-1
[282.079127] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[282.087684] Starting dynamic subtest: pipe-D-eDP-1
[282.091900] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[282.096049] Subtest plane-scaling: SKIP (0.308s)
[282.909864] [034/132] (736s left) gem_exec_reloc (basic-write-wc-active)
[282.979780] Subtest basic-write-wc-active: SKIP (0.000s)
[283.045949] [035/132] (736s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[283.136483] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[283.140488] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[283.234640] [036/132] (736s left) gem_reg_read (timestamp-monotonic)
[283.281687] Starting subtest: timestamp-monotonic
[287.689619] Subtest timestamp-monotonic: SUCCESS (4.483s)
[287.758713] [037/132] (731s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[287.862254] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf
[287.864367] Starting dynamic subtest: pipe-A-eDP-1
[287.963941] Dynamic subtest pipe-A-eDP-1: SKIP (0.101s)
[287.976802] Starting dynamic subtest: pipe-B-eDP-1
[289.073510] Dynamic subtest pipe-B-eDP-1: SKIP (1.121s)
[289.078144] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.226s)
[290.264961] [038/132] (729s left) syncobj_basic (bad-pad-fd-to-handle)
[290.315806] Starting subtest: bad-pad-fd-to-handle
[290.318130] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[290.418792] [039/132] (729s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[290.505189] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[290.509173] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[290.598481] [040/132] (729s left) kms_lease (lease-get)
[290.689280] Starting subtest: lease-get
[290.691589] Starting dynamic subtest: pipe-A-eDP-1
[290.693902] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[290.706716] Starting dynamic subtest: pipe-B-eDP-1
[290.708607] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[290.717516] Starting dynamic subtest: pipe-C-eDP-1
[290.719366] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[290.727370] Starting dynamic subtest: pipe-D-eDP-1
[290.729236] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[290.731052] Subtest lease-get: SUCCESS (0.008s)
[290.803162] [041/132] (728s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[290.900110] Starting subtest: plane-upscale-20x20-with-pixel-format
[290.902460] Starting dynamic subtest: pipe-A
[291.939665] Dynamic subtest pipe-A: SUCCESS (1.050s)
[291.948692] Starting dynamic subtest: pipe-B
[294.042736] Dynamic subtest pipe-B: SUCCESS (2.115s)
[294.051997] Starting dynamic subtest: pipe-C
[295.228054] Dynamic subtest pipe-C: SUCCESS (1.190s)
[295.239751] Starting dynamic subtest: pipe-D
[296.357498] Dynamic subtest pipe-D: SUCCESS (1.134s)
[296.359844] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.493s)
[297.554447] [042/132] (722s left) gem_lmem_swapping (parallel-random-engines)
[297.601487] Subtest parallel-random-engines: SKIP (0.000s)
[297.650049] [043/132] (722s left) gem_exec_schedule (noreorder-corked)
[297.824402] Starting subtest: noreorder-corked
[297.826863] Starting dynamic subtest: rcs0
[298.960041] Dynamic subtest rcs0: SUCCESS (1.140s)
[298.972366] Starting dynamic subtest: vcs0
[300.117131] Dynamic subtest vcs0: SUCCESS (1.158s)
[300.129569] Starting dynamic subtest: vcs1
[302.182152] Dynamic subtest vcs1: SUCCESS (2.068s)
[302.193426] Starting dynamic subtest: vecs0
[304.170557] Dynamic subtest vecs0: SUCCESS (1.989s)
[304.180781] Starting dynamic subtest: ccs0
[305.329273] Dynamic subtest ccs0: SUCCESS (1.159s)
[305.331994] Subtest noreorder-corked: SUCCESS (7.525s)
[305.539833] [044/132] (714s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[305.648227] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[306.703674] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.057s)
[306.827580] [045/132] (713s left) gem_userptr_blits (vma-merge)
[306.897045] Starting subtest: vma-merge
[306.935348] Subtest vma-merge: SUCCESS (0.038s)
[307.050022] [046/132] (712s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[307.147540] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[307.151134] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[307.238066] [047/132] (712s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[307.319568] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[307.321448] Starting dynamic subtest: pipe-A-eDP-1
[307.338606] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[307.348682] Starting dynamic subtest: pipe-B-eDP-1
[307.364693] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[307.373568] Starting dynamic subtest: pipe-C-eDP-1
[307.389353] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[307.398966] Starting dynamic subtest: pipe-D-eDP-1
[307.414797] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[307.416593] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[309.493477] [048/132] (710s left) kms_fbcon_fbt (fbc-suspend)
[309.546520] Starting subtest: fbc-suspend
[359.764843] Subtest fbc-suspend: SUCCESS (19.691s)
[361.121523] [049/132] (658s left) kms_psr (psr-cursor-plane-onoff)
[361.229062] Starting subtest: psr-cursor-plane-onoff
[361.231794] Starting dynamic subtest: eDP-1
[362.916538] Dynamic subtest eDP-1: SUCCESS (1.718s)
[362.919264] Subtest psr-cursor-plane-onoff: SUCCESS (1.719s)
[363.777120] [050/132] (656s left) gem_exec_reloc (basic-cpu)
[363.832507] Subtest basic-cpu: SKIP (0.000s)
[363.897376] [051/132] (656s left) gem_exec_fence (syncobj-invalid-wait)
[364.401001] Starting subtest: syncobj-invalid-wait
[364.403792] Subtest syncobj-invalid-wait: SUCCESS (0.001s)
[366.219584] [052/132] (653s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[366.353035] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[366.358207] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[366.493835] [053/132] (653s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[366.591595] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[366.593948] Starting dynamic subtest: A-eDP1
[375.419097] Dynamic subtest A-eDP1: SUCCESS (8.881s)
[375.433181] Starting dynamic subtest: B-eDP1
[384.331818] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[384.342751] Starting dynamic subtest: C-eDP1
[393.262771] Dynamic subtest C-eDP1: SUCCESS (8.926s)
[393.278453] Starting dynamic subtest: D-eDP1
[402.209751] Dynamic subtest D-eDP1: SUCCESS (8.936s)
[402.216146] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.687s)
[403.338369] [054/132] (616s left) gem_spin_batch (resubmit-new-all)
[403.408067] Starting subtest: resubmit-new-all
[403.410825] Starting dynamic subtest: rcs0
[403.424285] Dynamic subtest rcs0: SUCCESS (0.016s)
[403.433828] Starting dynamic subtest: bcs0
[403.439281] Dynamic subtest bcs0: SUCCESS (0.012s)
[403.449028] Starting dynamic subtest: vcs0
[403.451285] Dynamic subtest vcs0: SUCCESS (0.008s)
[403.460909] Starting dynamic subtest: vcs1
[403.463483] Dynamic subtest vcs1: SUCCESS (0.008s)
[403.472577] Starting dynamic subtest: vecs0
[403.475153] Dynamic subtest vecs0: SUCCESS (0.009s)
[403.484302] Starting dynamic subtest: ccs0
[403.487151] Dynamic subtest ccs0: SUCCESS (0.008s)
[403.490038] Subtest resubmit-new-all: SUCCESS (0.064s)
[405.164847] [055/132] (615s left) gem_mmap_gtt (hang-busy)
[405.225261] Subtest hang-busy: SKIP (0.000s)
[405.287871] [056/132] (615s left) gen3_render_tiledx_blits
[405.411988] [057/132] (614s left) kms_invalid_mode (clock-too-high)
[405.503586] Starting subtest: clock-too-high
[405.506186] Starting dynamic subtest: pipe-A-eDP-1
[405.511818] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[405.522548] Starting dynamic subtest: pipe-B-eDP-1
[405.527309] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[405.536835] Starting dynamic subtest: pipe-C-eDP-1
[405.542156] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[405.556111] Starting dynamic subtest: pipe-D-eDP-1
[405.562062] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[405.567251] Subtest clock-too-high: SKIP (0.004s)
[405.605963] [058/132] (614s left) gem_busy (close-race)
[405.673275] Starting subtest: close-race
[431.105181] Subtest close-race: SUCCESS (25.429s)
[431.491757] [059/132] (588s left) kms_psr (psr2-basic)
[431.642847] Starting subtest: psr2-basic
[431.645255] Starting dynamic subtest: eDP-1
[433.225034] Dynamic subtest eDP-1: SUCCESS (1.580s)
[433.227617] Subtest psr2-basic: SUCCESS (1.581s)
[434.018755] [060/132] (586s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[434.118910] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[434.123143] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[434.202747] [061/132] (586s left) gem_ctx_param (invalid-get-no-zeromap)
[434.257648] Starting subtest: invalid-get-no-zeromap
[434.260399] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[434.353413] [062/132] (586s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[434.444129] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[441.823041] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.384s)
[441.937815] [063/132] (578s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[442.045589] Starting subtest: yf-tiled-32bpp-rotate-270
[442.051078] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[442.146555] [064/132] (578s left) kms_vrr (seamless-rr-switch-virtual)
[442.235970] Starting subtest: seamless-rr-switch-virtual
[442.243691] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[442.319483] [065/132] (578s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[442.415104] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[442.420330] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[442.511666] [066/132] (578s left) gem_exec_reloc (basic-scanout)
[442.570443] Subtest basic-scanout: SKIP (0.000s)
[442.629355] [067/132] (578s left) api_intel_bb (object-noreloc-purge-cache-simple)
[442.684820] Starting subtest: object-noreloc-purge-cache-simple
[442.687719] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[442.814594] [068/132] (578s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[442.909589] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[442.911943] Starting dynamic subtest: pipe-A-eDP-1
[442.932077] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[442.942899] Starting dynamic subtest: pipe-B-eDP-1
[442.962638] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[442.971583] Starting dynamic subtest: pipe-C-eDP-1
[442.991442] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[443.000493] Starting dynamic subtest: pipe-D-eDP-1
[443.019121] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[443.021080] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[445.217541] [069/132] (575s left) kms_addfb_basic (unused-offsets)
[445.269030] Starting subtest: unused-offsets
[445.271505] Subtest unused-offsets: SUCCESS (0.000s)
[445.419459] [070/132] (575s left) gem_create (create-ext-cpu-access-sanity-check)
[445.584305] Starting subtest: create-ext-cpu-access-sanity-check
[445.589964] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[445.675079] [071/132] (575s left) gem_readwrite (read-bad-handle)
[445.740057] Subtest read-bad-handle: SKIP (0.000s)
[445.799736] [072/132] (575s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[446.130975] Starting subtest: cursor-rapid-movement-max-size
[446.138501] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[446.253627] [073/132] (574s left) device_reset (unbind-reset-rebind)
[446.307851] Starting subtest: unbind-reset-rebind
[449.334121] Subtest unbind-reset-rebind: SUCCESS (3.027s)
[449.383636] [074/132] (571s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[449.486668] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[449.489304] Starting dynamic subtest: pipe-A-default-mode
[450.648012] Dynamic subtest pipe-A-default-mode: SKIP (1.157s)
[450.653117] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.159s)
[450.804787] [075/132] (570s left) i915_pm_rps (reset)
[450.867276] Starting subtest: reset
[459.278343] Subtest reset: SUCCESS (8.408s)
[459.390115] [076/132] (561s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[459.441306] Starting subtest: invalid-smem-bo-on-discrete
[459.447040] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[459.564457] [077/132] (561s left) kms_flip (basic-flip-vs-dpms)
[459.660225] Starting subtest: basic-flip-vs-dpms
[459.663217] Starting dynamic subtest: A-eDP1
[462.224567] Dynamic subtest A-eDP1: SUCCESS (2.563s)
[462.236308] Starting dynamic subtest: B-eDP1
[464.399281] Dynamic subtest B-eDP1: SUCCESS (2.171s)
[464.410910] Starting dynamic subtest: C-eDP1
[466.461547] Dynamic subtest C-eDP1: SUCCESS (2.063s)
[466.472780] Starting dynamic subtest: D-eDP1
[468.546049] Dynamic subtest D-eDP1: SUCCESS (2.082s)
[468.548830] Subtest basic-flip-vs-dpms: SUCCESS (8.890s)
[469.374633] [078/132] (551s left) kms_atomic (plane-primary-legacy)
[469.470201] Starting subtest: plane-primary-legacy
[469.473048] Starting dynamic subtest: pipe-A-eDP-1
[469.929756] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.459s)
[469.932425] Subtest plane-primary-legacy: SUCCESS (0.460s)
[470.787136] [079/132] (550s left) gem_exec_schedule (u-semaphore-resolve)
[470.887211] Starting subtest: u-semaphore-resolve
[470.922997] Subtest u-semaphore-resolve: SUCCESS (0.041s)
[471.257627] [080/132] (550s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[471.385996] Starting subtest: cursorB-vs-flipB-legacy
[471.390677] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[471.532036] [081/132] (549s left) kms_content_protection (type1)
[471.677029] Starting subtest: type1
[471.682070] Subtest type1: SKIP (0.000s)
[471.818789] [082/132] (549s left) gem_mmap_wc (write-gtt-read-wc)
[471.879130] Subtest write-gtt-read-wc: SKIP (0.000s)
[471.937775] [083/132] (549s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[472.031207] Starting subtest: yf-tiled-32bpp-rotate-90
[472.036718] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[472.120655] [084/132] (549s left) gem_softpin (softpin)
[472.190271] Starting subtest: softpin
[504.490942] Subtest softpin: SUCCESS (32.294s)
[504.623891] [085/132] (517s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[504.735319] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[504.741263] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[504.854556] [086/132] (516s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[504.969081] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[504.973692] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[505.078320] [087/132] (516s left) gem_ctx_persistence (legacy-engines-mixed-process)
[505.142826] Starting subtest: legacy-engines-mixed-process
[505.145391] Starting dynamic subtest: render
[507.052338] Dynamic subtest render: SUCCESS (1.909s)
[507.063412] Starting dynamic subtest: bsd1
[509.103263] Dynamic subtest bsd1: SUCCESS (2.049s)
[509.116154] Starting dynamic subtest: bsd2
[511.110648] Dynamic subtest bsd2: SUCCESS (2.007s)
[511.123443] Starting dynamic subtest: blt
[513.072941] Dynamic subtest blt: SUCCESS (1.958s)
[513.086991] Starting dynamic subtest: vebox
[515.081555] Dynamic subtest vebox: SUCCESS (2.010s)
[515.084480] Subtest legacy-engines-mixed-process: SUCCESS (9.941s)
[515.193148] [088/132] (506s left) gem_exec_fence (basic-busy-all)
[515.269016] Starting subtest: basic-busy-all
[515.373393] Subtest basic-busy-all: SUCCESS (0.106s)
[517.206342] [089/132] (504s left) i915_suspend (basic-s2idle-without-i915)
[517.255425] Starting subtest: basic-s2idle-without-i915
[536.716220] Subtest basic-s2idle-without-i915: SUCCESS (4.134s)
[536.844872] [090/132] (485s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[536.924742] Starting subtest: mixed-tiled-to-y-tiled-ccs
[536.929278] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[537.121210] [091/132] (484s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[537.230029] Starting subtest: y-tiled-32bpp-rotate-0
[537.235405] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[537.338936] [092/132] (484s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[537.429170] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[537.433053] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[537.518709] [093/132] (484s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[537.606937] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[537.610971] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[537.690002] [094/132] (484s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[537.784361] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[537.788328] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[537.874531] [095/132] (484s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[537.962788] Starting subtest: disable-crc-after-crtc
[537.964993] Starting dynamic subtest: pipe-A-eDP-1
[539.657671] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.746s)
[539.667118] Starting dynamic subtest: pipe-B-eDP-1
[541.967111] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.362s)
[541.976823] Starting dynamic subtest: pipe-C-eDP-1
[544.193550] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.262s)
[544.203207] Starting dynamic subtest: pipe-D-eDP-1
[546.455380] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.287s)
[546.457667] Subtest disable-crc-after-crtc: SUCCESS (8.662s)
[547.260815] [096/132] (474s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[547.350711] Starting subtest: fbc-psr2-sprite-plane-onoff
[547.352881] Starting dynamic subtest: eDP-1
[547.356659] Dynamic subtest eDP-1: SKIP (0.000s)
[547.360519] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[547.423945] [097/132] (474s left) syncobj_wait (wait-all-interrupted)
[547.480928] Starting subtest: wait-all-interrupted
[547.583543] Subtest wait-all-interrupted: SUCCESS (0.103s)
[547.669808] [098/132] (474s left) sysfs_preempt_timeout (timeout)
[547.721697] Starting subtest: timeout
[547.724552] Starting dynamic subtest: vecs0
[557.448646] Dynamic subtest vecs0: SUCCESS (9.775s)
[557.460417] Starting dynamic subtest: ccs0
[558.535700] Dynamic subtest ccs0: SUCCESS (1.091s)
[558.551021] Starting dynamic subtest: vcs0
[568.452212] Dynamic subtest vcs0: SUCCESS (9.921s)
[568.466580] Starting dynamic subtest: bcs0
[569.591731] Dynamic subtest bcs0: SUCCESS (1.139s)
[569.603233] Starting dynamic subtest: rcs0
[570.786802] Dynamic subtest rcs0: SUCCESS (1.191s)
[570.797726] Starting dynamic subtest: vcs1
[580.489648] Dynamic subtest vcs1: SUCCESS (9.696s)
[580.492442] Subtest timeout: SUCCESS (32.826s)
[580.594266] [099/132] (441s left) kms_selftest (drm_framebuffer)
[580.653854] Starting subtest: drm_framebuffer
[580.666447] Starting dynamic subtest: drm_test_framebuffer_check_src_coords
[580.769803] Dynamic subtest drm_test_framebuffer_check_src_coords: SUCCESS (0.106s)
[580.779554] Starting dynamic subtest: drm_test_framebuffer_cleanup
[580.881234] Dynamic subtest drm_test_framebuffer_cleanup: SUCCESS (0.109s)
[580.890715] Starting dynamic subtest: drm_test_framebuffer_create
[581.078055] Dynamic subtest drm_test_framebuffer_create: SUCCESS (0.193s)
[581.087948] Starting dynamic subtest: drm_test_framebuffer_free
[581.294663] Dynamic subtest drm_test_framebuffer_free: FAIL (0.169s)
[581.299022] Subtest drm_framebuffer: FAIL (0.594s)
[581.420684] Aborting: Kernel badly tainted (0x44244, 0x200) (check dmesg for details):
	TAINT_WARN: WARN_ON has happened.

[581.437598] [FACT kms_selftest (drm_framebuffer)] new: kernel.is_tainted.taint_warn: true
[581.438420] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_flip
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: perf_pmu
results: parsing output: 8/ for test: kms_plane
results: parsing output: 9/ for test: gem_lmem_swapping
results: parsing output: 10/ for test: kms_vrr
results: parsing output: 11/ for test: kms_dsc
results: parsing output: 12/ for test: vgem_basic
results: parsing output: 13/ for test: gem_exec_whisper
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: i915_drm_fdinfo
results: parsing output: 16/ for test: gem_ctx_shared
results: parsing output: 17/ for test: gem_exec_balancer
results: parsing output: 18/ for test: kms_flip_scaled_crc
results: parsing output: 19/ for test: kms_cursor_crc
results: parsing output: 20/ for test: kms_color
results: parsing output: 21/ for test: kms_plane_scaling
results: parsing output: 22/ for test: gem_ctx_bad_destroy
results: parsing output: 23/ for test: kms_chamelium_edid
results: parsing output: 24/ for test: vgem_basic
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: gem_blits
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: i915_module_load
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: prime_vgem
results: parsing output: 32/ for test: kms_cdclk
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_reg_read
results: parsing output: 36/ for test: kms_psr2_sf
results: parsing output: 37/ for test: syncobj_basic
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_lease
results: parsing output: 40/ for test: kms_plane_scaling
results: parsing output: 41/ for test: gem_lmem_swapping
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_userptr_blits
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: kms_fbcon_fbt
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: gem_exec_reloc
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: kms_cursor_legacy
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: gem_spin_batch
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: gen3_render_tiledx_blits
results: parsing output: 56/ for test: kms_invalid_mode
results: parsing output: 57/ for test: gem_busy
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_ctx_param
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: kms_vrr
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_exec_reloc
results: parsing output: 66/ for test: api_intel_bb
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: kms_addfb_basic
results: parsing output: 69/ for test: gem_create
results: parsing output: 70/ for test: gem_readwrite
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: device_reset
results: parsing output: 73/ for test: kms_flip_scaled_crc
results: parsing output: 74/ for test: i915_pm_rps
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: kms_flip
results: parsing output: 77/ for test: kms_atomic
results: parsing output: 78/ for test: gem_exec_schedule
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: kms_content_protection
results: parsing output: 81/ for test: gem_mmap_wc
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: gem_softpin
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: gem_exec_fence
results: parsing output: 88/ for test: i915_suspend
results: parsing output: 89/ for test: gem_render_copy
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_plane
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_pipe_crc_basic
results: parsing output: 95/ for test: kms_psr
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: sysfs_preempt_timeout
results: parsing output: 98/ for test: kms_selftest
results: parsing output: 99/ for test: syncobj_timeline
results: no output, setting notrun
results: parsing output: 100/ for test: gem_exec_balancer
results: no output, setting notrun
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 102/ for test: kms_cursor_legacy
results: no output, setting notrun
results: parsing output: 103/ for test: kms_pm_backlight
results: no output, setting notrun
results: parsing output: 104/ for test: kms_chamelium_hpd
results: no output, setting notrun
results: parsing output: 105/ for test: kms_rotation_crc
results: no output, setting notrun
results: parsing output: 106/ for test: kms_getfb
results: no output, setting notrun
results: parsing output: 107/ for test: gem_eio
results: no output, setting notrun
results: parsing output: 108/ for test: syncobj_timeline
results: no output, setting notrun
results: parsing output: 109/ for test: sriov_basic
results: no output, setting notrun
results: parsing output: 110/ for test: kms_cursor_legacy
results: no output, setting notrun
results: parsing output: 111/ for test: syncobj_timeline
results: no output, setting notrun
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 113/ for test: syncobj_basic
results: no output, setting notrun
results: parsing output: 114/ for test: kms_big_fb
results: no output, setting notrun
results: parsing output: 115/ for test: kms_prop_blob
results: no output, setting notrun
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 117/ for test: gen9_exec_parse
results: no output, setting notrun
results: parsing output: 118/ for test: kms_big_fb
results: no output, setting notrun
results: parsing output: 119/ for test: gem_mmap_wc
results: no output, setting notrun
results: parsing output: 120/ for test: kms_debugfs
results: no output, setting notrun
results: parsing output: 121/ for test: kms_busy
results: no output, setting notrun
results: parsing output: 122/ for test: kms_cursor_legacy
results: no output, setting notrun
results: parsing output: 123/ for test: vgem_basic
results: no output, setting notrun
results: parsing output: 124/ for test: perf
results: no output, setting notrun
results: parsing output: 125/ for test: api_intel_allocator
results: no output, setting notrun
results: parsing output: 126/ for test: gem_render_copy
results: no output, setting notrun
results: parsing output: 127/ for test: kms_cursor_legacy
results: no output, setting notrun
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 130/ for test: gem_pread
results: no output, setting notrun
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
Done.