[54.713662] Initializing watchdogs
[54.713706]   /dev/watchdog0
[54.728263] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[54.732724] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.734897] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.738144] [001/134] (960s left) gem_caching (reads)
[54.811339] Subtest reads: SKIP (0.000s)
[54.890261] [002/134] (960s left) i915_hangman (gt-engine-error)
[54.956124] Starting subtest: gt-engine-error
[54.958696] Starting dynamic subtest: rcs0
[56.886263] Dynamic subtest rcs0: SUCCESS (1.928s)
[56.896917] Starting dynamic subtest: bcs0
[58.867895] Dynamic subtest bcs0: SUCCESS (1.980s)
[58.877993] Starting dynamic subtest: vcs0
[60.852051] Dynamic subtest vcs0: SUCCESS (1.983s)
[60.864774] Starting dynamic subtest: vcs1
[62.900618] Dynamic subtest vcs1: SUCCESS (2.048s)
[62.909082] Starting dynamic subtest: vecs0
[64.883829] Dynamic subtest vecs0: SUCCESS (1.982s)
[64.893880] Starting dynamic subtest: ccs0
[66.867318] Dynamic subtest ccs0: SUCCESS (1.982s)
[66.870260] Subtest gt-engine-error: SUCCESS (11.911s)
[67.036789] [003/134] (948s left) gem_mmap_gtt (basic)
[67.100503] Subtest basic: SKIP (0.000s)
[67.161483] [004/134] (948s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[67.267156] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[67.271580] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[67.383753] [005/134] (948s left) gem_render_copy_redux (interruptible)
[67.436459] Starting subtest: interruptible
[67.714036] Subtest interruptible: SUCCESS (0.279s)
[67.872131] [006/134] (947s left) gem_madvise (dontneed-before-mmap)
[67.905125] Starting subtest: dontneed-before-mmap
[67.936539] Subtest dontneed-before-mmap: SUCCESS (0.031s)
[68.045333] [007/134] (947s left) syncobj_eventfd (timeline-wait)
[68.094710] Starting subtest: timeline-wait
[68.105464] Subtest timeline-wait: SUCCESS (0.011s)
[68.186134] [008/134] (947s left) i915_pm_rc6_residency (rc6-idle)
[68.234507] Starting subtest: rc6-idle
[68.277279] Starting dynamic subtest: gt0-rcs0
[92.612053] Dynamic subtest gt0-rcs0: SUCCESS (24.334s)
[92.621969] Starting dynamic subtest: gt0-bcs0
[116.962321] Dynamic subtest gt0-bcs0: SUCCESS (24.350s)
[116.971238] Starting dynamic subtest: gt0-ccs0
[141.347081] Dynamic subtest gt0-ccs0: SUCCESS (24.384s)
[141.354856] Starting dynamic subtest: gt1-vcs0
[147.519924] Dynamic subtest gt1-vcs0: SUCCESS (6.169s)
[147.529666] Starting dynamic subtest: gt1-vecs0
[153.741286] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[153.743966] Subtest rc6-idle: SUCCESS (85.457s)
[153.783366] [009/134] (861s left) kms_chamelium_frames (hdmi-crc-fast)
[154.175735] Subtest hdmi-crc-fast: SKIP (0.000s)
[154.992271] [010/134] (860s left) kms_cdclk (mode-transition)
[155.090297] Starting subtest: mode-transition
[155.092940] Starting dynamic subtest: pipe-A-eDP-1
[155.385915] Dynamic subtest pipe-A-eDP-1: SKIP (0.295s)
[155.396252] Starting dynamic subtest: pipe-B-eDP-1
[155.400822] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[155.408329] Starting dynamic subtest: pipe-C-eDP-1
[155.412759] Dynamic subtest pipe-C-eDP-1: SKIP (0.002s)
[155.420104] Starting dynamic subtest: pipe-D-eDP-1
[155.424064] Dynamic subtest pipe-D-eDP-1: SKIP (0.002s)
[155.427966] Subtest mode-transition: SKIP (0.304s)
[156.261753] [011/134] (859s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[156.356723] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[163.045260] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.691s)
[163.204122] [012/134] (852s left) gem_mmap_gtt (basic-read-write)
[163.265077] Subtest basic-read-write: SKIP (0.000s)
[163.337195] [013/134] (852s left) api_intel_allocator (execbuf-with-allocator)
[163.390811] Starting subtest: execbuf-with-allocator
[163.395569] Subtest execbuf-with-allocator: SUCCESS (0.006s)
[163.533414] [014/134] (852s left) kms_plane_scaling (invalid-num-scalers)
[163.626710] Starting subtest: invalid-num-scalers
[163.629063] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[163.644970] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.017s)
[163.655210] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[163.680489] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.034s)
[163.688751] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[163.714686] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.033s)
[163.722197] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[163.748181] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.033s)
[163.750465] Subtest invalid-num-scalers: SUCCESS (0.123s)
[163.867647] [015/134] (851s left) kms_invalid_mode (zero-hdisplay)
[163.974157] Starting subtest: zero-hdisplay
[163.976368] Starting dynamic subtest: pipe-A-eDP-1
[164.259552] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[164.267509] Starting dynamic subtest: pipe-B-eDP-1
[164.269785] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[164.277556] Starting dynamic subtest: pipe-C-eDP-1
[164.279871] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[164.287639] Starting dynamic subtest: pipe-D-eDP-1
[164.289581] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[164.291533] Subtest zero-hdisplay: SUCCESS (0.290s)
[165.124400] [016/134] (850s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[165.238255] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[165.242759] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[165.357854] [017/134] (850s left) syncobj_timeline (invalid-query-illegal-handle)
[165.409704] Starting subtest: invalid-query-illegal-handle
[165.411956] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[165.509139] [018/134] (850s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[165.602363] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[165.604753] Starting dynamic subtest: pipe-A-eDP-1
[165.662912] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[165.672107] Starting dynamic subtest: pipe-B-eDP-1
[165.730221] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[165.740520] Starting dynamic subtest: pipe-C-eDP-1
[165.797487] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[165.806609] Starting dynamic subtest: pipe-D-eDP-1
[165.863042] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[165.866996] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.007s)
[167.802371] [019/134] (848s left) kms_addfb_basic (bad-pitch-0)
[167.865681] Starting subtest: bad-pitch-0
[167.867638] Subtest bad-pitch-0: SUCCESS (0.000s)
[168.033387] [020/134] (847s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[168.085618] Starting subtest: multi-wait-for-submit-available-signaled
[168.087844] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[168.222456] [021/134] (847s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[168.330063] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[168.334158] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[168.448262] [022/134] (847s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[168.544464] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[172.225892] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.685s)
[173.404162] [023/134] (842s left) gen9_exec_parse (unaligned-access)
[173.455934] Subtest unaligned-access: SKIP (0.000s)
[173.528263] [024/134] (842s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[173.619475] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[173.623677] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[173.760623] [025/134] (842s left) kms_psr (psr2-sprite-plane-move)
[173.860869] Starting subtest: psr2-sprite-plane-move
[173.863321] Starting dynamic subtest: eDP-1
[175.609304] Dynamic subtest eDP-1: SUCCESS (1.749s)
[175.611690] Subtest psr2-sprite-plane-move: SUCCESS (1.750s)
[176.459903] [026/134] (839s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[181.442641] Starting subtest: pr-primary-plane-update-sf-dmg-area
[181.447415] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.002s)
[182.286104] [027/134] (833s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[182.385701] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[182.390255] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[182.483230] [028/134] (833s left) kms_psr (fbc-pr-sprite-render)
[182.583105] Starting subtest: fbc-pr-sprite-render
[182.587245] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[182.679405] [029/134] (833s left) kms_dp_link_training (uhbr-sst)
[182.783207] Starting subtest: uhbr-sst
[182.791860] Subtest uhbr-sst: SKIP (0.000s)
[182.876267] [030/134] (833s left) gem_exec_alignment (pi-isolated)
[182.930977] Starting subtest: pi-isolated
[235.235604] Subtest pi-isolated: SUCCESS (52.300s)
[236.153342] [031/134] (780s left) gem_exec_schedule (preempt-queue)
[236.269992] Subtest preempt-queue: SKIP (0.000s)
[236.578842] [032/134] (779s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[236.691328] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[236.695817] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.002s)
[236.803251] [033/134] (779s left) gem_exec_schedule (u-semaphore-noskip)
[236.864653] Starting subtest: u-semaphore-noskip
[237.253982] Subtest u-semaphore-noskip: SUCCESS (0.395s)
[237.609204] [034/134] (778s left) kms_flip (2x-nonexisting-fb)
[237.696963] Starting subtest: 2x-nonexisting-fb
[237.706262] Subtest 2x-nonexisting-fb: SKIP (0.011s)
[237.808188] [035/134] (778s left) perf_pmu (most-busy-idle-check-all)
[237.846113] Starting subtest: most-busy-idle-check-all
[237.848265] Starting dynamic subtest: rcs0
[239.893944] Dynamic subtest rcs0: SUCCESS (2.046s)
[239.904317] Starting dynamic subtest: bcs0
[241.876516] Dynamic subtest bcs0: SUCCESS (1.980s)
[241.886353] Starting dynamic subtest: vcs0
[243.926526] Dynamic subtest vcs0: SUCCESS (2.048s)
[243.938039] Starting dynamic subtest: vcs1
[245.908758] Dynamic subtest vcs1: SUCCESS (1.981s)
[245.920418] Starting dynamic subtest: vecs0
[247.892186] Dynamic subtest vecs0: SUCCESS (1.982s)
[247.904061] Starting dynamic subtest: ccs0
[249.940944] Dynamic subtest ccs0: SUCCESS (2.047s)
[249.943750] Subtest most-busy-idle-check-all: SUCCESS (12.094s)
[250.063519] [036/134] (766s left) syncobj_wait (multi-wait-all-submitted-signaled)
[250.123139] Starting subtest: multi-wait-all-submitted-signaled
[250.224096] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.102s)
[250.316691] [037/134] (766s left) gen7_exec_parse (cmd-crossing-page)
[250.379602] Subtest cmd-crossing-page: SKIP (0.000s)
[250.464863] [038/134] (766s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[250.562021] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[250.566505] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[250.701148] [039/134] (766s left) kms_flip (2x-dpms-vs-vblank-race)
[250.808061] Starting subtest: 2x-dpms-vs-vblank-race
[250.817329] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[250.910191] [040/134] (765s left) kms_cursor_crc (cursor-alpha-transparent)
[251.010170] Starting subtest: cursor-alpha-transparent
[251.012384] Starting dynamic subtest: pipe-A-eDP-1
[251.669017] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.660s)
[251.678701] Starting dynamic subtest: pipe-D-eDP-1
[252.943274] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.270s)
[252.945150] Subtest cursor-alpha-transparent: SUCCESS (1.933s)
[253.753528] [041/134] (763s left) kms_plane_cursor (primary)
[253.845150] Starting subtest: primary
[253.849775] Starting dynamic subtest: pipe-A-eDP-1-size-64
[256.564244] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.717s)
[256.588080] Starting dynamic subtest: pipe-A-eDP-1-size-128
[259.243620] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.660s)
[259.266418] Starting dynamic subtest: pipe-A-eDP-1-size-256
[261.944243] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.683s)
[262.259143] Starting dynamic subtest: pipe-B-eDP-1-size-64
[265.619504] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.371s)
[265.643462] Starting dynamic subtest: pipe-B-eDP-1-size-128
[268.230138] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.594s)
[268.256911] Starting dynamic subtest: pipe-B-eDP-1-size-256
[270.954738] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.704s)
[271.282312] Starting dynamic subtest: pipe-C-eDP-1-size-64
[274.328323] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.059s)
[274.355327] Starting dynamic subtest: pipe-C-eDP-1-size-128
[276.550510] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.202s)
[276.576851] Starting dynamic subtest: pipe-C-eDP-1-size-256
[278.807165] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.236s)
[279.123466] Starting dynamic subtest: pipe-D-eDP-1-size-64
[282.077841] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.963s)
[282.104397] Starting dynamic subtest: pipe-D-eDP-1-size-128
[284.333654] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.236s)
[284.359680] Starting dynamic subtest: pipe-D-eDP-1-size-256
[286.577552] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.223s)
[286.882995] Subtest primary: SUCCESS (33.036s)
[287.782415] [042/134] (729s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[287.895454] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[292.252061] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.360s)
[293.492064] [043/134] (723s left) gem_exec_reloc (basic-write-read-active)
[293.557793] Subtest basic-write-read-active: SKIP (0.000s)
[293.648972] [044/134] (723s left) gem_spin_batch (resubmit-new-all)
[293.703668] Starting subtest: resubmit-new-all
[293.706069] Starting dynamic subtest: rcs0
[293.718637] Dynamic subtest rcs0: SUCCESS (0.015s)
[293.729321] Starting dynamic subtest: bcs0
[293.731606] Dynamic subtest bcs0: SUCCESS (0.009s)
[293.739454] Starting dynamic subtest: vcs0
[293.741759] Dynamic subtest vcs0: SUCCESS (0.010s)
[293.749998] Starting dynamic subtest: vcs1
[293.751913] Dynamic subtest vcs1: SUCCESS (0.008s)
[293.760445] Starting dynamic subtest: vecs0
[293.762556] Dynamic subtest vecs0: SUCCESS (0.008s)
[293.770648] Starting dynamic subtest: ccs0
[293.772494] Dynamic subtest ccs0: SUCCESS (0.008s)
[293.774368] Subtest resubmit-new-all: SUCCESS (0.062s)
[294.994859] [045/134] (721s left) perf (gen12-invalid-class-instance)
[295.146261] Starting subtest: gen12-invalid-class-instance
[295.155879] Subtest gen12-invalid-class-instance: SUCCESS (0.010s)
[295.201437] [046/134] (721s left) gem_partial_pwrite_pread (write-display)
[295.264185] Subtest write-display: SKIP (0.000s)
[295.346313] [047/134] (721s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[295.628842] Starting subtest: cursor-rapid-movement-max-size
[295.635327] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[295.791615] [048/134] (721s left) prime_vgem (basic-fence-flip)
[295.956503] Starting subtest: basic-fence-flip
[296.247379] Subtest basic-fence-flip: SUCCESS (0.291s)
[296.375480] [FACT prime_vgem (basic-fence-flip)] new: kernel.kmod_is_loaded.vgem: true
[296.378869] [049/134] (720s left) gem_exec_endless (dispatch)
[296.447698] Starting subtest: dispatch
[296.450110] Starting dynamic subtest: rcs0
[296.457527] Dynamic subtest rcs0: SUCCESS (0.005s)
[296.468106] Starting dynamic subtest: bcs0
[296.472577] Dynamic subtest bcs0: SUCCESS (0.004s)
[296.481343] Starting dynamic subtest: vcs0
[296.485594] Dynamic subtest vcs0: SUCCESS (0.004s)
[296.493692] Starting dynamic subtest: vcs1
[296.498065] Dynamic subtest vcs1: SUCCESS (0.005s)
[296.508684] Starting dynamic subtest: vecs0
[296.512830] Dynamic subtest vecs0: SUCCESS (0.005s)
[296.521070] Starting dynamic subtest: ccs0
[296.525107] Dynamic subtest ccs0: SUCCESS (0.005s)
[296.527366] Subtest dispatch: SUCCESS (0.032s)
[297.992149] [050/134] (719s left) kms_flip (plain-flip-fb-recreate)
[298.088808] Starting subtest: plain-flip-fb-recreate
[298.091154] Starting dynamic subtest: A-eDP1
[306.948727] Dynamic subtest A-eDP1: SUCCESS (8.862s)
[306.958728] Starting dynamic subtest: B-eDP1
[315.781300] Dynamic subtest B-eDP1: SUCCESS (8.831s)
[315.794096] Starting dynamic subtest: C-eDP1
[324.599697] Dynamic subtest C-eDP1: SUCCESS (8.816s)
[324.610363] Starting dynamic subtest: D-eDP1
[333.435933] Dynamic subtest D-eDP1: SUCCESS (8.835s)
[333.440190] Subtest plain-flip-fb-recreate: SUCCESS (35.355s)
[334.630147] [051/134] (682s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[334.752412] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[334.756902] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[334.909176] [052/134] (682s left) kms_psr (pr-primary-render)
[335.005980] Starting subtest: pr-primary-render
[335.010459] Subtest pr-primary-render: SKIP (0.000s)
[335.093197] [053/134] (682s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[335.196116] Starting subtest: multiplane-rotation-cropping-bottom
[342.466227] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.271s)
[342.625040] [054/134] (674s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[342.726631] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[342.730559] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[342.822208] [055/134] (674s left) kms_atomic (plane-overlay-legacy)
[342.903375] Starting subtest: plane-overlay-legacy
[342.904960] Starting dynamic subtest: pipe-A-eDP-1
[343.313950] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.411s)
[343.316424] Subtest plane-overlay-legacy: SUCCESS (0.412s)
[344.171495] [056/134] (673s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[344.224404] Starting subtest: legacy-engines-hostile-preempt
[344.226650] Starting dynamic subtest: render
[344.318525] Dynamic subtest render: SUCCESS (0.097s)
[344.332990] Starting dynamic subtest: bsd1
[345.858416] Dynamic subtest bsd1: SUCCESS (1.535s)
[345.869692] Starting dynamic subtest: bsd2
[347.946369] Dynamic subtest bsd2: SUCCESS (2.085s)
[347.957811] Starting dynamic subtest: blt
[347.980612] Dynamic subtest blt: SUCCESS (0.032s)
[347.991307] Starting dynamic subtest: vebox
[349.936589] Dynamic subtest vebox: SUCCESS (1.951s)
[349.939611] Subtest legacy-engines-hostile-preempt: SUCCESS (5.715s)
[350.029467] [057/134] (667s left) gem_exec_whisper (basic-queues-forked-all)
[350.092386] Starting subtest: basic-queues-forked-all
[368.368809] Subtest basic-queues-forked-all: SUCCESS (18.272s)
[368.551537] [058/134] (648s left) gem_exec_balancer (hog)
[368.617426] Starting subtest: hog
[368.621930] Subtest hog: SKIP (0.000s)
[368.973579] [059/134] (648s left) gem_eio (in-flight-internal-10ms)
[369.217619] Starting subtest: in-flight-internal-10ms
[370.988784] Subtest in-flight-internal-10ms: SUCCESS (1.770s)
[371.171923] [060/134] (646s left) gem_create (create-ext-cpu-access-big)
[371.341680] Starting subtest: create-ext-cpu-access-big
[371.346316] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[371.441297] [061/134] (646s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[371.541398] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[371.545400] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[371.644913] [062/134] (645s left) kms_big_fb (linear-32bpp-rotate-180)
[371.746916] Starting subtest: linear-32bpp-rotate-180
[373.236793] Subtest linear-32bpp-rotate-180: SUCCESS (1.490s)
[373.382935] [063/134] (644s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[373.449018] Subtest tiled-to-untiled: SKIP (0.000s)
[373.541364] [064/134] (644s left) kms_cursor_crc (cursor-offscreen-512x170)
[373.816295] Starting subtest: cursor-offscreen-512x170
[373.820298] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[373.991066] [065/134] (643s left) gem_mmap_wc (pf-nonblock)
[374.052040] Subtest pf-nonblock: SKIP (0.000s)
[374.120312] [066/134] (643s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[374.227529] Starting subtest: read-crc-frame-sequence
[374.229373] Starting dynamic subtest: pipe-A-eDP-1
[374.926125] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.698s)
[374.936170] Starting dynamic subtest: pipe-B-eDP-1
[376.308784] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.382s)
[376.321412] Starting dynamic subtest: pipe-C-eDP-1
[377.603217] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.290s)
[377.612994] Starting dynamic subtest: pipe-D-eDP-1
[378.931104] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[378.933470] Subtest read-crc-frame-sequence: SUCCESS (4.704s)
[379.792026] [067/134] (638s left) kms_addfb_basic (basic-y-tiled-legacy)
[379.859883] Starting subtest: basic-y-tiled-legacy
[379.864220] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[380.014142] [068/134] (637s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[380.117993] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[386.534217] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.421s)
[386.684491] [069/134] (631s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[386.741790] Starting subtest: y-tiled-to-vebox-x-tiled
[386.746215] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[386.939597] [070/134] (631s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[387.037800] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[394.463275] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.428s)
[394.627655] [071/134] (623s left) gem_ctx_persistence (engines-mixed-process)
[394.691343] Starting subtest: engines-mixed-process
[394.693760] Starting dynamic subtest: rcs0
[395.961125] Dynamic subtest rcs0: SUCCESS (1.269s)
[395.972710] Starting dynamic subtest: bcs0
[397.908111] Dynamic subtest bcs0: SUCCESS (1.945s)
[397.918448] Starting dynamic subtest: vcs0
[399.913837] Dynamic subtest vcs0: SUCCESS (2.003s)
[399.925236] Starting dynamic subtest: vcs1
[401.960025] Dynamic subtest vcs1: SUCCESS (2.044s)
[401.970094] Starting dynamic subtest: vecs0
[403.963752] Dynamic subtest vecs0: SUCCESS (2.002s)
[403.975308] Starting dynamic subtest: ccs0
[405.941056] Dynamic subtest ccs0: SUCCESS (1.975s)
[405.943690] Subtest engines-mixed-process: SUCCESS (11.251s)
[406.047515] [072/134] (612s left) sysfs_heartbeat_interval (nopreempt)
[406.111345] Starting subtest: nopreempt
[406.113561] Starting dynamic subtest: vecs0
[415.917309] Dynamic subtest vecs0: SUCCESS (9.805s)
[415.932459] Starting dynamic subtest: ccs0
[419.968793] Dynamic subtest ccs0: SUCCESS (4.047s)
[419.980503] Starting dynamic subtest: vcs0
[429.921926] Dynamic subtest vcs0: SUCCESS (9.949s)
[429.933960] Starting dynamic subtest: bcs0
[433.839286] Dynamic subtest bcs0: SUCCESS (3.919s)
[433.850638] Starting dynamic subtest: rcs0
[437.924970] Dynamic subtest rcs0: SUCCESS (4.083s)
[437.941018] Starting dynamic subtest: vcs1
[447.904575] Dynamic subtest vcs1: SUCCESS (9.975s)
[447.907138] Subtest nopreempt: SUCCESS (41.791s)
[447.992908] [073/134] (570s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[448.095440] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[454.606311] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.513s)
[454.735525] [074/134] (563s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[454.846335] Starting subtest: plane-scaler-unity-scaling-with-rotation
[454.848302] Starting dynamic subtest: pipe-A
[455.083857] Dynamic subtest pipe-A: SUCCESS (0.240s)
[455.094283] Starting dynamic subtest: pipe-B
[456.340562] Dynamic subtest pipe-B: SUCCESS (1.256s)
[456.350419] Starting dynamic subtest: pipe-C
[457.671021] Dynamic subtest pipe-C: SUCCESS (1.330s)
[457.681075] Starting dynamic subtest: pipe-D
[459.002722] Dynamic subtest pipe-D: SUCCESS (1.331s)
[459.005484] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.159s)
[460.198702] [075/134] (557s left) kms_pm_rpm (basic-pci-d3-state)
[462.026060] Starting subtest: basic-pci-d3-state
[463.519077] Subtest basic-pci-d3-state: SUCCESS (1.491s)
[463.622290] [076/134] (554s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[463.683099] Starting subtest: etime-single-wait-all-available-unsubmitted
[463.783907] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[463.873926] [077/134] (554s left) kms_colorop (plane-xr24-xr24-pq_125_eotf)
[464.008212] Subtest plane-XR24-XR24-pq_125_eotf: SKIP (0.000s)
[464.087472] [078/134] (554s left) gem_mmap_gtt (close-race)
[464.145614] Subtest close-race: SKIP (0.000s)
[464.215598] [079/134] (554s left) api_intel_bb (reset-flags)
[464.269623] Starting subtest: reset-flags
[464.275417] Subtest reset-flags: SUCCESS (0.007s)
[464.430437] [080/134] (553s left) kms_prop_blob (invalid-set-prop-any)
[464.475412] Starting subtest: invalid-set-prop-any
[464.477760] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[464.602656] [081/134] (553s left) gem_bad_reloc (negative-reloc-lut)
[464.669051] Subtest negative-reloc-lut: SKIP (0.000s)
[464.751274] [082/134] (553s left) gem_exec_flush (basic-uc-ro-default)
[464.814061] Starting subtest: basic-uc-ro-default
[470.301767] Subtest basic-uc-ro-default: SUCCESS (5.460s)
[470.475586] [083/134] (548s left) dmabuf_sync_file (import-multiple-read-write)
[470.514339] Starting subtest: import-multiple-read-write
[470.516668] Subtest import-multiple-read-write: SUCCESS (0.003s)
[470.580649] [084/134] (547s left) gem_softpin (noreloc-s3)
[470.641314] Starting subtest: noreloc-S3
[491.311500] Subtest noreloc-S3: SUCCESS (5.414s)
[491.524609] Aborting: Kernel badly tainted (0x4244, 0x200) (check dmesg for details):
	TAINT_WARN: WARN_ON has happened.

[491.547995] [FACT gem_softpin (noreloc-s3)] new: kernel.is_tainted.taint_warn: true
[491.548840] Closing watchdogs
results: parsing output: 0/ for test: gem_caching
results: parsing output: 1/ for test: i915_hangman
results: parsing output: 2/ for test: gem_mmap_gtt
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_render_copy_redux
results: parsing output: 5/ for test: gem_madvise
results: parsing output: 6/ for test: syncobj_eventfd
results: parsing output: 7/ for test: i915_pm_rc6_residency
results: parsing output: 8/ for test: kms_chamelium_frames
results: parsing output: 9/ for test: kms_cdclk
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: gem_mmap_gtt
results: parsing output: 12/ for test: api_intel_allocator
results: parsing output: 13/ for test: kms_plane_scaling
results: parsing output: 14/ for test: kms_invalid_mode
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: kms_ccs
results: parsing output: 18/ for test: kms_addfb_basic
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_psr2_sf
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_psr
results: parsing output: 28/ for test: kms_dp_link_training
results: parsing output: 29/ for test: gem_exec_alignment
results: parsing output: 30/ for test: gem_exec_schedule
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: kms_flip
results: parsing output: 34/ for test: perf_pmu
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gen7_exec_parse
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_flip
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: kms_plane_cursor
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_exec_reloc
results: parsing output: 43/ for test: gem_spin_batch
results: parsing output: 44/ for test: perf
results: parsing output: 45/ for test: gem_partial_pwrite_pread
results: parsing output: 46/ for test: kms_cursor_crc
results: parsing output: 47/ for test: prime_vgem
results: parsing output: 48/ for test: gem_exec_endless
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_cursor_legacy
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_rotation_crc
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_atomic
results: parsing output: 55/ for test: gem_ctx_persistence
results: parsing output: 56/ for test: gem_exec_whisper
results: parsing output: 57/ for test: gem_exec_balancer
results: parsing output: 58/ for test: gem_eio
results: parsing output: 59/ for test: gem_create
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: gem_set_tiling_vs_blt
results: parsing output: 63/ for test: kms_cursor_crc
results: parsing output: 64/ for test: gem_mmap_wc
results: parsing output: 65/ for test: kms_pipe_crc_basic
results: parsing output: 66/ for test: kms_addfb_basic
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_render_copy
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_ctx_persistence
results: parsing output: 71/ for test: sysfs_heartbeat_interval
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_plane_scaling
results: parsing output: 74/ for test: kms_pm_rpm
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: kms_colorop
results: parsing output: 77/ for test: gem_mmap_gtt
results: parsing output: 78/ for test: api_intel_bb
results: parsing output: 79/ for test: kms_prop_blob
results: parsing output: 80/ for test: gem_bad_reloc
results: parsing output: 81/ for test: gem_exec_flush
results: parsing output: 82/ for test: dmabuf_sync_file
results: parsing output: 83/ for test: gem_softpin
results: parsing output: 84/ for test: syncobj_wait
results: no output, setting notrun
results: parsing output: 85/ for test: gem_exec_params
results: no output, setting notrun
results: parsing output: 86/ for test: kms_big_fb
results: no output, setting notrun
results: parsing output: 87/ for test: syncobj_wait
results: no output, setting notrun
results: parsing output: 88/ for test: gem_exec_fence
results: no output, setting notrun
results: parsing output: 89/ for test: gem_reg_read
results: no output, setting notrun
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 91/ for test: gem_userptr_blits
results: no output, setting notrun
results: parsing output: 92/ for test: sw_sync
results: no output, setting notrun
results: parsing output: 93/ for test: gem_exec_params
results: no output, setting notrun
results: parsing output: 94/ for test: kms_big_fb
results: no output, setting notrun
results: parsing output: 95/ for test: kms_colorop
results: no output, setting notrun
results: parsing output: 96/ for test: kms_ccs
results: no output, setting notrun
results: parsing output: 97/ for test: kms_getfb
results: no output, setting notrun
results: parsing output: 98/ for test: vgem_basic
results: no output, setting notrun
results: parsing output: 99/ for test: kms_ccs
results: no output, setting notrun
results: parsing output: 100/ for test: kms_flip_scaled_crc
results: no output, setting notrun
results: parsing output: 101/ for test: gem_exec_params
results: no output, setting notrun
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 103/ for test: gem_exec_reloc
results: no output, setting notrun
results: parsing output: 104/ for test: gem_exec_whisper
results: no output, setting notrun
results: parsing output: 105/ for test: kms_sequence
results: no output, setting notrun
results: parsing output: 106/ for test: syncobj_basic
results: no output, setting notrun
results: parsing output: 107/ for test: prime_self_import
results: no output, setting notrun
results: parsing output: 108/ for test: syncobj_timeline
results: no output, setting notrun
results: parsing output: 109/ for test: kms_big_fb
results: no output, setting notrun
results: parsing output: 110/ for test: gem_caching
results: no output, setting notrun
results: parsing output: 111/ for test: gem_pxp
results: no output, setting notrun
results: parsing output: 112/ for test: gem_mmap_offset
results: no output, setting notrun
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 115/ for test: kms_ccs
results: no output, setting notrun
results: parsing output: 116/ for test: syncobj_wait
results: no output, setting notrun
results: parsing output: 117/ for test: sysfs_timeslice_duration
results: no output, setting notrun
results: parsing output: 118/ for test: kms_universal_plane
results: no output, setting notrun
results: parsing output: 119/ for test: kms_psr
results: no output, setting notrun
results: parsing output: 120/ for test: kms_pipe_b_c_ivb
results: no output, setting notrun
results: parsing output: 121/ for test: kms_addfb_basic
results: no output, setting notrun
results: parsing output: 122/ for test: kms_big_fb
results: no output, setting notrun
results: parsing output: 123/ for test: kms_dsc
results: no output, setting notrun
results: parsing output: 124/ for test: kms_psr2_sf
results: no output, setting notrun
results: parsing output: 125/ for test: kms_color
results: no output, setting notrun
results: parsing output: 126/ for test: kms_pm_rpm
results: no output, setting notrun
results: parsing output: 127/ for test: gem_tiled_fence_blits
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: perf_pmu
results: no output, setting notrun
results: parsing output: 130/ for test: kms_colorop
results: no output, setting notrun
results: parsing output: 131/ for test: i915_power
results: no output, setting notrun
results: parsing output: 132/ for test: gem_exec_params
results: no output, setting notrun
results: parsing output: 133/ for test: sw_sync
results: no output, setting notrun
Done.