[60.107253] Initializing watchdogs [60.107312] /dev/watchdog0 [60.119520] [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] [60.123251] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [60.125449] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [60.128674] [001/130] (960s left) kms_chamelium_color (ctm-max) [60.293630] Subtest ctm-max: SKIP (0.000s) [60.331654] [002/130] (960s left) gem_ctx_persistence (userptr) [60.405896] Starting subtest: userptr [60.636749] Subtest userptr: SUCCESS (0.229s) [60.705152] [003/130] (959s left) gem_mmap_offset (ptrace) [60.761988] Starting subtest: ptrace [60.764515] Starting dynamic subtest: smem0-wb [60.783740] Dynamic subtest smem0-wb: SUCCESS (0.020s) [60.792401] Starting dynamic subtest: smem0-wc [60.804774] Dynamic subtest smem0-wc: SUCCESS (0.021s) [60.811092] Starting dynamic subtest: smem0-uc [60.824888] Dynamic subtest smem0-uc: SUCCESS (0.020s) [60.827116] Subtest ptrace: SUCCESS (0.064s) [60.943757] [004/130] (959s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite) [61.037048] Starting subtest: fbcpsr-rgb101010-draw-pwrite [65.904242] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (4.851s) [66.021367] [005/130] (954s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area) [71.091736] Starting subtest: fbc-psr2-plane-move-sf-dmg-area [71.093999] Starting dynamic subtest: pipe-A-eDP-1 [71.896865] Dynamic subtest pipe-A-eDP-1: SKIP (0.800s) [71.904247] Starting dynamic subtest: pipe-B-eDP-1 [73.019215] Dynamic subtest pipe-B-eDP-1: SKIP (1.121s) [73.023501] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.925s) [74.194250] [006/130] (946s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs) [74.298871] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs [74.301130] Starting dynamic subtest: pipe-A-eDP-1 [74.323281] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [74.330830] Starting dynamic subtest: pipe-B-eDP-1 [74.351677] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [74.357526] Starting dynamic subtest: pipe-C-eDP-1 [74.379122] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [74.386066] Starting dynamic subtest: pipe-D-eDP-1 [74.407637] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [74.411875] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.004s) [76.454923] [007/130] (944s left) kms_invalid_mode (bad-vsync-end) [76.549976] Starting subtest: bad-vsync-end [76.552259] Starting dynamic subtest: pipe-A-eDP-1 [76.843873] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s) [76.850246] Starting dynamic subtest: pipe-B-eDP-1 [76.852463] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [76.858473] Starting dynamic subtest: pipe-C-eDP-1 [76.860704] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [76.866808] Starting dynamic subtest: pipe-D-eDP-1 [76.868697] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [76.870620] Subtest bad-vsync-end: SUCCESS (0.297s) [77.689588] [008/130] (943s left) kms_lease (lease-invalid-connector) [77.780869] Starting subtest: lease-invalid-connector [77.783160] Starting dynamic subtest: pipe-A-eDP-1 [77.785493] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [77.791833] Starting dynamic subtest: pipe-B-eDP-1 [77.794104] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [77.800195] Starting dynamic subtest: pipe-C-eDP-1 [77.802078] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [77.808056] Starting dynamic subtest: pipe-D-eDP-1 [77.810256] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [77.812453] Subtest lease-invalid-connector: SUCCESS (0.002s) [77.842794] [009/130] (943s left) kms_psr (psr2-cursor-plane-move) [77.945129] Starting subtest: psr2-cursor-plane-move [77.947487] Starting dynamic subtest: eDP-1 [79.679692] Dynamic subtest eDP-1: SUCCESS (1.732s) [79.681970] Subtest psr2-cursor-plane-move: SUCCESS (1.732s) [80.452527] [010/130] (940s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats) [80.555798] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats [80.558061] Starting dynamic subtest: pipe-A [80.719642] Dynamic subtest pipe-A: SKIP (0.158s) [80.726898] Starting dynamic subtest: pipe-B [81.967776] Dynamic subtest pipe-B: SKIP (1.246s) [81.976143] Starting dynamic subtest: pipe-C [83.152362] Dynamic subtest pipe-C: SUCCESS (1.190s) [83.160707] Starting dynamic subtest: pipe-D [84.272387] Dynamic subtest pipe-D: SUCCESS (1.120s) [84.274673] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.715s) [85.450851] [011/130] (935s left) kms_vblank (query-busy) [85.546047] Starting subtest: query-busy [85.548479] Starting dynamic subtest: pipe-A-eDP-1 [87.436807] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.886s) [87.450981] Starting dynamic subtest: pipe-D-eDP-1 [90.009243] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.569s) [90.012105] Subtest query-busy: SUCCESS (4.458s) [90.797329] [012/130] (930s left) gem_pxp (regular-baseline-src-copy-readible) [90.919848] Starting subtest: regular-baseline-src-copy-readible [90.979177] Subtest regular-baseline-src-copy-readible: SUCCESS (0.060s) [91.105355] [013/130] (929s left) i915_pm_rps (engine-order) [91.159400] Starting subtest: engine-order [95.782545] Subtest engine-order: SUCCESS (4.621s) [95.852675] [014/130] (925s left) drm_fdinfo (busy-idle) [95.893075] Starting subtest: busy-idle [95.895164] Starting dynamic subtest: rcs0 [95.943027] Dynamic subtest rcs0: SKIP (0.050s) [95.952571] Starting dynamic subtest: bcs0 [95.956672] Dynamic subtest bcs0: SKIP (0.001s) [95.964359] Starting dynamic subtest: vcs0 [95.968609] Dynamic subtest vcs0: SKIP (0.000s) [95.975012] Starting dynamic subtest: vcs1 [95.978948] Dynamic subtest vcs1: SKIP (0.000s) [95.984893] Starting dynamic subtest: vecs0 [95.988818] Dynamic subtest vecs0: SKIP (0.000s) [95.994771] Starting dynamic subtest: ccs0 [95.998722] Dynamic subtest ccs0: SKIP (0.000s) [96.002684] Subtest busy-idle: SKIP (0.054s) [96.029417] [015/130] (925s left) gem_mmap_gtt (ptrace) [96.086956] Subtest ptrace: SKIP (0.000s) [96.151027] [016/130] (924s left) gem_exec_reloc (basic-write-read) [96.218709] Subtest basic-write-read: SKIP (0.000s) [96.306940] [017/130] (924s left) kms_flip (2x-flip-vs-rmfb-interruptible) [96.414753] Starting subtest: 2x-flip-vs-rmfb-interruptible [96.426662] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.010s) [96.544646] [018/130] (924s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt) [96.665714] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt [99.339437] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.687s) [99.471808] [019/130] (921s left) gem_exec_gttfill (multigpu-basic) [99.645744] Starting subtest: multigpu-basic [115.523315] Subtest multigpu-basic: SUCCESS (15.875s) [115.563963] [020/130] (905s left) prime_mmap (test_dup) [115.657866] Starting subtest: test_dup [115.659774] Starting dynamic subtest: test_dup-smem [115.691457] Dynamic subtest test_dup-smem: SUCCESS (0.035s) [115.693907] Subtest test_dup: SUCCESS (0.035s) [115.759843] [021/130] (905s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [115.894645] Starting subtest: basic-flip-after-cursor-atomic [116.849633] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.956s) [117.644977] [022/130] (903s left) gem_render_copy_redux (interruptible) [117.707113] Starting subtest: interruptible [118.065462] Subtest interruptible: SUCCESS (0.358s) [118.179394] [023/130] (903s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu) [118.282474] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu [121.300440] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (3.022s) [121.428948] [024/130] (899s left) i915_query (hwconfig_table) [121.480668] Starting subtest: hwconfig_table [121.725951] Subtest hwconfig_table: SUCCESS (0.002s) [121.752772] [025/130] (899s left) kms_feature_discovery (chamelium) [121.852227] Starting subtest: chamelium [121.855898] Subtest chamelium: SKIP (0.000s) [121.919710] [026/130] (899s left) kms_psr (psr-cursor-mmap-gtt) [122.016705] Starting subtest: psr-cursor-mmap-gtt [122.018635] Starting dynamic subtest: eDP-1 [123.765506] Dynamic subtest eDP-1: SUCCESS (1.745s) [123.767480] Subtest psr-cursor-mmap-gtt: SUCCESS (1.746s) [124.623547] [027/130] (896s left) kms_flip (2x-flip-vs-fences) [124.719810] Starting subtest: 2x-flip-vs-fences [124.724245] Subtest 2x-flip-vs-fences: SKIP (0.000s) [124.834097] [028/130] (896s left) kms_flip (2x-blocking-wf_vblank) [124.926893] Starting subtest: 2x-blocking-wf_vblank [124.937660] Subtest 2x-blocking-wf_vblank: SKIP (0.010s) [125.006206] [029/130] (896s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [125.117292] Starting subtest: cursorA-vs-flipA-atomic-transitions [136.314513] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.198s) [137.147226] [030/130] (884s left) gem_exec_fence (nb-await) [137.330249] Starting subtest: nb-await [137.332637] Starting dynamic subtest: rcs0 [137.395527] Dynamic subtest rcs0: SUCCESS (0.064s) [137.405507] Starting dynamic subtest: bcs0 [137.453793] Dynamic subtest bcs0: SUCCESS (0.057s) [137.464083] Starting dynamic subtest: vcs0 [137.512970] Dynamic subtest vcs0: SUCCESS (0.058s) [137.522551] Starting dynamic subtest: vcs1 [137.571022] Dynamic subtest vcs1: SUCCESS (0.058s) [137.580753] Starting dynamic subtest: vecs0 [137.628824] Dynamic subtest vecs0: SUCCESS (0.057s) [137.638941] Starting dynamic subtest: ccs0 [137.686138] Dynamic subtest ccs0: SUCCESS (0.057s) [137.688588] Subtest nb-await: SUCCESS (0.355s) [139.976665] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true [139.979767] [031/130] (881s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation) [140.077815] Starting subtest: plane-upscale-factor-0-25-with-rotation [140.080241] Starting dynamic subtest: pipe-A [140.306348] Dynamic subtest pipe-A: SUCCESS (0.228s) [140.315244] Starting dynamic subtest: pipe-B [141.632527] Dynamic subtest pipe-B: SUCCESS (1.326s) [141.643501] Starting dynamic subtest: pipe-C [142.955515] Dynamic subtest pipe-C: SUCCESS (1.323s) [142.964709] Starting dynamic subtest: pipe-D [144.187096] Dynamic subtest pipe-D: SUCCESS (1.231s) [144.189832] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.109s) [145.351313] [032/130] (876s left) vgem_basic (debugfs) [145.399896] Starting subtest: debugfs [145.402181] Subtest debugfs: SUCCESS (0.002s) [145.450844] [033/130] (876s left) vgem_basic (second-client) [145.489409] Starting subtest: second-client [145.510847] Subtest second-client: SUCCESS (0.022s) [145.562880] [034/130] (876s left) kms_color (ctm-signed) [145.660080] Starting subtest: ctm-signed [145.662402] Starting dynamic subtest: pipe-A-eDP-1 [147.699348] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.036s) [147.708074] Starting dynamic subtest: pipe-B-eDP-1 [150.351607] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.653s) [150.359079] Starting dynamic subtest: pipe-C-eDP-1 [152.995555] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.643s) [153.004250] Starting dynamic subtest: pipe-D-eDP-1 [155.620061] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.624s) [155.622485] Subtest ctm-signed: SUCCESS (9.959s) [156.460440] [035/130] (865s left) kms_plane_multiple (tiling-x) [156.562849] Starting subtest: tiling-x [156.565235] Starting dynamic subtest: pipe-A-eDP-1 [160.279841] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.714s) [160.289403] Starting dynamic subtest: pipe-B-eDP-1 [164.677451] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.399s) [164.685977] Starting dynamic subtest: pipe-C-eDP-1 [168.974633] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.293s) [168.982723] Starting dynamic subtest: pipe-D-eDP-1 [173.256543] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.282s) [173.258842] Subtest tiling-x: SUCCESS (16.693s) [174.079426] [036/130] (847s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite) [174.177649] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite [177.634551] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (3.461s) [177.776663] [037/130] (844s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted) [177.837926] Starting subtest: invalid-multi-wait-unsubmitted-submitted [177.839997] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s) [177.940026] [038/130] (843s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt) [178.042078] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt [178.045676] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [178.152854] [039/130] (843s left) gem_exec_store (dword) [178.227116] Starting subtest: dword [178.229069] Starting dynamic subtest: rcs0 [178.322573] Dynamic subtest rcs0: SUCCESS (0.092s) [178.332835] Starting dynamic subtest: bcs0 [178.361240] Dynamic subtest bcs0: SUCCESS (0.040s) [178.370882] Starting dynamic subtest: vcs0 [178.401732] Dynamic subtest vcs0: SUCCESS (0.039s) [178.411584] Starting dynamic subtest: vcs1 [178.441616] Dynamic subtest vcs1: SUCCESS (0.038s) [178.451421] Starting dynamic subtest: vecs0 [178.483570] Dynamic subtest vecs0: SUCCESS (0.041s) [178.491578] Starting dynamic subtest: ccs0 [178.524055] Dynamic subtest ccs0: SUCCESS (0.039s) [178.526185] Subtest dword: SUCCESS (0.298s) [178.703533] [040/130] (843s left) kms_chamelium_hpd (dp-hpd-fast) [179.076071] Subtest dp-hpd-fast: SKIP (0.000s) [179.911575] [041/130] (842s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [180.023171] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu [180.027243] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [180.118082] [042/130] (841s left) gem_exec_whisper (basic-sync-all) [180.185908] Starting subtest: basic-sync-all [186.687540] Subtest basic-sync-all: SUCCESS (6.501s) [186.867169] [043/130] (835s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff) [186.971774] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff [186.975750] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [187.080616] [044/130] (834s left) sw_sync (timeline_closed) [187.117765] Starting subtest: timeline_closed [187.119756] Subtest timeline_closed: SUCCESS (0.000s) [187.166096] [045/130] (834s left) kms_big_fb (yf-tiled-addfb) [187.279659] Starting subtest: yf-tiled-addfb [187.284152] Subtest yf-tiled-addfb: SKIP (0.000s) [187.375852] [046/130] (834s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt) [187.485851] Starting subtest: fbc-rgb101010-draw-blt [191.030410] Subtest fbc-rgb101010-draw-blt: SUCCESS (3.548s) [191.148593] [047/130] (831s left) sw_sync (sync_merge) [191.182846] Starting subtest: sync_merge [191.185412] Subtest sync_merge: SUCCESS (0.000s) [191.224300] [048/130] (830s left) gem_ctx_isolation (dirty-create) [191.288309] Starting subtest: dirty-create [191.290639] Starting dynamic subtest: rcs0 [191.727890] Dynamic subtest rcs0: SUCCESS (0.439s) [191.737818] Starting dynamic subtest: bcs0 [192.157829] Dynamic subtest bcs0: SUCCESS (0.430s) [192.167396] Starting dynamic subtest: vcs0 [192.596202] Dynamic subtest vcs0: SUCCESS (0.438s) [192.607054] Starting dynamic subtest: vcs1 [194.325040] Dynamic subtest vcs1: SUCCESS (1.727s) [194.336348] Starting dynamic subtest: vecs0 [196.377596] Dynamic subtest vecs0: SUCCESS (2.052s) [196.388203] Starting dynamic subtest: ccs0 [198.414692] Dynamic subtest ccs0: SUCCESS (2.037s) [198.417130] Subtest dirty-create: SUCCESS (7.126s) [198.662392] [049/130] (823s left) syncobj_timeline (invalid-transfer-illegal-handle) [198.714528] Starting subtest: invalid-transfer-illegal-handle [198.716612] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s) [198.822968] [050/130] (823s left) kms_vblank (wait-busy-hang) [198.924129] Starting subtest: wait-busy-hang [198.926465] Starting dynamic subtest: pipe-A-eDP-1 [225.698266] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.770s) [225.711946] Starting dynamic subtest: pipe-D-eDP-1 [250.663896] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.962s) [250.666775] Subtest wait-busy-hang: SUCCESS (51.736s) [251.507551] [051/130] (770s left) gem_pwrite_snooped [251.660381] [052/130] (770s left) kms_busy (extended-pageflip-modeset-hang-oldfb) [251.769181] Starting subtest: extended-pageflip-modeset-hang-oldfb [251.771684] Starting dynamic subtest: pipe-A [259.673720] Dynamic subtest pipe-A: SUCCESS (7.902s) [259.683324] Starting dynamic subtest: pipe-D [268.305842] Dynamic subtest pipe-D: SUCCESS (8.629s) [268.308288] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.536s) [269.153122] [053/130] (753s left) perf (mi-rpc) [269.276450] Starting subtest: mi-rpc [269.281062] Subtest mi-rpc: SKIP (0.000s) [269.311334] [054/130] (753s left) perf_pmu (event-wait) [269.359966] Starting subtest: event-wait [269.362203] Starting dynamic subtest: rcs0 [269.366122] Dynamic subtest rcs0: SKIP (0.000s) [269.370134] Subtest event-wait: SKIP (0.001s) [269.485696] [055/130] (752s left) core_auth (basic-auth) [269.549371] Starting subtest: basic-auth [269.551694] Subtest basic-auth: SUCCESS (0.002s) [269.637959] [056/130] (752s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing) [269.741151] Starting subtest: plane-all-transition-nonblocking-fencing [269.743058] Starting dynamic subtest: pipe-A-eDP-1 [272.242330] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.502s) [272.533437] Starting dynamic subtest: pipe-B-eDP-1 [275.784063] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.262s) [276.062233] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.321s) [276.893846] [057/130] (745s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite) [277.002201] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite [277.006142] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [277.117120] [058/130] (745s left) gem_exec_params (mmapped) [277.187278] Starting subtest: mmapped [277.192645] Subtest mmapped: SUCCESS (0.003s) [277.328930] [059/130] (745s left) gem_exec_balancer (busy) [277.398177] Starting subtest: busy [278.971299] Subtest busy: SUCCESS (1.571s) [279.269178] [060/130] (743s left) kms_cursor_legacy (cursora-vs-flipa-legacy) [279.386595] Starting subtest: cursorA-vs-flipA-legacy [290.533555] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.147s) [291.321419] [061/130] (731s left) kms_psr (fbc-psr2-dpms) [291.425359] Starting subtest: fbc-psr2-dpms [291.427695] Starting dynamic subtest: eDP-1 [291.432162] Dynamic subtest eDP-1: SKIP (0.000s) [291.436572] Subtest fbc-psr2-dpms: SKIP (0.001s) [291.492290] [062/130] (731s left) api_intel_bb (bb-with-allocator) [291.547680] Starting subtest: bb-with-allocator [291.551963] Subtest bb-with-allocator: SUCCESS (0.005s) [291.676443] [063/130] (731s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu) [291.783676] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu [296.033514] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (4.254s) [296.162345] [064/130] (726s left) syncobj_eventfd (binary-wait) [296.218504] Starting subtest: binary-wait [296.226783] Subtest binary-wait: SUCCESS (0.010s) [296.293306] [065/130] (726s left) gem_wait (busy) [296.367266] Starting subtest: busy [296.369831] Starting dynamic subtest: all-engines [297.899093] Dynamic subtest all-engines: SUCCESS (1.530s) [297.910857] Starting dynamic subtest: rcs0 [298.439029] Dynamic subtest rcs0: SUCCESS (0.538s) [298.449734] Starting dynamic subtest: bcs0 [298.977518] Dynamic subtest bcs0: SUCCESS (0.538s) [298.986329] Starting dynamic subtest: vcs0 [300.906829] Dynamic subtest vcs0: SUCCESS (1.927s) [300.916789] Starting dynamic subtest: vcs1 [302.954888] Dynamic subtest vcs1: SUCCESS (2.046s) [302.965893] Starting dynamic subtest: vecs0 [304.938676] Dynamic subtest vecs0: SUCCESS (1.982s) [304.948550] Starting dynamic subtest: ccs0 [305.480243] Dynamic subtest ccs0: SUCCESS (0.541s) [305.482606] Subtest busy: SUCCESS (9.113s) [305.677811] [066/130] (717s left) kms_big_fb (x-tiled-addfb-size-overflow) [305.781588] Starting subtest: x-tiled-addfb-size-overflow [305.783524] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s) [305.886263] [067/130] (716s left) kms_cursor_legacy (forked-move) [306.007320] Starting subtest: forked-move [306.009180] Starting dynamic subtest: pipe-A [311.494961] Dynamic subtest pipe-A: SUCCESS (5.485s) [311.503897] Starting dynamic subtest: pipe-B [316.993713] Dynamic subtest pipe-B: SUCCESS (5.497s) [317.007644] Starting dynamic subtest: pipe-C [322.485867] Dynamic subtest pipe-C: SUCCESS (5.490s) [322.498275] Starting dynamic subtest: pipe-D [327.998736] Dynamic subtest pipe-D: SUCCESS (5.510s) [328.009838] Starting dynamic subtest: all-pipes [333.540306] Dynamic subtest all-pipes: SUCCESS (5.540s) [333.542462] Subtest forked-move: SUCCESS (27.531s) [333.669921] [068/130] (689s left) kms_dither (fb-8bpc-vs-panel-6bpc) [333.771175] Starting subtest: fb-8bpc-vs-panel-6bpc [333.773456] Starting dynamic subtest: pipe-A-eDP-1 [335.136077] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.362s) [335.138438] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.363s) [335.922274] [069/130] (687s left) kms_frontbuffer_tracking (psr-1p-rte) [336.018657] Starting subtest: psr-1p-rte [339.301812] Subtest psr-1p-rte: SUCCESS (3.288s) [339.443435] [070/130] (683s left) kms_psr (pr-dpms) [339.536770] Starting subtest: pr-dpms [339.540738] Subtest pr-dpms: SKIP (0.000s) [339.601454] [071/130] (683s left) kms_flip (bo-too-big-interruptible) [339.703106] Starting subtest: bo-too-big-interruptible [339.705458] Starting dynamic subtest: A-eDP1 [341.463684] Dynamic subtest A-eDP1: SUCCESS (1.759s) [341.474320] Starting dynamic subtest: B-eDP1 [343.202259] Dynamic subtest B-eDP1: SUCCESS (1.735s) [343.210192] Starting dynamic subtest: C-eDP1 [344.904395] Dynamic subtest C-eDP1: SUCCESS (1.700s) [344.912576] Starting dynamic subtest: D-eDP1 [346.645132] Dynamic subtest D-eDP1: SUCCESS (1.740s) [346.647453] Subtest bo-too-big-interruptible: SUCCESS (6.944s) [347.896188] [072/130] (675s left) kms_content_protection (dp-mst-type-1) [348.065640] Starting subtest: dp-mst-type-1 [348.070174] Subtest dp-mst-type-1: SKIP (0.000s) [348.206434] [073/130] (674s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs) [348.302329] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs [348.304575] Starting dynamic subtest: pipe-A-eDP-1 [348.361673] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [348.368998] Starting dynamic subtest: pipe-B-eDP-1 [348.426695] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [348.435095] Starting dynamic subtest: pipe-C-eDP-1 [348.489453] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [348.496820] Starting dynamic subtest: pipe-D-eDP-1 [348.552603] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [348.556520] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.008s) [350.458318] [074/130] (672s left) kms_addfb_basic (invalid-get-prop-any) [350.520053] Starting subtest: invalid-get-prop-any [350.521983] Subtest invalid-get-prop-any: SUCCESS (0.000s) [350.660911] [075/130] (672s left) gem_caching (writes) [350.715524] Subtest writes: SKIP (0.000s) [350.791530] [076/130] (672s left) kms_big_fb (linear-16bpp-rotate-90) [350.899936] Starting subtest: linear-16bpp-rotate-90 [350.919385] Subtest linear-16bpp-rotate-90: SKIP (0.018s) [351.058199] [077/130] (672s left) gem_exec_schedule (preempt-queue-contexts-chain) [351.143020] Subtest preempt-queue-contexts-chain: SKIP (0.000s) [351.418381] [078/130] (671s left) kms_scaling_modes (scaling-mode-full-aspect) [351.516288] Starting subtest: scaling-mode-full-aspect [351.518483] Starting dynamic subtest: pipe-A-eDP-1 [351.631113] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.114s) [351.641171] Starting dynamic subtest: pipe-B-eDP-1 [352.777420] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.145s) [352.785435] Starting dynamic subtest: pipe-C-eDP-1 [353.982224] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.204s) [353.990492] Starting dynamic subtest: pipe-D-eDP-1 [355.107533] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s) [355.109935] Subtest scaling-mode-full-aspect: SUCCESS (3.591s) [356.258980] [079/130] (667s left) gem_lmem_swapping (heavy-verify-random) [356.308077] Subtest heavy-verify-random: SKIP (0.000s) [356.353405] [080/130] (666s left) kms_psr (fbc-psr2-cursor-mmap-cpu) [356.458341] Starting subtest: fbc-psr2-cursor-mmap-cpu [356.460241] Starting dynamic subtest: eDP-1 [356.464260] Dynamic subtest eDP-1: SKIP (0.000s) [356.468240] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.001s) [356.531382] [081/130] (666s left) syncobj_basic (test-valid-cycle) [356.579614] Starting subtest: test-valid-cycle [356.582062] Subtest test-valid-cycle: SUCCESS (0.000s) [356.668238] [082/130] (666s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area) [361.688104] Starting subtest: pr-primary-plane-update-sf-dmg-area [361.692807] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.002s) [362.541816] [083/130] (660s left) gem_exec_reloc (basic-cpu-active) [362.612203] Subtest basic-cpu-active: SKIP (0.000s) [362.665909] [084/130] (660s left) syncobj_timeline (wait-all-complex) [362.724113] Starting subtest: wait-all-complex [362.938542] Subtest wait-all-complex: SUCCESS (0.214s) [363.008938] [085/130] (660s left) kms_atomic (atomic-plane-damage) [363.109112] Starting subtest: atomic-plane-damage [363.169068] Starting dynamic subtest: pipe-A-eDP-1 [363.752031] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.583s) [363.754336] Subtest atomic-plane-damage: SUCCESS (0.642s) [364.571947] [086/130] (658s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling) [364.678237] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling [364.680680] Starting dynamic subtest: pipe-A-default-mode [364.966639] Dynamic subtest pipe-A-default-mode: SKIP (0.284s) [364.971959] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.286s) [365.791772] [087/130] (657s left) kms_plane_alpha_blend (coverage-7efc) [365.882788] Starting subtest: coverage-7efc [366.306716] Starting dynamic subtest: pipe-A-eDP-1 [366.835471] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.527s) [366.858085] Starting dynamic subtest: pipe-D-eDP-1 [368.071750] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.220s) [368.074183] Subtest coverage-7efc: SUCCESS (2.187s) [368.851481] [088/130] (654s left) api_intel_bb (simple-bb-ctx) [368.902126] Starting subtest: simple-bb-ctx [368.909272] Subtest simple-bb-ctx: SUCCESS (0.010s) [369.031878] [089/130] (654s left) gem_userptr_blits (mmap-offset-banned) [369.100291] Starting subtest: mmap-offset-banned [369.102453] Starting dynamic subtest: wb [369.104689] Dynamic subtest wb: SUCCESS (0.000s) [369.112376] Starting dynamic subtest: wc [369.114219] Dynamic subtest wc: SUCCESS (0.000s) [369.121178] Starting dynamic subtest: uc [369.123059] Dynamic subtest uc: SUCCESS (0.000s) [369.124982] Subtest mmap-offset-banned: SUCCESS (0.002s) [369.254125] [090/130] (654s left) gem_userptr_blits (unsync-unmap) [369.335447] Subtest unsync-unmap: SKIP (0.000s) [369.463926] [091/130] (654s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [369.568211] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [369.572384] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [369.664058] [092/130] (654s left) kms_big_fb (4-tiled-32bpp-rotate-0) [369.763385] Starting subtest: 4-tiled-32bpp-rotate-0 [371.417952] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.655s) [371.578937] [093/130] (652s left) gen9_exec_parse (bb-oversize) [371.635980] Subtest bb-oversize: SKIP (0.000s) [371.723976] [094/130] (652s left) kms_cursor_crc (cursor-rapid-movement-64x64) [371.877591] Starting subtest: cursor-rapid-movement-64x64 [371.883925] Starting dynamic subtest: pipe-A-eDP-1 [372.436194] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.551s) [372.459383] Starting dynamic subtest: pipe-D-eDP-1 [373.661572] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.208s) [373.663831] Subtest cursor-rapid-movement-64x64: SUCCESS (1.782s) [374.502426] [095/130] (649s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs) [374.560146] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs [374.564679] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s) [374.740459] [096/130] (649s left) gem_unref_active_buffers [374.951109] [097/130] (648s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [375.059224] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip [375.063607] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [375.162975] [098/130] (648s left) core_getclient [375.320367] [099/130] (648s left) kms_big_fb (y-tiled-64bpp-rotate-180) [375.426116] Starting subtest: y-tiled-64bpp-rotate-180 [375.430554] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s) [375.529469] [100/130] (648s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt) [375.628470] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt [375.632984] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [375.734906] [101/130] (648s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff) [375.841276] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff [380.731725] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (4.895s) [380.894317] [102/130] (643s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180) [381.002885] Starting subtest: primary-yf-tiled-reflect-x-180 [381.061989] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s) [381.162255] [103/130] (642s left) kms_cursor_crc (cursor-random-64x64) [381.312600] Starting subtest: cursor-random-64x64 [381.318731] Starting dynamic subtest: pipe-A-eDP-1 [384.914875] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.598s) [384.941214] Starting dynamic subtest: pipe-D-eDP-1 [389.181874] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.245s) [389.184280] Subtest cursor-random-64x64: SUCCESS (7.868s) [390.012900] [104/130] (634s left) perf_pmu (init-busy) [390.054082] Starting subtest: init-busy [390.056354] Starting dynamic subtest: rcs0 [390.058654] Dynamic subtest rcs0: SUCCESS (0.001s) [390.066182] Starting dynamic subtest: bcs0 [390.068089] Dynamic subtest bcs0: SUCCESS (0.001s) [390.075257] Starting dynamic subtest: vcs0 [390.077250] Dynamic subtest vcs0: SUCCESS (0.001s) [390.084650] Starting dynamic subtest: vcs1 [390.086887] Dynamic subtest vcs1: SUCCESS (0.001s) [390.094064] Starting dynamic subtest: vecs0 [390.096283] Dynamic subtest vecs0: SUCCESS (0.001s) [390.103442] Starting dynamic subtest: ccs0 [390.105661] Dynamic subtest ccs0: SUCCESS (0.001s) [390.107902] Subtest init-busy: SUCCESS (0.007s) [390.165196] [105/130] (634s left) perf (per-context-mode-unprivileged) [390.246832] Starting subtest: per-context-mode-unprivileged [390.251448] Subtest per-context-mode-unprivileged: SKIP (0.000s) [390.283660] [106/130] (633s left) gem_ctx_persistence (engines-mixed-process) [390.344691] Starting subtest: engines-mixed-process [390.347169] Starting dynamic subtest: rcs0 [391.934725] Dynamic subtest rcs0: SUCCESS (1.589s) [391.944254] Starting dynamic subtest: bcs0 [393.966933] Dynamic subtest bcs0: SUCCESS (2.030s) [393.977349] Starting dynamic subtest: vcs0 [395.956741] Dynamic subtest vcs0: SUCCESS (1.989s) [395.967306] Starting dynamic subtest: vcs1 [397.949157] Dynamic subtest vcs1: SUCCESS (1.991s) [397.958226] Starting dynamic subtest: vecs0 [399.944185] Dynamic subtest vecs0: SUCCESS (1.993s) [399.954478] Starting dynamic subtest: ccs0 [401.909042] Dynamic subtest ccs0: SUCCESS (1.963s) [401.911417] Subtest engines-mixed-process: SUCCESS (11.564s) [401.982339] [107/130] (622s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-wc) [402.078385] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-wc [406.567713] Subtest psr-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (4.491s) [406.686494] [108/130] (617s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [406.796356] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu [406.800906] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [406.898800] [109/130] (617s left) i915_pm_freq_api (freq-suspend) [406.965815] Starting subtest: freq-suspend [406.968134] Starting dynamic subtest: gt0 [423.007967] Dynamic subtest gt0: SUCCESS (1.319s) [423.018183] Starting dynamic subtest: gt1 [438.937662] Dynamic subtest gt1: SUCCESS (1.145s) [438.953338] Subtest freq-suspend: SUCCESS (2.467s) [440.009952] [110/130] (614s left) syncobj_timeline (multi-wait-for-submit-available-submitted) [440.065970] Starting subtest: multi-wait-for-submit-available-submitted [440.069058] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s) [440.176027] [111/130] (614s left) kms_chamelium_audio (dp-audio-edid) [440.586279] Subtest dp-audio-edid: SKIP (0.000s) [441.381286] [112/130] (613s left) kms_big_fb (x-tiled-32bpp-rotate-0) [441.484647] Starting subtest: x-tiled-32bpp-rotate-0 [443.243010] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.734s) [443.433724] [113/130] (611s left) gem_ccs (ctrl-surf-copy-new-ctx) [443.496536] Starting subtest: ctrl-surf-copy-new-ctx [443.500892] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s) [443.586505] [114/130] (611s left) kms_frontbuffer_tracking (fbcpsr-tiling-4) [443.691799] Starting subtest: fbcpsr-tiling-4 [448.670125] Subtest fbcpsr-tiling-4: SUCCESS (4.936s) [448.825946] [115/130] (606s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels) [448.933001] Starting subtest: plane-all-modeset-transition-fencing-internal-panels [448.935801] Starting dynamic subtest: pipe-A-eDP-1 [450.289184] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.346s) [450.572180] Starting dynamic subtest: pipe-B-eDP-1 [451.616248] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.053s) [451.880699] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.931s) [452.719872] [116/130] (602s left) kms_pm_rpm (cursor) [454.569549] Starting subtest: cursor [454.575357] Subtest cursor: SKIP (0.000s) [454.963202] [117/130] (600s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs) [455.065189] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs [455.067954] Starting dynamic subtest: pipe-A-eDP-1 [455.251667] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.172s) [455.261820] Starting dynamic subtest: pipe-B-eDP-1 [455.282728] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [455.291862] Starting dynamic subtest: pipe-C-eDP-1 [455.312863] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s) [455.321844] Starting dynamic subtest: pipe-D-eDP-1 [455.343408] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s) [455.345896] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.180s) [457.517063] [118/130] (597s left) kms_addfb_basic (unused-handle) [457.574152] Starting subtest: unused-handle [457.576596] Subtest unused-handle: SUCCESS (0.000s) [457.724254] [119/130] (597s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt) [457.831005] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt [457.836180] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [457.942663] [120/130] (597s left) gem_mmap_gtt (basic-write) [458.004925] Subtest basic-write: SKIP (0.000s) [458.063947] [121/130] (597s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render) [458.177262] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render [458.182486] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [458.283520] [122/130] (597s left) prime_mmap_coherency (read) [458.341851] Starting subtest: read [463.728173] Subtest read: SUCCESS (5.376s) [463.859905] [123/130] (591s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi [463.909179] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted [464.008590] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.101s) [464.082565] [124/130] (591s left) gem_exec_flush (basic-batch-kernel-default-uc) [464.144296] Starting subtest: basic-batch-kernel-default-uc [471.510488] Subtest basic-batch-kernel-default-uc: SUCCESS (7.360s) [471.696450] [125/130] (583s left) syncobj_wait (multi-wait-submitted) [471.762471] Starting subtest: multi-wait-submitted [471.866599] Subtest multi-wait-submitted: SUCCESS (0.104s) [471.940553] [126/130] (583s left) kms_setmode (invalid-clone-exclusive-crtc) [471.993156] Starting subtest: invalid-clone-exclusive-crtc [472.000822] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s) [472.102878] [127/130] (583s left) gem_softpin (allocator-basic-reserve) [472.176650] Starting subtest: allocator-basic-reserve [472.241424] Subtest allocator-basic-reserve: SUCCESS (0.068s) [472.398771] [128/130] (583s left) fbdev (nullptr) [472.441106] Starting subtest: nullptr [472.443966] Subtest nullptr: SUCCESS (0.000s) [472.494989] [129/130] (583s left) kms_prop_blob (blob-prop-core) [472.550066] Starting subtest: blob-prop-core [472.553256] Subtest blob-prop-core: SUCCESS (0.000s) [472.652207] [130/130] (583s left) gem_exec_balancer (parallel) [472.901990] Starting subtest: parallel [473.685212] Subtest parallel: SUCCESS (0.786s) [474.440658] Closing watchdogs Done.