[55.567259] Initializing watchdogs [55.567311] /dev/watchdog0 [55.580877] [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] [55.585250] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [55.587372] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [55.590614] [001/129] (960s left) kms_flip (basic-flip-vs-modeset) [55.757284] Starting subtest: basic-flip-vs-modeset [55.759647] Starting dynamic subtest: A-eDP1 [58.383862] Dynamic subtest A-eDP1: SUCCESS (2.623s) [58.391675] Starting dynamic subtest: B-eDP1 [60.530355] Dynamic subtest B-eDP1: SUCCESS (2.148s) [60.537805] Starting dynamic subtest: C-eDP1 [62.671058] Dynamic subtest C-eDP1: SUCCESS (2.139s) [62.677488] Starting dynamic subtest: D-eDP1 [64.725485] Dynamic subtest D-eDP1: SUCCESS (2.055s) [64.728077] Subtest basic-flip-vs-modeset: SUCCESS (8.971s) [65.514920] [002/129] (950s left) kms_plane (planar-pixel-format-settings) [65.612769] Starting subtest: planar-pixel-format-settings [65.650785] Subtest planar-pixel-format-settings: SUCCESS (0.037s) [65.752360] [003/129] (950s left) kms_hdr (brightness-with-hdr) [65.855955] Starting subtest: brightness-with-hdr [65.862609] Subtest brightness-with-hdr: SKIP (0.000s) [65.921746] [004/129] (950s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90) [66.022577] Starting subtest: primary-yf-tiled-reflect-x-90 [66.027189] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s) [66.088593] [005/129] (950s left) perf (low-oa-exponent-permissions) [66.199595] Starting subtest: low-oa-exponent-permissions [66.252210] Subtest low-oa-exponent-permissions: SUCCESS (0.053s) [66.293061] [006/129] (949s left) kms_cursor_edge_walk (128x128-top-bottom) [66.386045] Starting subtest: 128x128-top-bottom [66.388450] Starting dynamic subtest: pipe-A-eDP-1 [69.878909] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.492s) [69.885233] Starting dynamic subtest: pipe-D-eDP-1 [72.980544] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.100s) [72.982466] Subtest 128x128-top-bottom: SUCCESS (6.594s) [73.786119] [007/129] (942s left) gem_mmap_gtt (medium-copy-odd) [73.848319] Subtest medium-copy-odd: SKIP (0.000s) [73.900436] [008/129] (942s left) drm_fdinfo (isolation) [73.937817] Starting subtest: isolation [73.940474] Starting dynamic subtest: rcs0 [73.976191] Dynamic subtest rcs0: SKIP (0.036s) [73.985410] Starting dynamic subtest: bcs0 [73.989865] Dynamic subtest bcs0: SKIP (0.000s) [73.996133] Starting dynamic subtest: vcs0 [74.000241] Dynamic subtest vcs0: SKIP (0.001s) [74.006520] Starting dynamic subtest: vcs1 [74.010977] Dynamic subtest vcs1: SKIP (0.001s) [74.017102] Starting dynamic subtest: vecs0 [74.021567] Dynamic subtest vecs0: SKIP (0.001s) [74.027634] Starting dynamic subtest: ccs0 [74.032084] Dynamic subtest ccs0: SKIP (0.001s) [74.036422] Subtest isolation: SKIP (0.041s) [74.063475] [009/129] (942s left) kms_big_fb (linear-64bpp-rotate-180) [74.172144] Starting subtest: linear-64bpp-rotate-180 [76.461085] Subtest linear-64bpp-rotate-180: SUCCESS (2.289s) [76.698349] [010/129] (939s left) kms_flip (flip-vs-dpms-off-vs-modeset) [76.795563] Starting subtest: flip-vs-dpms-off-vs-modeset [76.798044] Starting dynamic subtest: A-eDP1 [80.286165] Dynamic subtest A-eDP1: SUCCESS (3.489s) [80.294061] Starting dynamic subtest: B-eDP1 [83.366803] Dynamic subtest B-eDP1: SUCCESS (3.081s) [83.376663] Starting dynamic subtest: C-eDP1 [86.434242] Dynamic subtest C-eDP1: SUCCESS (3.067s) [86.442316] Starting dynamic subtest: D-eDP1 [89.511614] Dynamic subtest D-eDP1: SUCCESS (3.075s) [89.514090] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (12.717s) [90.300311] [011/129] (926s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size) [90.421434] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size [90.425872] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s) [90.512681] [012/129] (925s left) syncobj_timeline (wait-delayed-signal) [90.572714] Starting subtest: wait-delayed-signal [90.674394] Subtest wait-delayed-signal: SUCCESS (0.103s) [90.744394] [013/129] (925s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs) [90.837897] Starting subtest: missing-ccs-buffer-y-tiled-ccs [90.840180] Starting dynamic subtest: pipe-A-eDP-1 [90.862931] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [90.871802] Starting dynamic subtest: pipe-B-eDP-1 [90.892963] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [90.900168] Starting dynamic subtest: pipe-C-eDP-1 [90.922172] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [90.928920] Starting dynamic subtest: pipe-D-eDP-1 [90.951041] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [90.955129] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s) [92.957391] [014/129] (923s left) syncobj_wait (wait-any-interrupted) [93.015820] Starting subtest: wait-any-interrupted [93.116523] Subtest wait-any-interrupted: SUCCESS (0.102s) [93.183962] [015/129] (923s left) gem_ctx_param (invalid-ctx-set) [93.237641] Starting subtest: invalid-ctx-set [93.240076] Subtest invalid-ctx-set: SUCCESS (0.001s) [93.337651] [016/129] (923s left) kms_rotation_crc (sprite-rotation-90) [93.431905] Starting subtest: sprite-rotation-90 [93.436073] Subtest sprite-rotation-90: SKIP (0.001s) [93.498239] [017/129] (923s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [93.598240] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip [93.602523] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s) [93.706243] [018/129] (922s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt) [93.815179] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt [97.237861] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (3.426s) [97.379778] [019/129] (919s left) kms_psr (psr2-sprite-render) [97.483184] Starting subtest: psr2-sprite-render [97.485055] Starting dynamic subtest: eDP-1 [99.176227] Dynamic subtest eDP-1: SUCCESS (1.692s) [99.178607] Subtest psr2-sprite-render: SUCCESS (1.693s) [100.029837] [020/129] (916s left) i915_hangman (gt-engine-hang) [100.104425] Starting subtest: gt-engine-hang [100.106917] Starting dynamic subtest: rcs0 [101.898263] Dynamic subtest rcs0: SUCCESS (1.792s) [101.908247] Starting dynamic subtest: bcs0 [103.882238] Dynamic subtest bcs0: SUCCESS (1.982s) [103.891353] Starting dynamic subtest: vcs0 [105.864337] Dynamic subtest vcs0: SUCCESS (1.982s) [105.874322] Starting dynamic subtest: vcs1 [107.913087] Dynamic subtest vcs1: SUCCESS (2.047s) [107.922803] Starting dynamic subtest: vecs0 [109.896511] Dynamic subtest vecs0: SUCCESS (1.982s) [109.906973] Starting dynamic subtest: ccs0 [111.880052] Dynamic subtest ccs0: SUCCESS (1.983s) [111.882752] Subtest gt-engine-hang: SUCCESS (11.774s) [111.952106] [021/129] (904s left) fbdev (unaligned-read) [111.990198] Starting subtest: unaligned-read [112.007052] Subtest unaligned-read: SUCCESS (0.016s) [112.052207] [022/129] (904s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen) [112.159483] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen [112.163695] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s) [112.259340] [023/129] (904s left) gem_busy (close-race) [112.333659] Starting subtest: close-race [137.898756] Subtest close-race: SUCCESS (25.563s) [138.229926] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true [138.234182] [024/129] (878s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move) [138.377241] Starting subtest: psr-2p-scndscrn-cur-indfb-move [138.381256] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s) [138.473410] [025/129] (878s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen) [138.577944] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen [143.090049] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.519s) [143.208308] [026/129] (873s left) device_reset (cold-reset-bound) [143.258384] Starting subtest: cold-reset-bound [143.262891] Subtest cold-reset-bound: SKIP (0.000s) [143.331867] [027/129] (873s left) prime_mmap (test_forked) [143.387319] Starting subtest: test_forked [143.389653] Starting dynamic subtest: test_forked-smem [143.428966] Dynamic subtest test_forked-smem: SUCCESS (0.043s) [143.431523] Subtest test_forked: SUCCESS (0.044s) [143.513679] [028/129] (873s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt) [143.609801] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt [143.613638] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s) [143.709091] [029/129] (873s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled) [143.772445] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled [143.776748] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s) [143.953867] [030/129] (873s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc) [144.060092] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc [144.062486] Starting dynamic subtest: pipe-A-eDP-1 [144.087874] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [144.096562] Starting dynamic subtest: pipe-B-eDP-1 [144.118771] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [144.125861] Starting dynamic subtest: pipe-C-eDP-1 [144.148084] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [144.154839] Starting dynamic subtest: pipe-D-eDP-1 [144.176795] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [144.181243] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s) [146.224875] [031/129] (870s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling) [146.322113] Starting subtest: planes-downscale-factor-0-5-unity-scaling [146.324395] Starting dynamic subtest: pipe-A [146.333857] Dynamic subtest pipe-A: SKIP (0.005s) [146.342922] Starting dynamic subtest: pipe-B [146.352106] Dynamic subtest pipe-B: SKIP (0.016s) [146.359801] Starting dynamic subtest: pipe-C [146.368738] Dynamic subtest pipe-C: SKIP (0.017s) [146.376075] Starting dynamic subtest: pipe-D [146.383900] Dynamic subtest pipe-D: SKIP (0.016s) [146.387664] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.055s) [146.485799] [032/129] (870s left) i915_pm_rps (min-max-config-idle) [146.556975] Starting subtest: min-max-config-idle [146.561670] Subtest min-max-config-idle: SKIP (0.000s) [146.634431] [033/129] (870s left) kms_universal_plane (universal-plane-functional) [146.729054] Starting subtest: universal-plane-functional [146.731429] Starting dynamic subtest: pipe-A-eDP-1 [150.543903] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.813s) [150.552058] Starting dynamic subtest: pipe-B-eDP-1 [154.922030] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.376s) [154.930601] Starting dynamic subtest: pipe-C-eDP-1 [159.166181] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.241s) [159.174382] Starting dynamic subtest: pipe-D-eDP-1 [163.432739] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.265s) [163.435151] Subtest universal-plane-functional: SUCCESS (16.701s) [164.222570] [034/129] (852s left) gem_exec_params (batch-first) [164.291415] Starting subtest: batch-first [164.296841] Subtest batch-first: SUCCESS (0.005s) [164.408638] [035/129] (852s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc) [164.509269] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc [164.513158] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [164.584612] [036/129] (852s left) perf (non-system-wide-paranoid) [164.688887] Starting subtest: non-system-wide-paranoid [164.724824] Subtest non-system-wide-paranoid: SUCCESS (0.036s) [164.756968] [037/129] (852s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc) [164.857170] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc [164.859431] Starting dynamic subtest: pipe-A-eDP-1 [164.881551] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [164.889235] Starting dynamic subtest: pipe-B-eDP-1 [164.910374] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [164.917277] Starting dynamic subtest: pipe-C-eDP-1 [164.938805] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [164.945812] Starting dynamic subtest: pipe-D-eDP-1 [164.968057] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [164.972472] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s) [166.979275] [038/129] (850s left) gem_mmap_gtt (zero-extend) [167.044151] Subtest zero-extend: SKIP (0.000s) [167.101392] [039/129] (850s left) gem_exec_reloc (basic-cpu-wc-noreloc) [167.164203] Subtest basic-cpu-wc-noreloc: SKIP (0.000s) [167.250871] [040/129] (850s left) kms_cursor_crc (cursor-onscreen-128x42) [167.451169] Starting subtest: cursor-onscreen-128x42 [167.492532] Subtest cursor-onscreen-128x42: SKIP (0.036s) [167.704589] [041/129] (849s left) kms_dp_link_training (uhbr-mst) [167.813082] Starting subtest: uhbr-mst [167.821972] Subtest uhbr-mst: SKIP (0.000s) [167.881891] [042/129] (849s left) gem_lmem_swapping (massive-random) [167.926093] Subtest massive-random: SKIP (0.000s) [167.966770] [043/129] (849s left) kms_setmode (invalid-clone-single-crtc) [168.034165] Starting subtest: invalid-clone-single-crtc [168.039372] Subtest invalid-clone-single-crtc: SKIP (0.000s) [168.130430] [044/129] (849s left) kms_cursor_crc (cursor-sliding-64x64) [168.288192] Starting subtest: cursor-sliding-64x64 [168.293253] Starting dynamic subtest: pipe-A-eDP-1 [171.856565] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.563s) [171.878812] Starting dynamic subtest: pipe-D-eDP-1 [175.974991] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.101s) [175.977364] Subtest cursor-sliding-64x64: SUCCESS (7.685s) [176.831800] [045/129] (840s left) gem_reg_read (bad-register) [176.895971] Starting subtest: bad-register [176.898182] Subtest bad-register: SUCCESS (0.000s) [177.008276] [046/129] (840s left) gem_exec_whisper (basic-normal-all) [177.079581] Starting subtest: basic-normal-all [179.312817] Subtest basic-normal-all: SUCCESS (2.231s) [179.492147] [047/129] (838s left) syncobj_timeline (single-wait-all-for-submit-available-submitted) [179.555352] Starting subtest: single-wait-all-for-submit-available-submitted [179.557393] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s) [179.672957] [048/129] (837s left) gem_eio (in-flight-internal-immediate) [179.925896] Starting subtest: in-flight-internal-immediate [180.998726] Subtest in-flight-internal-immediate: SUCCESS (1.072s) [181.117897] [049/129] (836s left) gem_create (busy-create) [181.173764] Starting subtest: busy-create [181.176139] Starting dynamic subtest: smem0 [214.965296] Dynamic subtest smem0: SUCCESS (33.789s) [214.967856] Subtest busy-create: SUCCESS (33.790s) [215.149366] [050/129] (802s left) kms_big_fb (linear-16bpp-rotate-270) [215.265397] Starting subtest: linear-16bpp-rotate-270 [215.284704] Subtest linear-16bpp-rotate-270: SKIP (0.019s) [215.415476] [051/129] (802s left) vgem_basic (mmap) [215.452571] Starting subtest: mmap [215.474013] Subtest mmap: SUCCESS (0.024s) [215.527290] [052/129] (802s left) gem_gtt_cpu_tlb [215.643195] [053/129] (802s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt) [215.765227] Starting subtest: fbc-rgb565-draw-mmap-gtt [215.769125] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s) [215.874217] [054/129] (802s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu) [215.983723] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu [220.838192] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.857s) [220.936996] [055/129] (796s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu) [221.042469] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu [221.046984] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [221.162412] [056/129] (796s left) kms_panel_fitting (legacy) [221.265821] Starting subtest: legacy [221.268218] Starting dynamic subtest: pipe-A-eDP-1 [221.894246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.628s) [221.922084] Starting dynamic subtest: pipe-B-eDP-1 [223.473844] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.558s) [223.501565] Starting dynamic subtest: pipe-C-eDP-1 [225.068189] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.572s) [225.092691] Starting dynamic subtest: pipe-D-eDP-1 [226.697595] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.610s) [226.699731] Subtest legacy: SUCCESS (5.430s) [227.874652] [057/129] (790s left) kms_invalid_mode (clock-too-high) [227.987378] Starting subtest: clock-too-high [227.989721] Starting dynamic subtest: pipe-A-eDP-1 [227.994593] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [228.004135] Starting dynamic subtest: pipe-B-eDP-1 [228.008292] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [228.016587] Starting dynamic subtest: pipe-C-eDP-1 [228.021183] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [228.029202] Starting dynamic subtest: pipe-D-eDP-1 [228.033751] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [228.038281] Subtest clock-too-high: SKIP (0.004s) [228.069504] [058/129] (789s left) gem_exec_balancer (bonded-true-hang) [228.315796] Starting subtest: bonded-true-hang [228.320772] Subtest bonded-true-hang: SKIP (0.000s) [228.423845] [059/129] (789s left) syncobj_basic (bad-handle-to-fd) [228.479782] Starting subtest: bad-handle-to-fd [228.481848] Subtest bad-handle-to-fd: SUCCESS (0.000s) [228.586934] [060/129] (789s left) kms_psr (psr2-cursor-plane-onoff) [228.693200] Starting subtest: psr2-cursor-plane-onoff [228.695503] Starting dynamic subtest: eDP-1 [230.347749] Dynamic subtest eDP-1: SUCCESS (1.656s) [230.350322] Subtest psr2-cursor-plane-onoff: SUCCESS (1.657s) [231.127541] [061/129] (786s left) kms_properties (plane-properties-legacy) [231.232041] Starting subtest: plane-properties-legacy [231.234186] Starting dynamic subtest: pipe-A-eDP-1 [231.806730] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.573s) [231.815871] Starting dynamic subtest: pipe-B-eDP-1 [233.100873] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.292s) [233.109349] Starting dynamic subtest: pipe-C-eDP-1 [234.327303] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.225s) [234.335686] Starting dynamic subtest: pipe-D-eDP-1 [235.541582] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.214s) [235.543985] Subtest plane-properties-legacy: SUCCESS (4.309s) [236.334204] [062/129] (781s left) gem_ctx_shared (q-in-order) [236.400749] Starting subtest: Q-in-order [236.403114] Starting dynamic subtest: rcs0 [236.506044] Dynamic subtest rcs0: SUCCESS (0.104s) [236.523040] Starting dynamic subtest: vcs0 [236.589101] Dynamic subtest vcs0: SUCCESS (0.083s) [236.603870] Starting dynamic subtest: vcs1 [236.667209] Dynamic subtest vcs1: SUCCESS (0.077s) [236.682330] Starting dynamic subtest: vecs0 [236.738519] Dynamic subtest vecs0: SUCCESS (0.071s) [236.753032] Starting dynamic subtest: ccs0 [236.826057] Dynamic subtest ccs0: SUCCESS (0.087s) [236.828584] Subtest Q-in-order: SUCCESS (0.426s) [238.041202] [063/129] (780s left) i915_hangman (engine-engine-error) [238.109707] Starting subtest: engine-engine-error [238.112090] Starting dynamic subtest: rcs0 [239.879909] Dynamic subtest rcs0: SUCCESS (1.769s) [239.891328] Starting dynamic subtest: bcs0 [241.864279] Dynamic subtest bcs0: SUCCESS (1.982s) [241.875213] Starting dynamic subtest: vcs0 [243.911677] Dynamic subtest vcs0: SUCCESS (2.045s) [243.923466] Starting dynamic subtest: vcs1 [245.896224] Dynamic subtest vcs1: SUCCESS (1.982s) [245.911186] Starting dynamic subtest: vecs0 [247.880473] Dynamic subtest vecs0: SUCCESS (1.981s) [247.891041] Starting dynamic subtest: ccs0 [249.927874] Dynamic subtest ccs0: SUCCESS (2.045s) [249.931028] Subtest engine-engine-error: SUCCESS (11.818s) [250.005586] [064/129] (768s left) vgem_basic (setversion) [250.050958] Starting subtest: setversion [250.055812] Subtest setversion: SUCCESS (0.000s) [250.105055] [065/129] (768s left) kms_big_fb (y-tiled-64bpp-rotate-0) [250.209379] Starting subtest: y-tiled-64bpp-rotate-0 [250.213816] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s) [250.291635] [066/129] (768s left) kms_pm_backlight (basic-brightness) [250.398057] Starting subtest: basic-brightness [250.458323] Starting dynamic subtest: eDP-1 [250.965116] Dynamic subtest eDP-1: SUCCESS (0.503s) [250.967262] Subtest basic-brightness: SUCCESS (0.566s) [251.763196] [067/129] (766s left) gem_ctx_param (invalid-get-engines) [251.818306] Starting subtest: invalid-get-engines [251.820608] Subtest invalid-get-engines: SUCCESS (0.001s) [251.930536] [068/129] (766s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode) [252.322478] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s) [253.162441] [069/129] (765s left) prime_busy (hang-wait) [253.228931] Starting subtest: hang-wait [253.231340] Starting dynamic subtest: rcs0 [279.362075] Dynamic subtest rcs0: SUCCESS (26.128s) [279.370641] Starting dynamic subtest: bcs0 [286.583300] Dynamic subtest bcs0: SUCCESS (7.218s) [286.595818] Starting dynamic subtest: vcs0 [293.559734] Dynamic subtest vcs0: SUCCESS (6.974s) [293.569831] Starting dynamic subtest: vcs1 [300.535300] Dynamic subtest vcs1: SUCCESS (6.975s) [300.544760] Starting dynamic subtest: vecs0 [307.572650] Dynamic subtest vecs0: SUCCESS (7.035s) [307.586296] Starting dynamic subtest: ccs0 [321.409257] Dynamic subtest ccs0: SUCCESS (13.837s) [321.411791] Subtest hang-wait: SUCCESS (68.178s) [321.510561] [070/129] (696s left) kms_flip (2x-flip-vs-suspend) [321.608098] Starting subtest: 2x-flip-vs-suspend [321.619402] Subtest 2x-flip-vs-suspend: SKIP (0.011s) [321.692143] [071/129] (696s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt) [321.801432] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt [326.396776] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (4.599s) [326.525378] [072/129] (691s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw) [326.634597] Starting subtest: psr-2p-pri-indfb-multidraw [326.639076] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s) [326.736659] [073/129] (691s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc) [326.837112] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc [326.839372] Starting dynamic subtest: pipe-A-eDP-1 [326.865960] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [326.874641] Starting dynamic subtest: pipe-B-eDP-1 [326.898392] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [326.906886] Starting dynamic subtest: pipe-C-eDP-1 [326.930994] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [326.939339] Starting dynamic subtest: pipe-D-eDP-1 [326.963805] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [326.967938] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s) [328.986686] [074/129] (689s left) gem_exec_balancer (invalid-balancer) [329.041810] Starting subtest: invalid-balancer [329.795047] Subtest invalid-balancer: SUCCESS (0.751s) [330.093450] [075/129] (688s left) kms_big_fb (yf-tiled-64bpp-rotate-180) [330.208327] Starting subtest: yf-tiled-64bpp-rotate-180 [330.212589] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s) [330.331083] [076/129] (688s left) perf_pmu (busy-check-all) [330.376704] Starting subtest: busy-check-all [330.379251] Starting dynamic subtest: rcs0 [330.927274] Dynamic subtest rcs0: SUCCESS (0.552s) [330.938140] Starting dynamic subtest: bcs0 [331.476187] Dynamic subtest bcs0: SUCCESS (0.546s) [331.485417] Starting dynamic subtest: vcs0 [332.906244] Dynamic subtest vcs0: SUCCESS (1.428s) [332.916623] Starting dynamic subtest: vcs1 [334.953897] Dynamic subtest vcs1: SUCCESS (2.045s) [334.964962] Starting dynamic subtest: vecs0 [336.937966] Dynamic subtest vecs0: SUCCESS (1.982s) [336.948978] Starting dynamic subtest: ccs0 [337.491324] Dynamic subtest ccs0: SUCCESS (0.552s) [337.493937] Subtest busy-check-all: SUCCESS (7.117s) [337.597797] [077/129] (681s left) kms_lease (page-flip-implicit-plane) [337.708158] Starting subtest: page-flip-implicit-plane [337.710401] Starting dynamic subtest: pipe-A-eDP-1 [338.203152] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.494s) [338.212184] Starting dynamic subtest: pipe-B-eDP-1 [339.316063] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.108s) [339.324307] Starting dynamic subtest: pipe-C-eDP-1 [340.433276] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.115s) [340.442302] Starting dynamic subtest: pipe-D-eDP-1 [341.556301] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s) [341.558656] Subtest page-flip-implicit-plane: SUCCESS (3.847s) [342.383266] [078/129] (676s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs) [342.485103] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs [342.487379] Starting dynamic subtest: pipe-A-eDP-1 [342.509230] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [342.519081] Starting dynamic subtest: pipe-B-eDP-1 [342.540173] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [342.547957] Starting dynamic subtest: pipe-C-eDP-1 [342.574242] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [342.582196] Starting dynamic subtest: pipe-D-eDP-1 [342.605276] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [342.609322] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s) [344.650480] [079/129] (674s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite) [344.756580] Starting subtest: fbcpsr-rgb565-draw-pwrite [349.624024] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (4.870s) [349.744098] [080/129] (669s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [349.841464] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt [349.845607] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [349.932989] [081/129] (668s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible) [350.027326] Starting subtest: flip-vs-absolute-wf_vblank-interruptible [350.029644] Starting dynamic subtest: A-eDP1 [358.946825] Dynamic subtest A-eDP1: SUCCESS (8.918s) [358.954512] Starting dynamic subtest: B-eDP1 [367.873083] Dynamic subtest B-eDP1: SUCCESS (8.925s) [367.881336] Starting dynamic subtest: C-eDP1 [376.786579] Dynamic subtest C-eDP1: SUCCESS (8.912s) [376.798672] Starting dynamic subtest: D-eDP1 [385.710316] Dynamic subtest D-eDP1: SUCCESS (8.921s) [385.712670] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.684s) [386.922479] [082/129] (631s left) gem_spin_batch (resubmit-new) [386.993744] Starting subtest: resubmit-new [386.996249] Starting dynamic subtest: rcs0 [387.001105] Dynamic subtest rcs0: SUCCESS (0.007s) [387.009616] Starting dynamic subtest: bcs0 [387.011806] Dynamic subtest bcs0: SUCCESS (0.003s) [387.019852] Starting dynamic subtest: vcs0 [387.021723] Dynamic subtest vcs0: SUCCESS (0.004s) [387.029680] Starting dynamic subtest: vcs1 [387.031827] Dynamic subtest vcs1: SUCCESS (0.004s) [387.039932] Starting dynamic subtest: vecs0 [387.042168] Dynamic subtest vecs0: SUCCESS (0.003s) [387.050053] Starting dynamic subtest: ccs0 [387.052393] Dynamic subtest ccs0: SUCCESS (0.005s) [387.054673] Subtest resubmit-new: SUCCESS (0.029s) [388.938287] [083/129] (629s left) gem_exec_schedule (preempt-queue-contexts) [389.014387] Subtest preempt-queue-contexts: SKIP (0.000s) [389.296078] [084/129] (629s left) kms_big_fb (4-tiled-32bpp-rotate-90) [389.403577] Starting subtest: 4-tiled-32bpp-rotate-90 [389.423260] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.019s) [389.552492] [085/129] (629s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt) [389.655657] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt [389.659823] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [389.766173] [086/129] (629s left) gem_create (create-ext-cpu-access-sanity-check) [389.926460] Starting subtest: create-ext-cpu-access-sanity-check [389.931688] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s) [389.996318] [087/129] (629s left) gem_exec_fence (syncobj-invalid-flags) [390.412095] Starting subtest: syncobj-invalid-flags [390.414841] Subtest syncobj-invalid-flags: SUCCESS (0.000s) [391.972889] [088/129] (627s left) kms_vblank (wait-forked-busy-hang) [392.062173] Starting subtest: wait-forked-busy-hang [392.064480] Starting dynamic subtest: pipe-A-eDP-1 [418.653173] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.587s) [418.667312] Starting dynamic subtest: pipe-D-eDP-1 [443.686466] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.030s) [443.688635] Subtest wait-forked-busy-hang: SUCCESS (51.622s) [444.464010] [089/129] (574s left) kms_cursor_legacy (short-flip-after-cursor-toggle) [444.590039] Starting subtest: short-flip-after-cursor-toggle [445.509176] Subtest short-flip-after-cursor-toggle: SUCCESS (0.918s) [446.297836] [090/129] (572s left) gem_wait (await) [446.370471] Starting subtest: await [446.372929] Starting dynamic subtest: all-engines [447.487868] Dynamic subtest all-engines: SUCCESS (1.117s) [447.502875] Starting dynamic subtest: rcs0 [448.543784] Dynamic subtest rcs0: SUCCESS (1.054s) [448.558242] Starting dynamic subtest: bcs0 [449.601740] Dynamic subtest bcs0: SUCCESS (1.056s) [449.616522] Starting dynamic subtest: vcs0 [450.653589] Dynamic subtest vcs0: SUCCESS (1.050s) [450.669187] Starting dynamic subtest: vcs1 [451.706706] Dynamic subtest vcs1: SUCCESS (1.051s) [451.721946] Starting dynamic subtest: vecs0 [452.758554] Dynamic subtest vecs0: SUCCESS (1.050s) [452.773848] Starting dynamic subtest: ccs0 [453.814745] Dynamic subtest ccs0: SUCCESS (1.055s) [453.817494] Subtest await: SUCCESS (7.445s) [454.012928] [091/129] (565s left) kms_psr2_sf (pr-cursor-plane-update-sf) [459.063994] Starting subtest: pr-cursor-plane-update-sf [459.068620] Subtest pr-cursor-plane-update-sf: SKIP (0.003s) [459.896885] [092/129] (559s left) gem_exec_schedule (implicit-boths) [459.979448] Starting subtest: implicit-boths [459.981426] Starting dynamic subtest: rcs0 [460.073708] Dynamic subtest rcs0: SUCCESS (0.101s) [460.084768] Starting dynamic subtest: vcs0 [460.147779] Dynamic subtest vcs0: SUCCESS (0.074s) [460.159101] Starting dynamic subtest: vcs1 [460.231821] Dynamic subtest vcs1: SUCCESS (0.083s) [460.242814] Starting dynamic subtest: vecs0 [460.311849] Dynamic subtest vecs0: SUCCESS (0.080s) [460.322713] Starting dynamic subtest: ccs0 [460.405395] Dynamic subtest ccs0: SUCCESS (0.092s) [460.408033] Subtest implicit-boths: SUCCESS (0.434s) [462.047060] [093/129] (557s left) gem_exec_schedule (u-submit-golden-slice) [462.117144] Starting subtest: u-submit-golden-slice [462.119078] Starting dynamic subtest: rcs0 [462.143082] Dynamic subtest rcs0: SUCCESS (0.033s) [462.151567] Starting dynamic subtest: bcs0 [462.176147] Dynamic subtest bcs0: SUCCESS (0.032s) [462.185133] Starting dynamic subtest: vcs0 [462.206119] Dynamic subtest vcs0: SUCCESS (0.029s) [462.214593] Starting dynamic subtest: vcs1 [462.236618] Dynamic subtest vcs1: SUCCESS (0.030s) [462.245400] Starting dynamic subtest: vecs0 [462.263433] Dynamic subtest vecs0: SUCCESS (0.026s) [462.271802] Starting dynamic subtest: ccs0 [462.294488] Dynamic subtest ccs0: SUCCESS (0.031s) [462.296832] Subtest u-submit-golden-slice: SUCCESS (0.185s) [463.998908] [094/129] (555s left) kms_pm_rpm (dpms-mode-unset-non-lpsp) [466.044983] Starting subtest: dpms-mode-unset-non-lpsp [467.206981] Subtest dpms-mode-unset-non-lpsp: SKIP (1.158s) [467.564046] [095/129] (551s left) gen3_render_tiledy_blits [467.689436] [096/129] (551s left) gem_exec_reloc (basic-wc-cpu-noreloc) [467.761994] Subtest basic-wc-cpu-noreloc: SKIP (0.000s) [467.876352] [097/129] (551s left) kms_psr (fbc-pr-primary-mmap-gtt) [467.973222] Starting subtest: fbc-pr-primary-mmap-gtt [467.977633] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s) [468.038152] [098/129] (551s left) gem_exec_balancer (sequential) [468.103077] Starting subtest: sequential [471.022592] Subtest sequential: SUCCESS (2.919s) [471.357900] [099/129] (548s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-blt) [471.469210] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-blt [474.613318] Subtest fbc-1p-offscren-pri-shrfb-draw-blt: SUCCESS (3.147s) [474.764302] [100/129] (544s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt) [474.871188] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt [474.874922] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [474.981479] [101/129] (544s left) kms_plane_lowres (tiling-4) [475.076789] Starting subtest: tiling-4 [475.078678] Starting dynamic subtest: pipe-A-eDP-1 [475.082434] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [475.373647] Starting dynamic subtest: pipe-B-eDP-1 [475.378572] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [475.389044] Starting dynamic subtest: pipe-C-eDP-1 [475.394061] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [475.401955] Starting dynamic subtest: pipe-D-eDP-1 [475.406599] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [475.411211] Subtest tiling-4: SKIP (0.290s) [476.204702] [102/129] (543s left) gem_mmap_gtt (big-copy) [476.271931] Subtest big-copy: SKIP (0.000s) [476.325232] [103/129] (543s left) gem_mmap_offset (mmap-boundaries) [476.393838] Starting subtest: mmap-boundaries [476.396060] Starting dynamic subtest: smem0 [477.481083] Dynamic subtest smem0: SUCCESS (1.086s) [477.484256] Subtest mmap-boundaries: SUCCESS (1.087s) [477.657007] [104/129] (541s left) perf_pmu (render-node-busy-idle) [477.744389] Starting subtest: render-node-busy-idle [477.746854] Starting dynamic subtest: rcs0 [478.474663] Dynamic subtest rcs0: SUCCESS (0.733s) [478.485391] Starting dynamic subtest: bcs0 [479.150458] Dynamic subtest bcs0: SUCCESS (0.674s) [479.161954] Starting dynamic subtest: vcs0 [480.937511] Dynamic subtest vcs0: SUCCESS (1.784s) [480.949510] Starting dynamic subtest: vcs1 [482.922514] Dynamic subtest vcs1: SUCCESS (1.983s) [482.934329] Starting dynamic subtest: vecs0 [484.906517] Dynamic subtest vecs0: SUCCESS (1.981s) [484.918053] Starting dynamic subtest: ccs0 [485.574866] Dynamic subtest ccs0: SUCCESS (0.667s) [485.578032] Subtest render-node-busy-idle: SUCCESS (7.835s) [485.645262] [105/129] (534s left) kms_flip (dpms-vs-vblank-race-interruptible) [485.750658] Starting subtest: dpms-vs-vblank-race-interruptible [485.752942] Starting dynamic subtest: A-eDP1 [490.669832] Dynamic subtest A-eDP1: SUCCESS (4.914s) [490.678706] Starting dynamic subtest: B-eDP1 [495.263285] Dynamic subtest B-eDP1: SUCCESS (4.593s) [495.273917] Starting dynamic subtest: C-eDP1 [499.856079] Dynamic subtest C-eDP1: SUCCESS (4.591s) [499.864380] Starting dynamic subtest: D-eDP1 [504.445032] Dynamic subtest D-eDP1: SUCCESS (4.588s) [504.447473] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (18.695s) [505.317566] [106/129] (514s left) kms_big_fb (x-tiled-addfb) [505.424168] Starting subtest: x-tiled-addfb [505.426042] Subtest x-tiled-addfb: SUCCESS (0.000s) [505.516754] [107/129] (514s left) kms_flip (2x-flip-vs-expired-vblank-interruptible) [505.618884] Starting subtest: 2x-flip-vs-expired-vblank-interruptible [505.632197] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s) [505.704068] [108/129] (514s left) kms_prop_blob (invalid-get-prop) [505.765581] Starting subtest: invalid-get-prop [505.767874] Subtest invalid-get-prop: SUCCESS (0.000s) [505.865049] [109/129] (513s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt) [505.974858] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt [505.978839] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s) [506.055264] [110/129] (513s left) kms_psr (fbc-psr-primary-mmap-cpu) [506.149110] Starting subtest: fbc-psr-primary-mmap-cpu [506.151257] Starting dynamic subtest: eDP-1 [506.155133] Dynamic subtest eDP-1: SKIP (0.000s) [506.158997] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s) [506.215719] [111/129] (513s left) kms_chamelium_color (ctm-limited-range) [506.330479] Subtest ctm-limited-range: SKIP (0.000s) [506.371897] [112/129] (513s left) i915_selftest (live) [509.909418] Starting subtest: live [509.912237] Starting dynamic subtest: sanitycheck [512.728399] Dynamic subtest sanitycheck: SUCCESS (2.818s) [512.737371] Starting dynamic subtest: uncore [517.182218] Dynamic subtest uncore: SUCCESS (4.452s) [517.191829] Starting dynamic subtest: workarounds [520.375222] Dynamic subtest workarounds: SUCCESS (3.191s) [520.384519] Starting dynamic subtest: gt_engines [524.957926] Dynamic subtest gt_engines: SUCCESS (4.581s) [524.967080] Starting dynamic subtest: gt_timelines [539.520557] Dynamic subtest gt_timelines: SUCCESS (14.559s) [539.529889] Starting dynamic subtest: gt_contexts [543.957164] Dynamic subtest gt_contexts: SUCCESS (4.435s) [543.971705] Starting dynamic subtest: gt_lrc [550.101528] Dynamic subtest gt_lrc: SUCCESS (6.142s) [550.111318] Starting dynamic subtest: gt_mocs [553.093318] Dynamic subtest gt_mocs: SUCCESS (2.990s) [553.102348] Starting dynamic subtest: gt_pm [559.958460] Dynamic subtest gt_pm: SUCCESS (6.862s) [559.967846] Starting dynamic subtest: gt_heartbeat [562.715253] Dynamic subtest gt_heartbeat: SUCCESS (2.755s) [562.724389] Starting dynamic subtest: gt_tlb [572.012501] Dynamic subtest gt_tlb: SUCCESS (9.295s) [572.021746] Starting dynamic subtest: requests [583.402042] Dynamic subtest requests: SUCCESS (11.387s) [583.410352] Starting dynamic subtest: migrate [591.407857] Dynamic subtest migrate: SUCCESS (8.004s) [591.417267] Starting dynamic subtest: active [595.804447] Dynamic subtest active: SUCCESS (4.394s) [595.814035] Starting dynamic subtest: objects [599.502130] Dynamic subtest objects: SUCCESS (3.696s) [599.517971] Starting dynamic subtest: mman [604.399754] Dynamic subtest mman: SUCCESS (4.895s) [604.408690] Starting dynamic subtest: dmabuf [607.129111] Dynamic subtest dmabuf: SUCCESS (2.727s) [607.138667] Starting dynamic subtest: vma [611.523397] Dynamic subtest vma: SUCCESS (4.392s) [611.533044] Starting dynamic subtest: coherency [616.535928] Dynamic subtest coherency: SUCCESS (5.011s) [616.544420] Starting dynamic subtest: gtt [626.353461] Dynamic subtest gtt: SUCCESS (9.814s) [626.362653] Starting dynamic subtest: gem [633.911378] Dynamic subtest gem: SUCCESS (7.555s) [633.920800] Starting dynamic subtest: evict [637.782231] Dynamic subtest evict: SUCCESS (3.869s) [637.791681] Starting dynamic subtest: hugepages [648.717077] Dynamic subtest hugepages: SUCCESS (10.932s) [648.726250] Starting dynamic subtest: gem_contexts [676.436995] Dynamic subtest gem_contexts: SUCCESS (27.718s) [676.445966] Starting dynamic subtest: client [680.883306] Dynamic subtest client: SUCCESS (4.445s) [680.891738] Starting dynamic subtest: gem_migrate [685.275574] Dynamic subtest gem_migrate: SUCCESS (4.390s) [685.284858] Starting dynamic subtest: reset [688.091426] Dynamic subtest reset: SUCCESS (2.813s) [688.100618] Starting dynamic subtest: memory_region [692.498651] Dynamic subtest memory_region: SUCCESS (4.405s) [692.508042] Starting dynamic subtest: hangcheck [705.595401] Dynamic subtest hangcheck: SUCCESS (13.095s) [705.604518] Starting dynamic subtest: execlists [709.996914] Dynamic subtest execlists: SUCCESS (4.400s) [710.006263] Starting dynamic subtest: ring_submission [714.376797] Dynamic subtest ring_submission: SUCCESS (4.378s) [714.386201] Starting dynamic subtest: perf [718.736308] Dynamic subtest perf: SUCCESS (4.358s) [718.745503] Starting dynamic subtest: slpc [732.760158] Dynamic subtest slpc: SUCCESS (14.022s) [732.769974] Starting dynamic subtest: guc [736.570288] Dynamic subtest guc: SUCCESS (3.808s) [736.583854] Starting dynamic subtest: guc_multi_lrc [740.952982] Dynamic subtest guc_multi_lrc: SUCCESS (4.381s) [740.962591] Starting dynamic subtest: guc_hang [745.906038] Dynamic subtest guc_hang: SUCCESS (4.951s) [745.915452] Starting dynamic subtest: late_gt_pm [748.720066] Dynamic subtest late_gt_pm: SUCCESS (2.812s) [748.722948] Subtest live: SUCCESS (238.798s) [748.746704] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [748.747435] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true [748.750575] [113/129] (271s left) prime_self_import (basic-with_fd_dup) [748.797976] Starting subtest: basic-with_fd_dup [750.127227] Subtest basic-with_fd_dup: SUCCESS (1.331s) [750.198907] [FACT prime_self_import (basic-with_fd_dup)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [750.200823] [FACT prime_self_import (basic-with_fd_dup)] new: kernel.kmod_is_loaded.i915: true [750.205058] [114/129] (269s left) gem_mmap (short-mmap) [750.271338] Subtest short-mmap: SKIP (0.000s) [750.329377] [115/129] (269s left) kms_cursor_crc (cursor-offscreen-512x512) [750.565792] Starting subtest: cursor-offscreen-512x512 [750.570099] Subtest cursor-offscreen-512x512: SKIP (0.001s) [750.705352] [116/129] (269s left) gem_mmap_wc (write-cpu-read-wc-unflushed) [750.774985] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s) [750.833939] [117/129] (269s left) kms_sequence (get-forked) [750.944230] Starting subtest: get-forked [750.946610] Starting dynamic subtest: pipe-A-eDP-1 [752.874642] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.927s) [752.883066] Starting dynamic subtest: pipe-B-eDP-1 [755.418815] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.545s) [755.427252] Starting dynamic subtest: pipe-C-eDP-1 [757.945575] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.525s) [757.953354] Starting dynamic subtest: pipe-D-eDP-1 [760.471899] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.525s) [760.474613] Subtest get-forked: SUCCESS (9.528s) [761.281983] [118/129] (258s left) kms_chamelium_hpd (dp-hpd-after-suspend) [761.666598] Subtest dp-hpd-after-suspend: SKIP (0.000s) [762.479548] [119/129] (257s left) gem_render_copy (yf-tiled-to-vebox-linear) [762.545207] Starting subtest: yf-tiled-to-vebox-linear [762.549997] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s) [762.723173] [120/129] (257s left) gem_pxp (protected-raw-src-copy-not-readible) [762.862772] Starting subtest: protected-raw-src-copy-not-readible [762.920218] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s) [763.053620] [121/129] (257s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area) [768.073960] Starting subtest: pr-overlay-primary-update-sf-dmg-area [768.078626] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s) [768.896785] [122/129] (251s left) kms_psr (fbc-psr2-primary-mmap-cpu) [768.997839] Starting subtest: fbc-psr2-primary-mmap-cpu [769.000097] Starting dynamic subtest: eDP-1 [769.004318] Dynamic subtest eDP-1: SKIP (0.000s) [769.008209] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.002s) [769.065818] [123/129] (251s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling) [769.158348] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling [769.160647] Starting dynamic subtest: pipe-A-default-mode [771.953948] Dynamic subtest pipe-A-default-mode: SUCCESS (2.793s) [771.956555] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.795s) [772.783683] [124/129] (247s left) gem_mmap_gtt (basic-write-cpu-read-gtt) [772.841640] Subtest basic-write-cpu-read-gtt: SKIP (0.000s) [772.899053] [125/129] (247s left) syncobj_timeline (invalid-signal-one-illegal-handle) [772.955202] Starting subtest: invalid-signal-one-illegal-handle [772.957117] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s) [773.059223] [126/129] (247s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal [773.122915] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled [773.230332] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.105s) [773.298742] [127/129] (247s left) kms_atomic (plane-immutable-zpos) [773.395141] Starting subtest: plane-immutable-zpos [773.397613] Starting dynamic subtest: pipe-A-eDP-1 [774.074220] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.675s) [774.076504] Subtest plane-immutable-zpos: SUCCESS (0.677s) [774.915660] [128/129] (245s left) api_intel_allocator (reopen) [774.968553] Starting subtest: reopen [774.970818] Subtest reopen: SUCCESS (0.005s) [775.108100] [129/129] (245s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted) [775.153917] Starting subtest: etime-single-wait-for-submit-unsubmitted [775.262139] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.107s) [775.328061] Closing watchdogs results: parsing output: 0/ for test: kms_flip results: parsing output: 1/ for test: kms_plane results: parsing output: 2/ for test: kms_hdr results: parsing output: 3/ for test: kms_rotation_crc results: parsing output: 4/ for test: perf results: parsing output: 5/ for test: kms_cursor_edge_walk results: parsing output: 6/ for test: gem_mmap_gtt results: parsing output: 7/ for test: drm_fdinfo results: parsing output: 8/ for test: kms_big_fb results: parsing output: 9/ for test: kms_flip results: parsing output: 10/ for test: kms_cursor_legacy results: parsing output: 11/ for test: syncobj_timeline results: parsing output: 12/ for test: kms_ccs results: parsing output: 13/ for test: syncobj_wait results: parsing output: 14/ for test: gem_ctx_param results: parsing output: 15/ for test: kms_rotation_crc results: parsing output: 16/ for test: kms_big_fb results: parsing output: 17/ for test: kms_frontbuffer_tracking results: parsing output: 18/ for test: kms_psr results: parsing output: 19/ for test: i915_hangman results: parsing output: 20/ for test: fbdev results: parsing output: 21/ for test: kms_frontbuffer_tracking results: parsing output: 22/ for test: gem_busy results: parsing output: 23/ for test: kms_frontbuffer_tracking results: parsing output: 24/ for test: kms_frontbuffer_tracking results: parsing output: 25/ for test: device_reset results: parsing output: 26/ for test: prime_mmap results: parsing output: 27/ for test: kms_frontbuffer_tracking results: parsing output: 28/ for test: gem_render_copy results: parsing output: 29/ for test: kms_ccs results: parsing output: 30/ for test: kms_plane_scaling results: parsing output: 31/ for test: i915_pm_rps results: parsing output: 32/ for test: kms_universal_plane results: parsing output: 33/ for test: gem_exec_params results: parsing output: 34/ for test: kms_frontbuffer_tracking results: parsing output: 35/ for test: perf results: parsing output: 36/ for test: kms_ccs results: parsing output: 37/ for test: gem_mmap_gtt results: parsing output: 38/ for test: gem_exec_reloc results: parsing output: 39/ for test: kms_cursor_crc results: parsing output: 40/ for test: kms_dp_link_training results: parsing output: 41/ for test: gem_lmem_swapping results: parsing output: 42/ for test: kms_setmode results: parsing output: 43/ for test: kms_cursor_crc results: parsing output: 44/ for test: gem_reg_read results: parsing output: 45/ for test: gem_exec_whisper results: parsing output: 46/ for test: syncobj_timeline results: parsing output: 47/ for test: gem_eio results: parsing output: 48/ for test: gem_create results: parsing output: 49/ for test: kms_big_fb results: parsing output: 50/ for test: vgem_basic results: parsing output: 51/ for test: gem_gtt_cpu_tlb results: parsing output: 52/ for test: kms_frontbuffer_tracking results: parsing output: 53/ for test: kms_frontbuffer_tracking results: parsing output: 54/ for test: kms_frontbuffer_tracking results: parsing output: 55/ for test: kms_panel_fitting results: parsing output: 56/ for test: kms_invalid_mode results: parsing output: 57/ for test: gem_exec_balancer results: parsing output: 58/ for test: syncobj_basic results: parsing output: 59/ for test: kms_psr results: parsing output: 60/ for test: kms_properties results: parsing output: 61/ for test: gem_ctx_shared results: parsing output: 62/ for test: i915_hangman results: parsing output: 63/ for test: vgem_basic results: parsing output: 64/ for test: kms_big_fb results: parsing output: 65/ for test: kms_pm_backlight results: parsing output: 66/ for test: gem_ctx_param results: parsing output: 67/ for test: kms_chamelium_hpd results: parsing output: 68/ for test: prime_busy results: parsing output: 69/ for test: kms_flip results: parsing output: 70/ for test: kms_frontbuffer_tracking results: parsing output: 71/ for test: kms_frontbuffer_tracking results: parsing output: 72/ for test: kms_ccs results: parsing output: 73/ for test: gem_exec_balancer results: parsing output: 74/ for test: kms_big_fb results: parsing output: 75/ for test: perf_pmu results: parsing output: 76/ for test: kms_lease results: parsing output: 77/ for test: kms_ccs results: parsing output: 78/ for test: kms_frontbuffer_tracking results: parsing output: 79/ for test: kms_frontbuffer_tracking results: parsing output: 80/ for test: kms_flip results: parsing output: 81/ for test: gem_spin_batch results: parsing output: 82/ for test: gem_exec_schedule results: parsing output: 83/ for test: kms_big_fb results: parsing output: 84/ for test: kms_frontbuffer_tracking results: parsing output: 85/ for test: gem_create results: parsing output: 86/ for test: gem_exec_fence results: parsing output: 87/ for test: kms_vblank results: parsing output: 88/ for test: kms_cursor_legacy results: parsing output: 89/ for test: gem_wait results: parsing output: 90/ for test: kms_psr2_sf results: parsing output: 91/ for test: gem_exec_schedule results: parsing output: 92/ for test: gem_exec_schedule results: parsing output: 93/ for test: kms_pm_rpm results: parsing output: 94/ for test: gen3_render_tiledy_blits results: parsing output: 95/ for test: gem_exec_reloc results: parsing output: 96/ for test: kms_psr results: parsing output: 97/ for test: gem_exec_balancer results: parsing output: 98/ for test: kms_frontbuffer_tracking results: parsing output: 99/ for test: kms_frontbuffer_tracking results: parsing output: 100/ for test: kms_plane_lowres results: parsing output: 101/ for test: gem_mmap_gtt results: parsing output: 102/ for test: gem_mmap_offset results: parsing output: 103/ for test: perf_pmu results: parsing output: 104/ for test: kms_flip results: parsing output: 105/ for test: kms_big_fb results: parsing output: 106/ for test: kms_flip results: parsing output: 107/ for test: kms_prop_blob results: parsing output: 108/ for test: kms_frontbuffer_tracking results: parsing output: 109/ for test: kms_psr results: parsing output: 110/ for test: kms_chamelium_color results: parsing output: 111/ for test: i915_selftest results: parsing output: 112/ for test: prime_self_import results: parsing output: 113/ for test: gem_mmap results: parsing output: 114/ for test: kms_cursor_crc results: parsing output: 115/ for test: gem_mmap_wc results: parsing output: 116/ for test: kms_sequence results: parsing output: 117/ for test: kms_chamelium_hpd results: parsing output: 118/ for test: gem_render_copy results: parsing output: 119/ for test: gem_pxp results: parsing output: 120/ for test: kms_psr2_sf results: parsing output: 121/ for test: kms_psr results: parsing output: 122/ for test: kms_flip_scaled_crc results: parsing output: 123/ for test: gem_mmap_gtt results: parsing output: 124/ for test: syncobj_timeline results: parsing output: 125/ for test: syncobj_timeline results: parsing output: 126/ for test: kms_atomic results: parsing output: 127/ for test: api_intel_allocator results: parsing output: 128/ for test: syncobj_timeline Done.