[62.171424] Initializing watchdogs [62.171469] /dev/watchdog0 [62.185874] [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] [62.189835] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [62.192040] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [62.195414] [001/130] (960s left) perf (non-zero-reason) [62.389043] Starting subtest: non-zero-reason [62.391245] Starting dynamic subtest: 0-rcs0 [67.834099] Dynamic subtest 0-rcs0: SUCCESS (5.443s) [67.843033] Starting dynamic subtest: 1-vcs1 [71.764430] Dynamic subtest 1-vcs1: SUCCESS (3.930s) [71.767576] Subtest non-zero-reason: SUCCESS (9.374s) [71.813322] [002/130] (950s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [71.946082] Starting subtest: basic-flip-before-cursor-varying-size [72.905818] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.958s) [73.775422] [003/130] (949s left) gem_render_tiled_blits (basic) [73.834065] Subtest basic: SKIP (0.000s) [73.918265] [004/130] (948s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite) [74.028390] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite [78.611866] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.587s) [78.731830] [005/130] (944s left) core_hotunplug (unbind-rebind) [78.816396] Starting subtest: unbind-rebind [84.803589] Subtest unbind-rebind: SUCCESS (5.989s) [84.841039] [006/130] (938s left) kms_atomic_interruptible (legacy-cursor) [85.949051] Starting subtest: legacy-cursor [85.951339] Starting dynamic subtest: pipe-A-eDP-1 [93.310733] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.362s) [93.313796] Subtest legacy-cursor: SUCCESS (7.363s) [94.148271] [007/130] (928s left) kms_cursor_crc (cursor-random-max-size) [94.426570] Starting subtest: cursor-random-max-size [94.433242] Subtest cursor-random-max-size: SKIP (0.000s) [94.563980] [008/130] (928s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt) [94.672360] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt [97.363075] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.694s) [97.494736] [009/130] (925s left) kms_content_protection (mei-interface) [97.673083] Starting subtest: mei-interface [97.676921] Subtest mei-interface: SKIP (0.001s) [97.806393] [010/130] (925s left) kms_rotation_crc (bad-tiling) [97.903718] Starting subtest: bad-tiling [97.907412] Subtest bad-tiling: SKIP (0.001s) [97.966785] [011/130] (925s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt) [98.072061] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt [98.075936] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [98.167530] [012/130] (924s left) kms_big_fb (x-tiled-64bpp-rotate-180) [98.275672] Starting subtest: x-tiled-64bpp-rotate-180 [100.578110] Subtest x-tiled-64bpp-rotate-180: SUCCESS (2.302s) [100.802072] [013/130] (922s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt) [100.907450] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt [100.911691] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [101.032423] [014/130] (922s left) kms_psr (fbc-psr2-suspend) [101.132343] Starting subtest: fbc-psr2-suspend [101.134732] Starting dynamic subtest: eDP-1 [101.139120] Dynamic subtest eDP-1: SKIP (0.000s) [101.143309] Subtest fbc-psr2-suspend: SKIP (0.000s) [101.198490] [015/130] (921s left) kms_addfb_basic (master-rmfb) [101.258855] Starting subtest: master-rmfb [101.260790] Subtest master-rmfb: SUCCESS (0.001s) [101.393247] [016/130] (921s left) gem_pread (snoop) [101.462048] Subtest snoop: SKIP (0.000s) [101.517931] [017/130] (921s left) kms_getfb (getfb-handle-zero) [101.580634] Starting subtest: getfb-handle-zero [101.582968] Subtest getfb-handle-zero: SUCCESS (0.000s) [101.673775] [018/130] (921s left) syncobj_timeline (etime-single-wait-for-submit-submitted) [101.731978] Starting subtest: etime-single-wait-for-submit-submitted [101.835693] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.103s) [101.900845] [019/130] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff) [102.005439] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff [106.861423] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (4.858s) [107.000797] [020/130] (916s left) kms_dp_linktrain_fallback (dp-fallback) [107.107054] Starting subtest: dp-fallback [107.113625] Subtest dp-fallback: SKIP (0.000s) [107.173066] [021/130] (916s left) gem_ctx_persistence (legacy-engines-mixed) [107.240251] Starting subtest: legacy-engines-mixed [107.242438] Starting dynamic subtest: render [107.369139] Dynamic subtest render: SUCCESS (0.128s) [107.380028] Starting dynamic subtest: bsd1 [107.448833] Dynamic subtest bsd1: SUCCESS (0.078s) [107.459060] Starting dynamic subtest: bsd2 [107.542952] Dynamic subtest bsd2: SUCCESS (0.093s) [107.552778] Starting dynamic subtest: blt [107.629352] Dynamic subtest blt: SUCCESS (0.085s) [107.639917] Starting dynamic subtest: vebox [107.701492] Dynamic subtest vebox: SUCCESS (0.071s) [107.704211] Subtest legacy-engines-mixed: SUCCESS (0.460s) [107.771317] [022/130] (915s left) gem_mmap_wc (set-cache-level) [107.836439] Subtest set-cache-level: SKIP (0.000s) [107.891714] [023/130] (915s left) prime_mmap (test_dup) [107.954411] Starting subtest: test_dup [107.956137] Starting dynamic subtest: test_dup-smem [108.017887] Dynamic subtest test_dup-smem: SUCCESS (0.063s) [108.020724] Subtest test_dup: SUCCESS (0.064s) [108.087212] [024/130] (915s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render) [108.191465] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render [111.629402] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (3.441s) [111.769108] [025/130] (911s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs) [111.867541] Starting subtest: crc-primary-rotation-180-y-tiled-ccs [111.869525] Starting dynamic subtest: pipe-A-eDP-1 [111.889075] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [111.898065] Starting dynamic subtest: pipe-B-eDP-1 [111.916255] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [111.923232] Starting dynamic subtest: pipe-C-eDP-1 [111.940246] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [111.947207] Starting dynamic subtest: pipe-D-eDP-1 [111.966230] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [111.969523] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.004s) [113.990921] [026/130] (909s left) gem_userptr_blits (sd-probe) [114.056949] Starting subtest: sd-probe [114.059248] Subtest sd-probe: SUCCESS (0.000s) [114.176661] [027/130] (909s left) kms_hdr (bpc-switch-suspend) [114.281037] Starting subtest: bpc-switch-suspend [115.365976] Starting dynamic subtest: pipe-A-eDP-1 [134.515286] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.465s) [134.517496] Subtest bpc-switch-suspend: SUCCESS (5.550s) [134.656711] [028/130] (903s left) kms_big_fb (4-tiled-64bpp-rotate-180) [134.765467] Starting subtest: 4-tiled-64bpp-rotate-180 [137.074624] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (2.307s) [137.289213] [029/130] (901s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs) [137.383294] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs [137.385704] Starting dynamic subtest: pipe-A-eDP-1 [137.408649] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [137.417189] Starting dynamic subtest: pipe-B-eDP-1 [137.439117] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [137.448407] Starting dynamic subtest: pipe-C-eDP-1 [137.470703] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [137.478894] Starting dynamic subtest: pipe-D-eDP-1 [137.501001] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [137.505468] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.003s) [139.509967] [030/130] (898s left) kms_flip (blocking-absolute-wf_vblank) [139.619781] Starting subtest: blocking-absolute-wf_vblank [139.622083] Starting dynamic subtest: A-eDP1 [148.373764] Dynamic subtest A-eDP1: SUCCESS (8.749s) [148.383874] Starting dynamic subtest: B-eDP1 [157.097256] Dynamic subtest B-eDP1: SUCCESS (8.719s) [157.105639] Starting dynamic subtest: C-eDP1 [165.805036] Dynamic subtest C-eDP1: SUCCESS (8.704s) [165.818357] Starting dynamic subtest: D-eDP1 [174.533365] Dynamic subtest D-eDP1: SUCCESS (8.726s) [174.537648] Subtest blocking-absolute-wf_vblank: SUCCESS (34.910s) [175.728948] [031/130] (862s left) kms_sequence (queue-idle) [175.827456] Starting subtest: queue-idle [175.829653] Starting dynamic subtest: pipe-A-eDP-1 [177.644021] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.813s) [177.652002] Starting dynamic subtest: pipe-B-eDP-1 [180.091206] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.446s) [180.101298] Starting dynamic subtest: pipe-C-eDP-1 [182.512276] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.421s) [182.521841] Starting dynamic subtest: pipe-D-eDP-1 [184.929557] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.415s) [184.932029] Subtest queue-idle: SUCCESS (9.099s) [185.737622] [032/130] (852s left) kms_plane_cursor (overlay) [185.822469] Starting subtest: overlay [185.827067] Starting dynamic subtest: pipe-A-eDP-1-size-64 [188.707111] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.882s) [188.766683] Starting dynamic subtest: pipe-A-eDP-1-size-128 [191.608986] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.849s) [191.668299] Starting dynamic subtest: pipe-A-eDP-1-size-256 [194.521106] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.859s) [194.867481] Starting dynamic subtest: pipe-B-eDP-1-size-64 [198.402305] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.544s) [198.461417] Starting dynamic subtest: pipe-B-eDP-1-size-128 [201.358472] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.905s) [201.418305] Starting dynamic subtest: pipe-B-eDP-1-size-256 [204.280278] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.870s) [204.628093] Starting dynamic subtest: pipe-C-eDP-1-size-64 [207.811642] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.192s) [207.849245] Starting dynamic subtest: pipe-C-eDP-1-size-128 [210.323648] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.480s) [210.360972] Starting dynamic subtest: pipe-C-eDP-1-size-256 [212.812539] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.458s) [213.150399] Starting dynamic subtest: pipe-D-eDP-1-size-64 [216.268498] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.126s) [216.307806] Starting dynamic subtest: pipe-D-eDP-1-size-128 [218.758404] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.457s) [218.795907] Starting dynamic subtest: pipe-D-eDP-1-size-256 [221.237249] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.447s) [221.555322] Subtest overlay: SUCCESS (35.726s) [222.343441] [033/130] (816s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt) [222.454556] Starting subtest: fbcpsr-farfromfence-mmap-gtt [222.458843] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s) [222.542098] [034/130] (815s left) kms_frontbuffer_tracking (fbc-2p-rte) [222.650192] Starting subtest: fbc-2p-rte [222.654284] Subtest fbc-2p-rte: SKIP (0.000s) [222.734663] [035/130] (815s left) gen9_exec_parse (bb-start-out) [222.799881] Subtest bb-start-out: SKIP (0.000s) [222.857698] [036/130] (815s left) gem_ctx_param (set-priority-invalid-size) [222.913350] Starting subtest: set-priority-invalid-size [222.915922] Subtest set-priority-invalid-size: SUCCESS (0.000s) [222.971308] [037/130] (815s left) gem_ctx_param (vm) [223.024098] Starting subtest: vm [223.068567] Subtest vm: SUCCESS (0.044s) [223.192878] [038/130] (815s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format) [223.290493] Starting subtest: plane-upscale-20x20-with-pixel-format [223.292520] Starting dynamic subtest: pipe-A [224.340180] Dynamic subtest pipe-A: SUCCESS (1.050s) [224.348279] Starting dynamic subtest: pipe-B [226.443914] Dynamic subtest pipe-B: SUCCESS (2.102s) [226.451972] Starting dynamic subtest: pipe-C [227.630088] Dynamic subtest pipe-C: SUCCESS (1.185s) [227.638598] Starting dynamic subtest: pipe-D [228.754644] Dynamic subtest pipe-D: SUCCESS (1.124s) [228.756962] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.463s) [229.940003] [039/130] (808s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted) [230.005301] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted [230.109770] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.104s) [230.181009] [040/130] (808s left) gem_exec_flush (basic-wb-ro-before-default) [230.247418] Starting subtest: basic-wb-ro-before-default [235.720319] Subtest basic-wb-ro-before-default: SUCCESS (5.474s) [235.906745] [041/130] (802s left) gem_wait (busy) [235.974166] Starting subtest: busy [235.976663] Starting dynamic subtest: all-engines [237.640160] Dynamic subtest all-engines: SUCCESS (1.665s) [237.649505] Starting dynamic subtest: rcs0 [238.183969] Dynamic subtest rcs0: SUCCESS (0.543s) [238.194114] Starting dynamic subtest: bcs0 [238.723269] Dynamic subtest bcs0: SUCCESS (0.539s) [238.733233] Starting dynamic subtest: vcs0 [240.655197] Dynamic subtest vcs0: SUCCESS (1.930s) [240.664297] Starting dynamic subtest: vcs1 [242.632816] Dynamic subtest vcs1: SUCCESS (1.976s) [242.644183] Starting dynamic subtest: vecs0 [244.615492] Dynamic subtest vecs0: SUCCESS (1.981s) [244.626550] Starting dynamic subtest: ccs0 [245.157934] Dynamic subtest ccs0: SUCCESS (0.541s) [245.160699] Subtest busy: SUCCESS (9.185s) [245.352620] [042/130] (793s left) gem_pxp (verify-pxp-stale-buf-execution) [245.470598] Starting subtest: verify-pxp-stale-buf-execution [246.539898] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.070s) [246.673653] [043/130] (792s left) gem_render_copy (y-tiled-ccs-to-y-tiled) [246.739027] Starting subtest: y-tiled-ccs-to-y-tiled [246.743372] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s) [246.922134] [044/130] (791s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-render) [247.025615] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-render [250.274692] Subtest fbc-1p-offscren-pri-shrfb-draw-render: SUCCESS (3.253s) [250.425883] [045/130] (788s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc) [250.524164] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc [250.526011] Starting dynamic subtest: pipe-A-eDP-1 [250.545360] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [250.559455] Starting dynamic subtest: pipe-B-eDP-1 [250.580367] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [250.587901] Starting dynamic subtest: pipe-C-eDP-1 [250.605383] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [250.612949] Starting dynamic subtest: pipe-D-eDP-1 [250.631061] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [250.634620] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s) [252.718528] [046/130] (786s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf) [257.761650] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf [257.766461] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.002s) [258.618791] [047/130] (780s left) gem_exec_reloc (basic-gtt-cpu-active) [258.670438] Subtest basic-gtt-cpu-active: SKIP (0.000s) [258.729597] [048/130] (780s left) kms_chamelium_color (ctm-0-75) [258.830659] Subtest ctm-0-75: SKIP (0.000s) [258.870216] [049/130] (780s left) kms_psr (fbc-psr-primary-render) [258.970686] Starting subtest: fbc-psr-primary-render [258.972913] Starting dynamic subtest: eDP-1 [258.977388] Dynamic subtest eDP-1: SKIP (0.000s) [258.981954] Subtest fbc-psr-primary-render: SKIP (0.001s) [259.035381] [050/130] (779s left) syncobj_eventfd (timeline-wait) [259.097790] Starting subtest: timeline-wait [259.108284] Subtest timeline-wait: SUCCESS (0.011s) [259.175099] [051/130] (779s left) gem_exec_capture (capture-recoverable) [259.228255] Starting subtest: capture-recoverable [259.233318] Subtest capture-recoverable: SUCCESS (0.003s) [259.352935] [052/130] (779s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [259.459327] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s) [259.563800] [053/130] (779s left) gem_ctx_exec (basic-invalid-context) [259.629472] Starting subtest: basic-invalid-context [259.631865] Subtest basic-invalid-context: SUCCESS (0.004s) [259.775350] [054/130] (779s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [259.883325] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [259.885327] Starting dynamic subtest: pipe-A-default-mode [260.171183] Dynamic subtest pipe-A-default-mode: SKIP (0.283s) [260.175894] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.285s) [260.949714] [055/130] (778s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt) [261.071621] Starting subtest: fbc-rgb565-draw-blt [264.255007] Subtest fbc-rgb565-draw-blt: SUCCESS (3.185s) [264.408975] [056/130] (774s left) gem_exec_params (invalid-bsd1-flag-on-blt) [264.474472] Starting subtest: invalid-bsd1-flag-on-blt [264.476487] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s) [264.592740] [057/130] (774s left) gem_exec_whisper (basic-forked-all) [264.666715] Starting subtest: basic-forked-all [276.953510] Subtest basic-forked-all: SUCCESS (12.284s) [277.132006] [058/130] (762s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa [277.189450] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled [277.191296] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s) [277.279140] [059/130] (762s left) prime_mmap (test_correct_cpu_write) [277.343275] Starting subtest: test_correct_cpu_write [277.345559] Starting dynamic subtest: test_correct_cpu_write-smem [277.347483] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s) [277.349419] Subtest test_correct_cpu_write: SUCCESS (0.001s) [277.438303] [060/130] (761s left) syncobj_basic (bad-destroy-pad) [277.487861] Starting subtest: bad-destroy-pad [277.490204] Subtest bad-destroy-pad: SUCCESS (0.000s) [277.596520] [061/130] (761s left) kms_flip (absolute-wf_vblank) [277.710518] Starting subtest: absolute-wf_vblank [277.712817] Starting dynamic subtest: A-eDP1 [286.431029] Dynamic subtest A-eDP1: SUCCESS (8.719s) [286.439838] Starting dynamic subtest: B-eDP1 [295.158099] Dynamic subtest B-eDP1: SUCCESS (8.725s) [295.168884] Starting dynamic subtest: C-eDP1 [303.871101] Dynamic subtest C-eDP1: SUCCESS (8.711s) [303.880309] Starting dynamic subtest: D-eDP1 [312.594609] Dynamic subtest D-eDP1: SUCCESS (8.722s) [312.597094] Subtest absolute-wf_vblank: SUCCESS (34.885s) [313.801290] [062/130] (725s left) kms_psr2_su (page_flip-nv12) [313.860391] Subtest page_flip-NV12: SKIP (0.000s) [313.921212] [063/130] (725s left) kms_lease (lease-get) [314.015217] Starting subtest: lease-get [314.017534] Starting dynamic subtest: pipe-A-eDP-1 [314.019706] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s) [314.030173] Starting dynamic subtest: pipe-B-eDP-1 [314.032498] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [314.040834] Starting dynamic subtest: pipe-C-eDP-1 [314.042641] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [314.050979] Starting dynamic subtest: pipe-D-eDP-1 [314.052862] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [314.054693] Subtest lease-get: SUCCESS (0.013s) [314.130851] [064/130] (725s left) syncobj_wait (multi-wait-submitted-signaled) [314.195345] Starting subtest: multi-wait-submitted-signaled [314.197697] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s) [314.290998] [065/130] (725s left) gem_exec_reloc (basic-write-wc-noreloc) [314.356081] Subtest basic-write-wc-noreloc: SKIP (0.000s) [314.424673] [066/130] (725s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic) [314.554029] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic [314.558560] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s) [314.647168] [067/130] (724s left) kms_psr (psr-sprite-render) [314.757026] Starting subtest: psr-sprite-render [314.758932] Starting dynamic subtest: eDP-1 [316.524974] Dynamic subtest eDP-1: SUCCESS (1.768s) [316.527262] Subtest psr-sprite-render: SUCCESS (1.769s) [317.347302] [068/130] (722s left) kms_plane (plane-panning-top-left) [317.440075] Starting subtest: plane-panning-top-left [317.442310] Starting dynamic subtest: pipe-A [319.091682] Dynamic subtest pipe-A: SUCCESS (1.649s) [319.100548] Starting dynamic subtest: pipe-B [321.345519] Dynamic subtest pipe-B: SUCCESS (2.251s) [321.348122] Subtest plane-panning-top-left: SUCCESS (3.903s) [322.146862] [069/130] (717s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip) [322.251003] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip [323.261496] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.011s) [323.401162] [070/130] (716s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite) [323.517105] Starting subtest: fbcpsr-rgb565-draw-pwrite [328.306456] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (4.791s) [328.435110] [071/130] (711s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area) [333.464875] Starting subtest: fbc-psr2-plane-move-sf-dmg-area [333.466850] Starting dynamic subtest: pipe-A-eDP-1 [334.264491] Dynamic subtest pipe-A-eDP-1: SKIP (0.795s) [334.273748] Starting dynamic subtest: pipe-B-eDP-1 [335.385651] Dynamic subtest pipe-B-eDP-1: SKIP (1.119s) [335.390317] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.918s) [336.561148] [072/130] (703s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-factor-0-25) [336.666527] Starting subtest: planes-downscale-factor-0-25-upscale-factor-0-25 [336.668724] Starting dynamic subtest: pipe-A [336.684123] Dynamic subtest pipe-A: SKIP (0.012s) [336.694798] Starting dynamic subtest: pipe-B [336.706937] Dynamic subtest pipe-B: SKIP (0.021s) [336.715700] Starting dynamic subtest: pipe-C [336.727887] Dynamic subtest pipe-C: SKIP (0.021s) [336.736631] Starting dynamic subtest: pipe-D [336.751496] Dynamic subtest pipe-D: SKIP (0.022s) [336.755549] Subtest planes-downscale-factor-0-25-upscale-factor-0-25: SKIP (0.078s) [336.850622] [073/130] (702s left) gem_exec_schedule (implicit-write-read) [336.922515] Starting subtest: implicit-write-read [336.924723] Starting dynamic subtest: rcs0 [337.021235] Dynamic subtest rcs0: SUCCESS (0.109s) [337.032768] Starting dynamic subtest: vcs0 [337.098476] Dynamic subtest vcs0: SUCCESS (0.076s) [337.110523] Starting dynamic subtest: vcs1 [337.176614] Dynamic subtest vcs1: SUCCESS (0.077s) [337.189255] Starting dynamic subtest: vecs0 [337.252468] Dynamic subtest vecs0: SUCCESS (0.075s) [337.264224] Starting dynamic subtest: ccs0 [337.333566] Dynamic subtest ccs0: SUCCESS (0.080s) [337.335899] Subtest implicit-write-read: SUCCESS (0.422s) [338.724367] [074/130] (701s left) kms_rotation_crc (sprite-rotation-90-pos-100-0) [338.820017] Starting subtest: sprite-rotation-90-pos-100-0 [338.824758] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s) [338.888236] [075/130] (700s left) gem_madvise (dontneed-before-pwrite) [338.921659] Starting subtest: dontneed-before-pwrite [338.955418] Subtest dontneed-before-pwrite: SKIP (0.031s) [339.020513] [076/130] (700s left) kms_big_fb (y-tiled-8bpp-rotate-0) [339.129651] Starting subtest: y-tiled-8bpp-rotate-0 [339.133589] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s) [339.236651] [077/130] (700s left) fbdev (read) [339.281106] Starting subtest: read [339.811581] Subtest read: SUCCESS (0.528s) [339.867551] [078/130] (700s left) kms_chamelium_color (ctm-0-25) [339.960728] Subtest ctm-0-25: SKIP (0.000s) [339.999729] [079/130] (700s left) syncobj_timeline (multi-wait-all-available-submitted-signaled) [340.062494] Starting subtest: multi-wait-all-available-submitted-signaled [340.064949] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s) [340.168898] [080/130] (699s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs) [340.263203] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs [340.265500] Starting dynamic subtest: pipe-A-eDP-1 [340.314174] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.049s) [340.324527] Starting dynamic subtest: pipe-B-eDP-1 [340.363840] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.049s) [340.373319] Starting dynamic subtest: pipe-C-eDP-1 [340.413129] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.048s) [340.423032] Starting dynamic subtest: pipe-D-eDP-1 [340.462063] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.048s) [340.464318] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.199s) [342.606576] [081/130] (697s left) gem_render_copy_redux (normal) [342.658642] Starting subtest: normal [342.993202] Subtest normal: SUCCESS (0.334s) [343.113432] [082/130] (697s left) gem_exec_fence (syncobj-import) [343.570673] Starting subtest: syncobj-import [343.576696] Subtest syncobj-import: SUCCESS (0.005s) [344.673341] [FACT gem_exec_fence (syncobj-import)] new: kernel.kmod_is_loaded.vgem: true [344.676943] [083/130] (695s left) kms_big_fb (yf-tiled-16bpp-rotate-90) [344.796679] Starting subtest: yf-tiled-16bpp-rotate-90 [344.800672] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s) [344.910360] [084/130] (695s left) syncobj_timeline (single-wait-for-submit-available-submitted) [344.971874] Starting subtest: single-wait-for-submit-available-submitted [344.974051] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s) [345.087028] [085/130] (695s left) fbdev (unaligned-write) [345.124670] Starting subtest: unaligned-write [345.220257] Subtest unaligned-write: SUCCESS (0.100s) [345.277147] [086/130] (694s left) gem_mmap_wc (copy) [345.340437] Subtest copy: SKIP (0.000s) [345.395296] [087/130] (694s left) kms_writeback (writeback-fb-id) [345.499571] Subtest writeback-fb-id: SKIP (0.000s) [345.539952] [088/130] (694s left) kms_cursor_edge_walk (128x128-right-edge) [345.644152] Starting subtest: 128x128-right-edge [345.646150] Starting dynamic subtest: pipe-A-eDP-1 [349.061803] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.415s) [349.071822] Starting dynamic subtest: pipe-D-eDP-1 [352.161906] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.098s) [352.164318] Subtest 128x128-right-edge: SUCCESS (6.515s) [352.980571] [089/130] (687s left) gem_mmap (big-bo) [353.043028] Subtest big-bo: SKIP (0.000s) [353.093587] [090/130] (687s left) gem_fence_thrash (bo-write-verify-threaded-none) [353.132204] Starting subtest: bo-write-verify-threaded-none [353.155534] Subtest bo-write-verify-threaded-none: SKIP (0.023s) [353.219861] [091/130] (687s left) gem_exec_schedule (preempt-other) [353.283642] Starting subtest: preempt-other [353.285872] Starting dynamic subtest: rcs0 [353.345881] Dynamic subtest rcs0: SUCCESS (0.069s) [353.357017] Starting dynamic subtest: bcs0 [353.410139] Dynamic subtest bcs0: SUCCESS (0.064s) [353.421949] Starting dynamic subtest: vcs0 [353.472949] Dynamic subtest vcs0: SUCCESS (0.062s) [353.485269] Starting dynamic subtest: vcs1 [353.536649] Dynamic subtest vcs1: SUCCESS (0.063s) [353.549022] Starting dynamic subtest: vecs0 [353.599362] Dynamic subtest vecs0: SUCCESS (0.062s) [353.613175] Starting dynamic subtest: ccs0 [353.661648] Dynamic subtest ccs0: SUCCESS (0.061s) [353.664076] Subtest preempt-other: SUCCESS (0.385s) [354.765267] [092/130] (685s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen) [354.876782] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen [357.889499] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.016s) [358.023778] [093/130] (682s left) gem_pread (bench) [358.083327] Subtest bench: SKIP (0.000s) [358.141776] [094/130] (682s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip) [358.239125] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip [358.242687] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s) [358.337069] [095/130] (682s left) gem_exec_schedule (u-submit-golden-slice) [358.410097] Starting subtest: u-submit-golden-slice [358.412053] Starting dynamic subtest: rcs0 [358.437010] Dynamic subtest rcs0: SUCCESS (0.031s) [358.446300] Starting dynamic subtest: bcs0 [358.462913] Dynamic subtest bcs0: SUCCESS (0.025s) [358.471802] Starting dynamic subtest: vcs0 [358.492963] Dynamic subtest vcs0: SUCCESS (0.029s) [358.501733] Starting dynamic subtest: vcs1 [358.522932] Dynamic subtest vcs1: SUCCESS (0.029s) [358.531643] Starting dynamic subtest: vecs0 [358.549284] Dynamic subtest vecs0: SUCCESS (0.026s) [358.559346] Starting dynamic subtest: ccs0 [358.581078] Dynamic subtest ccs0: SUCCESS (0.031s) [358.582927] Subtest u-submit-golden-slice: SUCCESS (0.175s) [359.703499] [096/130] (680s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff) [359.815467] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff [359.819521] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s) [359.904196] [097/130] (680s left) gem_reg_read (timestamp-monotonic) [359.957560] Starting subtest: timestamp-monotonic [364.844493] Subtest timestamp-monotonic: SUCCESS (4.887s) [364.913232] [098/130] (675s left) gem_softpin (evict-prime-sanity-check) [364.975857] Starting subtest: evict-prime-sanity-check [364.978196] Starting dynamic subtest: rcs0 [366.172496] Dynamic subtest rcs0: SUCCESS (1.196s) [366.189551] Starting dynamic subtest: bcs0 [367.316450] Dynamic subtest bcs0: SUCCESS (1.142s) [367.328447] Starting dynamic subtest: vcs0 [368.601884] Dynamic subtest vcs0: SUCCESS (1.282s) [368.614225] Starting dynamic subtest: vcs1 [370.677143] Dynamic subtest vcs1: SUCCESS (2.052s) [370.688874] Starting dynamic subtest: vecs0 [372.639156] Dynamic subtest vecs0: SUCCESS (1.981s) [372.651393] Starting dynamic subtest: ccs0 [373.747530] Dynamic subtest ccs0: SUCCESS (1.108s) [373.757864] Starting dynamic subtest: all [374.894329] Dynamic subtest all: SUCCESS (1.146s) [374.896835] Subtest evict-prime-sanity-check: SUCCESS (9.919s) [374.971595] [099/130] (665s left) syncobj_wait (wait-any-snapshot) [375.031041] Starting subtest: wait-any-snapshot [375.111042] Subtest wait-any-snapshot: SUCCESS (0.082s) [375.185127] [100/130] (665s left) device_reset (unbind-reset-rebind) [375.232109] Starting subtest: unbind-reset-rebind [379.852678] Subtest unbind-reset-rebind: SUCCESS (4.615s) [379.888408] [101/130] (660s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c) [379.952695] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s) [380.009414] [102/130] (660s left) api_intel_allocator (reopen) [380.063252] Starting subtest: reopen [380.068176] Subtest reopen: SUCCESS (0.006s) [380.202003] [103/130] (660s left) gem_mmap_wc (bad-object) [380.248923] Subtest bad-object: SKIP (0.000s) [380.310940] [104/130] (660s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [380.423213] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip [381.276133] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.855s) [381.499595] [105/130] (659s left) gem_tiled_fence_blits (basic) [381.569082] Subtest basic: SKIP (0.000s) [381.637037] [106/130] (659s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [381.741444] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [381.745830] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [381.837864] [107/130] (659s left) kms_joiner (basic-force-ultra-joiner) [381.947993] Starting subtest: basic-force-ultra-joiner [381.952487] Subtest basic-force-ultra-joiner: SKIP (0.000s) [382.009272] [108/130] (659s left) kms_vblank (query-forked-busy) [382.106265] Starting subtest: query-forked-busy [382.108557] Starting dynamic subtest: pipe-A-eDP-1 [384.006675] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.899s) [384.020025] Starting dynamic subtest: pipe-D-eDP-1 [386.546015] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.536s) [386.548252] Subtest query-forked-busy: SUCCESS (4.439s) [387.347075] [109/130] (653s left) kms_fbc_dirty_rect (fbc-dirty-rectangle-different-formats) [387.684094] Subtest fbc-dirty-rectangle-different-formats: SKIP (0.000s) [387.732976] [FACT kms_fbc_dirty_rect (fbc-dirty-rectangle-different-formats)] new: kernel.kmod_is_loaded.xe: true [387.736527] [110/130] (653s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu) [387.850938] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu [392.418209] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (4.569s) [392.560880] [111/130] (648s left) kms_pm_rpm (basic-pci-d3-state) [394.715763] Starting subtest: basic-pci-d3-state [396.186997] Subtest basic-pci-d3-state: SUCCESS (1.473s) [396.274242] [112/130] (644s left) kms_psr (pr-sprite-plane-move) [396.380536] Starting subtest: pr-sprite-plane-move [396.384726] Subtest pr-sprite-plane-move: SKIP (0.000s) [396.446458] [113/130] (644s left) kms_chamelium_hpd (hdmi-hpd) [396.831144] Subtest hdmi-hpd: SKIP (0.000s) [397.643391] [114/130] (643s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite) [397.746710] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite [397.751015] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [397.846826] [115/130] (643s left) prime_busy (after) [397.909855] Starting subtest: after [397.915108] Starting dynamic subtest: rcs0 [397.949684] Dynamic subtest rcs0: SUCCESS (0.036s) [397.961131] Starting dynamic subtest: bcs0 [398.011997] Dynamic subtest bcs0: SUCCESS (0.062s) [398.023055] Starting dynamic subtest: vcs0 [398.052232] Dynamic subtest vcs0: SUCCESS (0.039s) [398.061406] Starting dynamic subtest: vcs1 [398.092780] Dynamic subtest vcs1: SUCCESS (0.040s) [398.102238] Starting dynamic subtest: vecs0 [398.134384] Dynamic subtest vecs0: SUCCESS (0.041s) [398.143784] Starting dynamic subtest: ccs0 [398.174604] Dynamic subtest ccs0: SUCCESS (0.039s) [398.284271] Subtest after: SUCCESS (0.372s) [398.356531] [116/130] (643s left) gem_gpgpu_fill (basic) [398.408700] Starting subtest: basic [398.411122] Starting dynamic subtest: smem [398.413552] Dynamic subtest smem: SUCCESS (0.007s) [398.415979] Subtest basic: SUCCESS (0.007s) [398.516336] [117/130] (642s left) gem_exec_balancer (bonded-chain) [398.585176] Starting subtest: bonded-chain [405.445413] Subtest bonded-chain: SUCCESS (6.861s) [406.638363] [118/130] (634s left) kms_cursor_crc (cursor-rapid-movement-max-size) [406.883572] Starting subtest: cursor-rapid-movement-max-size [406.890500] Subtest cursor-rapid-movement-max-size: SKIP (0.000s) [407.028267] [119/130] (634s left) i915_hangman (gt-error-state-capture) [407.097021] Starting subtest: gt-error-state-capture [407.099411] Starting dynamic subtest: rcs0 [418.599559] Dynamic subtest rcs0: SUCCESS (11.502s) [418.612038] Starting dynamic subtest: bcs0 [430.575238] Dynamic subtest bcs0: SUCCESS (11.964s) [430.587535] Starting dynamic subtest: vcs0 [442.536993] Dynamic subtest vcs0: SUCCESS (11.969s) [442.549207] Starting dynamic subtest: vcs1 [453.543662] Dynamic subtest vcs1: SUCCESS (11.004s) [453.555205] Starting dynamic subtest: vecs0 [465.512489] Dynamic subtest vecs0: SUCCESS (11.965s) [465.525139] Starting dynamic subtest: ccs0 [476.584910] Dynamic subtest ccs0: SUCCESS (11.069s) [476.587523] Subtest gt-error-state-capture: SUCCESS (69.485s) [476.660911] [120/130] (564s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode) [477.051196] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s) [477.863247] [121/130] (563s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render) [477.968039] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render [477.972551] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [478.066334] [122/130] (563s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move) [478.174784] Starting subtest: psr-2p-scndscrn-spr-indfb-move [478.179048] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [478.269600] [123/130] (563s left) gem_exec_balancer (nop) [478.349161] Starting subtest: nop [524.875419] Subtest nop: SUCCESS (46.522s) [525.188414] [124/130] (516s left) i915_pm_rpm (reg-read-ioctl) [526.733202] Starting subtest: reg-read-ioctl [529.038029] Subtest reg-read-ioctl: SUCCESS (2.305s) [529.392721] [125/130] (512s left) kms_psr (fbc-pr-sprite-plane-move) [529.497948] Starting subtest: fbc-pr-sprite-plane-move [529.502705] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s) [529.566960] [126/130] (512s left) gem_media_fill (media-fill) [529.635737] Subtest media-fill: SKIP (0.000s) [529.734265] [127/130] (512s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [529.846199] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc [529.850191] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [529.940845] [128/130] (511s left) gem_exec_params (secure-non-root) [530.001763] Starting subtest: secure-non-root [530.006475] Subtest secure-non-root: SKIP (0.000s) [530.091734] [129/130] (511s left) sysfs_heartbeat_interval (nopreempt) [530.147527] Starting subtest: nopreempt [530.149932] Starting dynamic subtest: vecs0 [539.619661] Dynamic subtest vecs0: SUCCESS (9.470s) [539.629815] Aborting: Kernel badly tainted (0x240, 0x200) (check dmesg for details): TAINT_WARN: WARN_ON has happened. [539.635695] Starting dynamic subtest: ccs0 [543.625493] Dynamic subtest ccs0: SUCCESS (4.004s) [543.628115] Starting dynamic subtest: vcs0 [553.640224] Dynamic subtest vcs0: SUCCESS (10.009s) [553.642592] Starting dynamic subtest: bcs0 [557.552378] Dynamic subtest bcs0: SUCCESS (3.910s) [557.555059] Starting dynamic subtest: rcs0 [563.382302] Dynamic subtest rcs0: SUCCESS (5.829s) [563.385009] Starting dynamic subtest: vcs1 [572.638583] Dynamic subtest vcs1: SUCCESS (9.256s) [572.641145] Subtest nopreempt: SUCCESS (42.488s) [572.702355] [FACT sysfs_heartbeat_interval (nopreempt)] new: kernel.is_tainted.taint_warn: true [572.703286] Closing watchdogs Done.