[56.546665] Initializing watchdogs [56.546715] /dev/watchdog0 [56.561167] [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] [56.565543] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [56.567991] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [56.571344] [001/134] (960s left) syncobj_timeline (invalid-wait-bad-flags) [56.637571] Starting subtest: invalid-wait-bad-flags [56.640269] Subtest invalid-wait-bad-flags: SUCCESS (0.000s) [56.752750] [002/134] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render) [56.856633] Starting subtest: fbcpsr-rgb101010-draw-render [63.670743] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (6.817s) [63.827324] [003/134] (953s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode) [64.213427] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s) [65.013910] [004/134] (952s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt) [65.113992] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt [65.118281] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [65.222555] [005/134] (951s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc) [65.307717] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc [65.310058] Starting dynamic subtest: pipe-A-eDP-1 [66.218134] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.904s) [66.226432] Starting dynamic subtest: pipe-B-eDP-1 [67.821821] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.600s) [67.830749] Starting dynamic subtest: pipe-C-eDP-1 [69.403144] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.581s) [69.410115] Starting dynamic subtest: pipe-D-eDP-1 [70.921960] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.519s) [70.924097] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.607s) [72.176704] [006/134] (945s left) kms_big_fb (yf-tiled-16bpp-rotate-270) [72.290812] Starting subtest: yf-tiled-16bpp-rotate-270 [72.295141] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s) [72.435609] [007/134] (944s left) kms_async_flips (alternate-sync-async-flip-atomic) [72.520724] Starting subtest: alternate-sync-async-flip-atomic [72.893812] Starting dynamic subtest: pipe-A-eDP-1 [74.250483] Dynamic subtest pipe-A-eDP-1: FAIL (0.772s) [74.259453] Starting dynamic subtest: pipe-B-eDP-1 [75.161349] Dynamic subtest pipe-B-eDP-1: FAIL (0.742s) [75.170586] Starting dynamic subtest: pipe-C-eDP-1 [77.909429] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.778s) [78.272695] Starting dynamic subtest: pipe-D-eDP-1 [81.034450] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.769s) [81.036499] Subtest alternate-sync-async-flip-atomic: FAIL (8.517s) [82.210039] [008/134] (935s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area) [87.228066] Starting subtest: pr-primary-plane-update-sf-dmg-area [87.232820] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.002s) [88.066669] [009/134] (929s left) gem_exec_reloc (basic-write-cpu) [88.138201] Subtest basic-write-cpu: SKIP (0.000s) [88.203220] [010/134] (929s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render) [88.308200] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render [95.633223] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.335s) [95.771289] [011/134] (921s left) gem_exec_flush (basic-wb-rw-before-default) [95.842249] Starting subtest: basic-wb-rw-before-default [101.321658] Subtest basic-wb-rw-before-default: SUCCESS (5.450s) [101.488661] [012/134] (916s left) kms_colorop (plane-xr30-xr30-pq_125_eotf-pq_125_inv_eotf) [101.629423] Subtest plane-XR30-XR30-pq_125_eotf-pq_125_inv_eotf: SKIP (0.000s) [101.712491] [013/134] (915s left) syncobj_timeline (invalid-signal-bad-pad) [101.762203] Starting subtest: invalid-signal-bad-pad [101.764584] Subtest invalid-signal-bad-pad: SUCCESS (0.000s) [101.858851] [014/134] (915s left) kms_getfb (getfb-addfb-different-handles) [101.955224] Starting subtest: getfb-addfb-different-handles [101.957508] Subtest getfb-addfb-different-handles: SUCCESS (0.000s) [102.071639] [015/134] (915s left) perf_pmu (module-unload) [102.182595] Starting subtest: module-unload [110.641852] Subtest module-unload: FAIL (8.351s) [110.670830] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true [110.673784] [016/134] (906s left) kms_atomic_transition (modeset-transition) [112.227135] Starting subtest: modeset-transition [112.229102] Starting dynamic subtest: 1x-outputs [123.178543] Dynamic subtest 1x-outputs: SUCCESS (10.954s) [123.181192] Subtest modeset-transition: SUCCESS (10.955s) [124.063892] [FACT kms_atomic_transition (modeset-transition)] new: kernel.kmod_is_loaded.i915: true [124.067524] [017/134] (893s left) kms_cursor_crc (cursor-random-max-size) [124.361078] Starting subtest: cursor-random-max-size [124.367847] Subtest cursor-random-max-size: SKIP (0.000s) [124.527123] [018/134] (893s left) gem_pxp (hw-rejects-pxp-context) [124.664113] Starting subtest: hw-rejects-pxp-context [124.668347] Subtest hw-rejects-pxp-context: SKIP (0.000s) [124.766818] [019/134] (893s left) kms_flip (2x-flip-vs-expired-vblank-interruptible) [124.859188] Starting subtest: 2x-flip-vs-expired-vblank-interruptible [124.871070] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s) [124.964097] [020/134] (892s left) gem_exec_params (no-blt) [125.024903] Starting subtest: no-blt [125.026835] Subtest no-blt: SUCCESS (0.002s) [125.171959] [021/134] (892s left) tools_test (tools_test) [125.213641] Starting subtest: tools_test [125.264521] Subtest tools_test: SUCCESS (0.062s) [125.346235] [022/134] (892s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs) [125.441433] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs [125.443352] Starting dynamic subtest: pipe-A-eDP-1 [125.462499] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [125.471192] Starting dynamic subtest: pipe-B-eDP-1 [125.489677] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [125.496533] Starting dynamic subtest: pipe-C-eDP-1 [125.514177] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [125.521227] Starting dynamic subtest: pipe-D-eDP-1 [125.539961] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [125.543771] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s) [127.643221] [023/134] (890s left) gem_lmem_swapping (heavy-random) [127.685199] Subtest heavy-random: SKIP (0.000s) [127.725849] [024/134] (890s left) gem_softpin (evict-prime) [127.779150] Starting subtest: evict-prime [127.781615] Starting dynamic subtest: rcs0 [128.954430] Dynamic subtest rcs0: SUCCESS (1.174s) [128.969782] Starting dynamic subtest: bcs0 [130.085205] Dynamic subtest bcs0: SUCCESS (1.131s) [130.094557] Starting dynamic subtest: vcs0 [131.902365] Dynamic subtest vcs0: SUCCESS (1.815s) [131.914319] Starting dynamic subtest: vcs1 [133.936862] Dynamic subtest vcs1: SUCCESS (2.032s) [133.949432] Starting dynamic subtest: vecs0 [135.926065] Dynamic subtest vecs0: SUCCESS (1.987s) [135.937258] Starting dynamic subtest: ccs0 [137.073049] Dynamic subtest ccs0: SUCCESS (1.147s) [137.084374] Starting dynamic subtest: all [138.858707] Dynamic subtest all: SUCCESS (1.783s) [138.861574] Subtest evict-prime: SUCCESS (11.080s) [138.959591] [025/134] (879s left) prime_mmap (test_userptr) [139.020837] Starting subtest: test_userptr [139.023306] Starting dynamic subtest: test_userptr-smem [139.025306] Dynamic subtest test_userptr-smem: SUCCESS (0.000s) [139.027286] Subtest test_userptr: SUCCESS (0.001s) [139.142637] [026/134] (878s left) gem_exec_whisper (basic-fds) [139.203498] Starting subtest: basic-fds [146.868349] Subtest basic-fds: SUCCESS (7.663s) [147.065167] [027/134] (870s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy) [147.189491] Starting subtest: 2x-flip-vs-cursor-legacy [147.194134] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s) [147.344432] [028/134] (870s left) gem_exec_schedule (preempt-hang) [147.522911] Starting subtest: preempt-hang [147.525958] Starting dynamic subtest: rcs0 [147.767170] Dynamic subtest rcs0: SUCCESS (0.244s) [147.777838] Starting dynamic subtest: bcs0 [147.999854] Dynamic subtest bcs0: SUCCESS (0.231s) [148.008024] Starting dynamic subtest: vcs0 [148.233483] Dynamic subtest vcs0: SUCCESS (0.233s) [148.244320] Starting dynamic subtest: vcs1 [148.460565] Dynamic subtest vcs1: SUCCESS (0.225s) [148.469357] Starting dynamic subtest: vecs0 [148.684396] Dynamic subtest vecs0: SUCCESS (0.224s) [148.693785] Starting dynamic subtest: ccs0 [148.918227] Dynamic subtest ccs0: SUCCESS (0.233s) [148.920418] Subtest preempt-hang: SUCCESS (1.394s) [150.037542] [029/134] (868s left) gem_ctx_param (vm) [150.098124] Starting subtest: vm [150.162404] Subtest vm: SUCCESS (0.064s) [150.297311] [030/134] (867s left) kms_psr (fbc-psr-sprite-blt) [150.386906] Starting subtest: fbc-psr-sprite-blt [150.389249] Starting dynamic subtest: eDP-1 [150.393617] Dynamic subtest eDP-1: SKIP (0.000s) [150.397964] Subtest fbc-psr-sprite-blt: SKIP (0.001s) [150.487388] [031/134] (867s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen) [150.582940] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen [157.281643] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.696s) [157.446396] [032/134] (860s left) gem_exec_capture (capture-invisible) [157.511076] Starting subtest: capture-invisible [157.513044] Starting dynamic subtest: smem0 [157.517207] Dynamic subtest smem0: SKIP (0.000s) [157.521143] Subtest capture-invisible: SKIP (0.000s) [157.643991] [033/134] (860s left) vgem_slow (nohang) [157.745744] Starting subtest: nohang [168.101491] Subtest nohang: SUCCESS (10.299s) [168.140233] [FACT vgem_slow (nohang)] new: kernel.kmod_is_loaded.vgem: true [168.143394] [034/134] (850s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip) [168.254338] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip [168.258634] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [168.370119] [035/134] (850s left) kms_chamelium_color (ctm-negative) [168.461222] Subtest ctm-negative: SKIP (0.000s) [168.504504] [036/134] (849s left) gem_exec_reloc (basic-write-read) [168.570199] Subtest basic-write-read: SKIP (0.000s) [168.644709] [037/134] (849s left) gem_eio (context-create) [168.881012] Starting subtest: context-create [169.224780] Subtest context-create: SUCCESS (0.344s) [169.370852] [038/134] (849s left) perf (gen12-invalid-class-instance) [169.506182] Starting subtest: gen12-invalid-class-instance [169.514200] Subtest gen12-invalid-class-instance: SUCCESS (0.008s) [169.557065] [039/134] (848s left) dmabuf_sync_file (export-multiwait) [169.602402] Starting subtest: export-multiwait [169.604371] Subtest export-multiwait: SUCCESS (0.001s) [169.689605] [040/134] (848s left) kms_psr (fbc-psr-no-drrs) [169.784714] Starting subtest: fbc-psr-no-drrs [169.786669] Starting dynamic subtest: eDP-1 [169.790097] Dynamic subtest eDP-1: SKIP (0.000s) [169.793517] Subtest fbc-psr-no-drrs: SKIP (0.001s) [169.883906] [041/134] (848s left) kms_rotation_crc (multiplane-rotation-cropping-bottom) [169.981139] Starting subtest: multiplane-rotation-cropping-bottom [177.334401] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.350s) [177.475784] [042/134] (841s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render) [177.571031] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render [177.574379] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [177.681106] [043/134] (841s left) gem_exec_balancer (bonded-dual) [177.733576] Starting subtest: bonded-dual [177.737123] Subtest bonded-dual: SKIP (0.000s) [178.081881] [044/134] (840s left) kms_hdr (invalid-metadata-sizes) [178.173205] Starting subtest: invalid-metadata-sizes [178.179891] Subtest invalid-metadata-sizes: SKIP (0.000s) [178.260478] [045/134] (840s left) gem_userptr_blits (process-exit) [178.327111] Starting subtest: process-exit [178.334233] Subtest process-exit: SUCCESS (0.008s) [178.481314] [046/134] (840s left) kms_cursor_crc (cursor-offscreen-256x85) [178.743075] Starting subtest: cursor-offscreen-256x85 [178.792499] Subtest cursor-offscreen-256x85: SKIP (0.044s) [178.999305] [047/134] (839s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc) [179.099476] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc [179.101312] Starting dynamic subtest: pipe-A-eDP-1 [179.118645] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [179.128795] Starting dynamic subtest: pipe-B-eDP-1 [179.145059] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [179.154321] Starting dynamic subtest: pipe-C-eDP-1 [179.171354] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [179.183947] Starting dynamic subtest: pipe-D-eDP-1 [179.201366] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [179.203296] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s) [181.363569] [048/134] (837s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c) [181.428072] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s) [181.492364] [049/134] (837s left) i915_drm_fdinfo (memory-info-shared) [181.528211] Starting subtest: memory-info-shared [181.530592] Starting dynamic subtest: smem0 [181.596407] Dynamic subtest smem0: SUCCESS (0.071s) [181.599046] Subtest memory-info-shared: SUCCESS (0.071s) [181.693318] [050/134] (837s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation) [181.779124] Starting subtest: plane-scaler-unity-scaling-with-rotation [181.781331] Starting dynamic subtest: pipe-A [182.004946] Dynamic subtest pipe-A: SUCCESS (0.228s) [182.016213] Starting dynamic subtest: pipe-B [183.330245] Dynamic subtest pipe-B: SUCCESS (1.324s) [183.339660] Starting dynamic subtest: pipe-C [184.662308] Dynamic subtest pipe-C: SUCCESS (1.331s) [184.671478] Starting dynamic subtest: pipe-D [185.993088] Dynamic subtest pipe-D: SUCCESS (1.330s) [185.995216] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.215s) [187.204024] [051/134] (831s left) kms_big_fb (4-tiled-64bpp-rotate-0) [187.304505] Starting subtest: 4-tiled-64bpp-rotate-0 [188.927741] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.623s) [189.119864] [052/134] (829s left) gem_mmap_gtt (big-bo) [189.181719] Subtest big-bo: SKIP (0.000s) [189.258071] [053/134] (829s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all) [189.315731] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all [189.375276] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.006s) [189.469945] [054/134] (829s left) kms_addfb_basic (addfb25-modifier-no-flag) [189.568979] Starting subtest: addfb25-modifier-no-flag [189.570661] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [189.675916] [055/134] (829s left) kms_chamelium_edid (dp-edid-resolution-list) [190.065730] Subtest dp-edid-resolution-list: SKIP (0.000s) [190.888602] [056/134] (828s left) gem_madvise (dontneed-before-mmap) [190.927110] Starting subtest: dontneed-before-mmap [190.948495] Subtest dontneed-before-mmap: SUCCESS (0.022s) [191.067870] [057/134] (828s left) kms_hdmi_inject (inject-audio) [191.413054] Starting subtest: inject-audio [191.690287] Subtest inject-audio: SUCCESS (0.278s) [192.297576] [058/134] (827s left) core_auth (basic-auth) [192.356616] Starting subtest: basic-auth [192.359035] Subtest basic-auth: SUCCESS (0.001s) [192.496517] [059/134] (826s left) gem_userptr_blits (map-fixed-invalidate-busy) [192.573379] Starting subtest: map-fixed-invalidate-busy [192.577762] Subtest map-fixed-invalidate-busy: SKIP (0.000s) [192.743320] [060/134] (826s left) gem_exec_reloc (basic-concurrent16) [192.808253] Subtest basic-concurrent16: SKIP (0.000s) [192.872140] [061/134] (826s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite) [192.969891] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite [192.974246] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [193.099583] [062/134] (826s left) kms_dp_link_training (non-uhbr-mst) [193.193589] Starting subtest: non-uhbr-mst [193.200876] Subtest non-uhbr-mst: SKIP (0.000s) [193.309443] [063/134] (826s left) kms_scaling_modes (scaling-mode-none) [193.391466] Starting subtest: scaling-mode-none [193.393124] Starting dynamic subtest: pipe-A-eDP-1 [193.483366] Dynamic subtest pipe-A-eDP-1: SKIP (0.091s) [193.492344] Starting dynamic subtest: pipe-B-eDP-1 [193.518775] Dynamic subtest pipe-B-eDP-1: SKIP (0.034s) [193.529956] Starting dynamic subtest: pipe-C-eDP-1 [193.550556] Dynamic subtest pipe-C-eDP-1: SKIP (0.030s) [193.559059] Starting dynamic subtest: pipe-D-eDP-1 [193.580560] Dynamic subtest pipe-D-eDP-1: SKIP (0.029s) [193.584207] Subtest scaling-mode-none: SKIP (0.190s) [193.742659] [064/134] (825s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [193.838231] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt [193.841667] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [193.970660] [065/134] (825s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf) [199.037549] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf [199.039853] Starting dynamic subtest: pipe-A-eDP-1 [199.799406] Dynamic subtest pipe-A-eDP-1: SKIP (0.759s) [199.811480] Starting dynamic subtest: pipe-B-eDP-1 [200.994546] Dynamic subtest pipe-B-eDP-1: SKIP (1.191s) [200.999173] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.956s) [202.253547] [066/134] (817s left) syncobj_timeline (single-wait-for-submit-signaled) [202.315517] Starting subtest: single-wait-for-submit-signaled [202.317670] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s) [202.453327] [067/134] (817s left) vgem_basic (dmabuf-export) [202.495691] Starting subtest: dmabuf-export [202.541775] Subtest dmabuf-export: SUCCESS (0.052s) [202.697246] [068/134] (817s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [202.808693] Starting subtest: basic-busy-flip-before-cursor-legacy [202.813281] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [202.968939] [069/134] (816s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu) [203.071646] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu [203.075732] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [203.209135] [070/134] (816s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf) [208.223218] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf [208.227671] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s) [209.070409] [071/134] (810s left) kms_rotation_crc (sprite-rotation-90) [209.161060] Starting subtest: sprite-rotation-90 [209.165200] Subtest sprite-rotation-90: SKIP (0.001s) [209.244492] [072/134] (810s left) kms_vblank (ts-continuation-modeset-rpm) [209.334916] Starting subtest: ts-continuation-modeset-rpm [209.336813] Starting dynamic subtest: pipe-A-eDP-1 [211.917849] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.583s) [211.932864] Starting dynamic subtest: pipe-D-eDP-1 [214.096755] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.174s) [214.099031] Subtest ts-continuation-modeset-rpm: SUCCESS (4.761s) [214.962334] [073/134] (805s left) gem_exec_schedule (semaphore-user) [215.041472] Starting subtest: semaphore-user [215.094845] Subtest semaphore-user: SUCCESS (0.060s) [216.980626] [074/134] (803s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc) [217.081684] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc [221.387041] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.308s) [222.564976] [075/134] (797s left) kms_flip (dpms-off-confusion) [222.671233] Starting subtest: dpms-off-confusion [222.673479] Starting dynamic subtest: A-eDP1 [231.353660] Dynamic subtest A-eDP1: SUCCESS (8.647s) [231.362142] Starting dynamic subtest: B-eDP1 [240.056973] Dynamic subtest B-eDP1: SUCCESS (8.701s) [240.067551] Starting dynamic subtest: C-eDP1 [248.756406] Dynamic subtest C-eDP1: SUCCESS (8.687s) [248.767696] Starting dynamic subtest: D-eDP1 [257.461611] Dynamic subtest D-eDP1: SUCCESS (8.702s) [257.463647] Subtest dpms-off-confusion: SUCCESS (34.746s) [258.674827] [076/134] (761s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [258.768343] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [258.772534] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [258.892200] [077/134] (761s left) kms_lease (lease-unleased-crtc) [258.984973] Starting subtest: lease-unleased-crtc [258.987149] Starting dynamic subtest: pipe-A-eDP-1 [259.278522] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s) [259.287881] Starting dynamic subtest: pipe-B-eDP-1 [260.072741] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.793s) [260.084313] Starting dynamic subtest: pipe-C-eDP-1 [260.086712] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.005s) [260.100200] Starting dynamic subtest: pipe-D-eDP-1 [260.102253] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.005s) [260.104278] Subtest lease-unleased-crtc: SUCCESS (1.101s) [260.479260] [078/134] (759s left) kms_cursor_edge_walk (128x128-right-edge) [260.586394] Starting subtest: 128x128-right-edge [260.588665] Starting dynamic subtest: pipe-A-eDP-1 [264.086952] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.499s) [264.096242] Starting dynamic subtest: pipe-D-eDP-1 [267.259231] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.171s) [267.261516] Subtest 128x128-right-edge: SUCCESS (6.674s) [268.066289] [079/134] (752s left) gen9_exec_parse (bb-start-out) [268.130345] Subtest bb-start-out: SKIP (0.000s) [268.217673] [080/134] (752s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc) [268.314600] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc [268.318822] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [268.445308] [081/134] (751s left) i915_pm_rpm (gem-pread) [270.062734] Starting subtest: gem-pread [272.029510] Subtest gem-pread: SUCCESS (1.968s) [272.141140] [082/134] (748s left) kms_plane_alpha_blend (constant-alpha-min) [272.233202] Starting subtest: constant-alpha-min [272.515880] Starting dynamic subtest: pipe-A-eDP-1 [273.261921] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.742s) [273.273065] Starting dynamic subtest: pipe-D-eDP-1 [274.702274] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.441s) [274.704639] Subtest constant-alpha-min: SUCCESS (2.471s) [275.555924] [083/134] (744s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render) [275.663246] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render [275.667106] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [275.804765] [084/134] (744s left) gem_mmap_gtt (cpuset-big-copy-odd) [275.866931] Subtest cpuset-big-copy-odd: SKIP (0.000s) [275.938213] [085/134] (744s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite) [276.024576] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite [284.553347] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.531s) [284.700821] [086/134] (735s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt) [284.808730] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt [288.948249] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.142s) [290.166325] [087/134] (730s left) kms_flip (2x-flip-vs-dpms-on-nop) [290.254390] Starting subtest: 2x-flip-vs-dpms-on-nop [290.263497] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.011s) [290.354216] [088/134] (730s left) gem_basic (bad-close) [290.406805] Starting subtest: bad-close [290.410765] Subtest bad-close: SUCCESS (0.000s) [290.515140] [089/134] (730s left) kms_psr (psr2-cursor-render) [290.613213] Starting subtest: psr2-cursor-render [290.615516] Starting dynamic subtest: eDP-1 [292.304295] Dynamic subtest eDP-1: SUCCESS (1.690s) [292.306185] Subtest psr2-cursor-render: SUCCESS (1.691s) [293.151284] [090/134] (727s left) api_intel_bb (render-ccs) [293.203526] Starting subtest: render-ccs [293.678805] Subtest render-ccs: SUCCESS (0.474s) [293.839157] [091/134] (726s left) kms_cursor_crc (cursor-offscreen-64x21) [294.038339] Starting subtest: cursor-offscreen-64x21 [294.084403] Subtest cursor-offscreen-64x21: SKIP (0.044s) [294.365563] [092/134] (726s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [294.485660] Starting subtest: basic-busy-flip-before-cursor-atomic [294.490171] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [294.648616] [093/134] (726s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted) [294.695729] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted [294.801600] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.105s) [294.893308] [094/134] (725s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move) [294.987154] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move [294.991559] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [295.117419] [095/134] (725s left) gem_flink_basic (bad-open) [295.166073] Starting subtest: bad-open [295.167945] Subtest bad-open: SUCCESS (0.000s) [295.310803] [096/134] (725s left) kms_flip (2x-blocking-wf_vblank) [295.397421] Starting subtest: 2x-blocking-wf_vblank [295.406238] Subtest 2x-blocking-wf_vblank: SKIP (0.011s) [295.541511] [097/134] (725s left) kms_big_fb (linear-8bpp-rotate-0) [295.634240] Starting subtest: linear-8bpp-rotate-0 [296.913260] Subtest linear-8bpp-rotate-0: SUCCESS (1.280s) [297.030273] [098/134] (723s left) api_intel_allocator (fork-simple-once) [297.092019] Starting subtest: fork-simple-once [297.104372] Subtest fork-simple-once: SUCCESS (0.014s) [297.260097] [099/134] (723s left) kms_big_fb (y-tiled-32bpp-rotate-180) [297.359742] Starting subtest: y-tiled-32bpp-rotate-180 [297.363379] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s) [297.480629] [100/134] (723s left) syncobj_timeline (wait-all-for-submit-delayed-submit) [297.532876] Starting subtest: wait-all-for-submit-delayed-submit [297.635718] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s) [297.722381] [101/134] (723s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers) [297.814166] Starting subtest: plane-downscale-factor-0-75-with-modifiers [297.816491] Starting dynamic subtest: pipe-A [298.164319] Dynamic subtest pipe-A: SUCCESS (0.352s) [298.174693] Starting dynamic subtest: pipe-B [299.602672] Dynamic subtest pipe-B: SUCCESS (1.438s) [299.611561] Starting dynamic subtest: pipe-C [301.014031] Dynamic subtest pipe-C: SUCCESS (1.410s) [301.028935] Starting dynamic subtest: pipe-D [302.407476] Dynamic subtest pipe-D: SUCCESS (1.392s) [302.409778] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.596s) [303.558206] [102/134] (717s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs) [303.650309] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs [303.652272] Starting dynamic subtest: pipe-A-eDP-1 [304.473057] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.814s) [304.483446] Starting dynamic subtest: pipe-B-eDP-1 [306.087290] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.613s) [306.097508] Starting dynamic subtest: pipe-C-eDP-1 [307.618416] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.530s) [307.628211] Starting dynamic subtest: pipe-D-eDP-1 [309.145996] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.527s) [309.148325] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.489s) [310.370381] [103/134] (710s left) kms_big_fb (yf-tiled-addfb) [310.471488] Starting subtest: yf-tiled-addfb [310.476150] Subtest yf-tiled-addfb: SKIP (0.000s) [310.611274] [104/134] (710s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite) [310.710628] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite [319.945749] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.242s) [320.098389] [105/134] (701s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu) [320.200471] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu [326.830465] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.631s) [327.005379] [106/134] (694s left) gem_exec_reloc (basic-wc-gtt-active) [327.076702] Subtest basic-wc-gtt-active: SKIP (0.000s) [327.148104] [107/134] (694s left) perf (global-sseu-config-invalid) [327.258343] Starting subtest: global-sseu-config-invalid [327.263089] Subtest global-sseu-config-invalid: SKIP (0.000s) [327.305961] [108/134] (694s left) kms_pm_rpm (fences-dpms) [329.126328] Starting subtest: fences-dpms [329.131360] Subtest fences-dpms: SKIP (0.000s) [329.575187] [109/134] (691s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling) [329.670577] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling [329.672869] Starting dynamic subtest: pipe-A-default-mode [330.884695] Dynamic subtest pipe-A-default-mode: SKIP (1.212s) [330.889309] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.213s) [331.056642] [110/134] (690s left) kms_pm_rpm (cursor-dpms) [333.090337] Starting subtest: cursor-dpms [333.096019] Subtest cursor-dpms: SKIP (0.000s) [333.499211] [111/134] (688s left) syncobj_wait (invalid-multi-wait-all-unsubmitted) [333.557719] Starting subtest: invalid-multi-wait-all-unsubmitted [333.560044] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s) [333.684440] [112/134] (687s left) kms_psr (fbc-pr-sprite-render) [333.782251] Starting subtest: fbc-pr-sprite-render [333.786178] Subtest fbc-pr-sprite-render: SKIP (0.000s) [333.882136] [113/134] (687s left) kms_big_fb (x-tiled-16bpp-rotate-270) [333.987653] Starting subtest: x-tiled-16bpp-rotate-270 [334.007344] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s) [334.136593] [114/134] (687s left) perf (invalid-remove-userspace-config) [334.225972] Starting subtest: invalid-remove-userspace-config [334.234094] Subtest invalid-remove-userspace-config: SUCCESS (0.011s) [334.274946] [115/134] (687s left) gem_watchdog (default-virtual) [334.347873] Starting subtest: default-virtual [340.621270] Subtest default-virtual: SUCCESS (6.282s) [340.747387] [116/134] (681s left) sw_sync (sync_merge_same) [340.781962] Starting subtest: sync_merge_same [340.784212] Subtest sync_merge_same: SUCCESS (0.000s) [340.850280] [117/134] (680s left) gem_ringfill (basic-all) [346.904778] Starting subtest: basic-all [348.058428] Subtest basic-all: SUCCESS (1.153s) [348.234849] [118/134] (673s left) gem_ccs (suspend-resume) [348.298065] Starting subtest: suspend-resume [348.302650] Subtest suspend-resume: SKIP (0.000s) [348.444855] [119/134] (673s left) gem_workarounds (basic-read) [348.511027] Starting subtest: basic-read [348.527742] Subtest basic-read: SUCCESS (0.016s) [348.644793] [120/134] (673s left) gem_softpin (noreloc-s3) [348.699826] Starting subtest: noreloc-S3 [369.223030] Subtest noreloc-S3: SUCCESS (5.407s) [369.350378] Aborting: Kernel badly tainted (0x4244, 0x200) (check dmesg for details): TAINT_WARN: WARN_ON has happened. [369.368842] [FACT gem_softpin (noreloc-s3)] new: kernel.is_tainted.taint_warn: true [369.369694] Closing watchdogs results: parsing output: 0/ for test: syncobj_timeline results: parsing output: 1/ for test: kms_frontbuffer_tracking results: parsing output: 2/ for test: kms_chamelium_hpd results: parsing output: 3/ for test: kms_frontbuffer_tracking results: parsing output: 4/ for test: kms_ccs results: parsing output: 5/ for test: kms_big_fb results: parsing output: 6/ for test: kms_async_flips results: parsing output: 7/ for test: kms_psr2_sf results: parsing output: 8/ for test: gem_exec_reloc results: parsing output: 9/ for test: kms_frontbuffer_tracking results: parsing output: 10/ for test: gem_exec_flush results: parsing output: 11/ for test: kms_colorop results: parsing output: 12/ for test: syncobj_timeline results: parsing output: 13/ for test: kms_getfb results: parsing output: 14/ for test: perf_pmu results: parsing output: 15/ for test: kms_atomic_transition results: parsing output: 16/ for test: kms_cursor_crc results: parsing output: 17/ for test: gem_pxp results: parsing output: 18/ for test: kms_flip results: parsing output: 19/ for test: gem_exec_params results: parsing output: 20/ for test: tools_test results: parsing output: 21/ for test: kms_ccs results: parsing output: 22/ for test: gem_lmem_swapping results: parsing output: 23/ for test: gem_softpin results: parsing output: 24/ for test: prime_mmap results: parsing output: 25/ for test: gem_exec_whisper results: parsing output: 26/ for test: kms_cursor_legacy results: parsing output: 27/ for test: gem_exec_schedule results: parsing output: 28/ for test: gem_ctx_param results: parsing output: 29/ for test: kms_psr results: parsing output: 30/ for test: kms_frontbuffer_tracking results: parsing output: 31/ for test: gem_exec_capture results: parsing output: 32/ for test: vgem_slow results: parsing output: 33/ for test: kms_big_fb results: parsing output: 34/ for test: kms_chamelium_color results: parsing output: 35/ for test: gem_exec_reloc results: parsing output: 36/ for test: gem_eio results: parsing output: 37/ for test: perf results: parsing output: 38/ for test: dmabuf_sync_file results: parsing output: 39/ for test: kms_psr results: parsing output: 40/ for test: kms_rotation_crc results: parsing output: 41/ for test: kms_frontbuffer_tracking results: parsing output: 42/ for test: gem_exec_balancer results: parsing output: 43/ for test: kms_hdr results: parsing output: 44/ for test: gem_userptr_blits results: parsing output: 45/ for test: kms_cursor_crc results: parsing output: 46/ for test: kms_ccs results: parsing output: 47/ for test: kms_pipe_b_c_ivb results: parsing output: 48/ for test: i915_drm_fdinfo results: parsing output: 49/ for test: kms_plane_scaling results: parsing output: 50/ for test: kms_big_fb results: parsing output: 51/ for test: gem_mmap_gtt results: parsing output: 52/ for test: sriov_basic results: parsing output: 53/ for test: kms_addfb_basic results: parsing output: 54/ for test: kms_chamelium_edid results: parsing output: 55/ for test: gem_madvise results: parsing output: 56/ for test: kms_hdmi_inject results: parsing output: 57/ for test: core_auth results: parsing output: 58/ for test: gem_userptr_blits results: parsing output: 59/ for test: gem_exec_reloc results: parsing output: 60/ for test: kms_frontbuffer_tracking results: parsing output: 61/ for test: kms_dp_link_training results: parsing output: 62/ for test: kms_scaling_modes results: parsing output: 63/ for test: kms_frontbuffer_tracking results: parsing output: 64/ for test: kms_psr2_sf results: parsing output: 65/ for test: syncobj_timeline results: parsing output: 66/ for test: vgem_basic results: parsing output: 67/ for test: kms_cursor_legacy results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: kms_psr2_sf results: parsing output: 70/ for test: kms_rotation_crc results: parsing output: 71/ for test: kms_vblank results: parsing output: 72/ for test: gem_exec_schedule results: parsing output: 73/ for test: kms_frontbuffer_tracking results: parsing output: 74/ for test: kms_flip results: parsing output: 75/ for test: kms_frontbuffer_tracking results: parsing output: 76/ for test: kms_lease results: parsing output: 77/ for test: kms_cursor_edge_walk results: parsing output: 78/ for test: gen9_exec_parse results: parsing output: 79/ for test: kms_frontbuffer_tracking results: parsing output: 80/ for test: i915_pm_rpm results: parsing output: 81/ for test: kms_plane_alpha_blend results: parsing output: 82/ for test: kms_frontbuffer_tracking results: parsing output: 83/ for test: gem_mmap_gtt results: parsing output: 84/ for test: kms_frontbuffer_tracking results: parsing output: 85/ for test: kms_frontbuffer_tracking results: parsing output: 86/ for test: kms_flip results: parsing output: 87/ for test: gem_basic results: parsing output: 88/ for test: kms_psr results: parsing output: 89/ for test: api_intel_bb results: parsing output: 90/ for test: kms_cursor_crc results: parsing output: 91/ for test: kms_cursor_legacy results: parsing output: 92/ for test: syncobj_timeline results: parsing output: 93/ for test: kms_frontbuffer_tracking results: parsing output: 94/ for test: gem_flink_basic results: parsing output: 95/ for test: kms_flip results: parsing output: 96/ for test: kms_big_fb results: parsing output: 97/ for test: api_intel_allocator results: parsing output: 98/ for test: kms_big_fb results: parsing output: 99/ for test: syncobj_timeline results: parsing output: 100/ for test: kms_plane_scaling results: parsing output: 101/ for test: kms_ccs results: parsing output: 102/ for test: kms_big_fb results: parsing output: 103/ for test: kms_frontbuffer_tracking results: parsing output: 104/ for test: kms_frontbuffer_tracking results: parsing output: 105/ for test: gem_exec_reloc results: parsing output: 106/ for test: perf results: parsing output: 107/ for test: kms_pm_rpm results: parsing output: 108/ for test: kms_flip_scaled_crc results: parsing output: 109/ for test: kms_pm_rpm results: parsing output: 110/ for test: syncobj_wait results: parsing output: 111/ for test: kms_psr results: parsing output: 112/ for test: kms_big_fb results: parsing output: 113/ for test: perf results: parsing output: 114/ for test: gem_watchdog results: parsing output: 115/ for test: sw_sync results: parsing output: 116/ for test: gem_ringfill results: parsing output: 117/ for test: gem_ccs results: parsing output: 118/ for test: gem_workarounds results: parsing output: 119/ for test: gem_softpin results: parsing output: 120/ for test: vgem_basic results: no output, setting notrun results: parsing output: 121/ for test: gem_mmap_gtt results: no output, setting notrun results: parsing output: 122/ for test: i915_drm_fdinfo results: no output, setting notrun results: parsing output: 123/ for test: kms_force_connector_basic results: no output, setting notrun results: parsing output: 124/ for test: i915_pm_rps results: no output, setting notrun results: parsing output: 125/ for test: fbdev results: no output, setting notrun results: parsing output: 126/ for test: kms_colorop results: no output, setting notrun results: parsing output: 127/ for test: gem_ctx_persistence results: no output, setting notrun results: parsing output: 128/ for test: prime_mmap results: no output, setting notrun results: parsing output: 129/ for test: gem_pxp results: no output, setting notrun results: parsing output: 130/ for test: kms_colorop results: no output, setting notrun results: parsing output: 131/ for test: gem_partial_pwrite_pread results: no output, setting notrun results: parsing output: 132/ for test: kms_joiner results: no output, setting notrun results: parsing output: 133/ for test: gem_exec_fence results: no output, setting notrun Done.