[62.633457] Initializing watchdogs [62.633505] /dev/watchdog0 [62.650521] [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.654673] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [62.657023] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [62.660455] [001/132] (960s left) syncobj_timeline (wait-for-submit-snapshot) [62.749927] Starting subtest: wait-for-submit-snapshot [62.832673] Subtest wait-for-submit-snapshot: SUCCESS (0.082s) [62.935140] [002/132] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs) [63.027872] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs [63.030285] Starting dynamic subtest: pipe-A-eDP-1 [63.302745] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.241s) [63.311927] Starting dynamic subtest: pipe-B-eDP-1 [64.559292] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.248s) [64.568705] Starting dynamic subtest: pipe-C-eDP-1 [65.787482] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.223s) [65.796162] Starting dynamic subtest: pipe-D-eDP-1 [67.017976] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.229s) [67.020067] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.946s) [68.454067] [003/132] (954s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt) [68.553814] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt [72.707093] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.147s) [73.873525] [004/132] (949s left) kms_big_fb (linear-8bpp-rotate-270) [73.976492] Starting subtest: linear-8bpp-rotate-270 [73.997802] Subtest linear-8bpp-rotate-270: SKIP (0.021s) [74.155479] [005/132] (949s left) kms_busy (extended-modeset-hang-newfb) [74.268646] Starting subtest: extended-modeset-hang-newfb [74.270930] Starting dynamic subtest: pipe-A [108.828725] Dynamic subtest pipe-A: SUCCESS (34.537s) [108.838165] Starting dynamic subtest: pipe-D [131.799552] Dynamic subtest pipe-D: SUCCESS (22.968s) [131.801980] Subtest extended-modeset-hang-newfb: SUCCESS (57.509s) [132.695718] [006/132] (890s left) perf (oa-exponents) [132.831520] Starting subtest: oa-exponents [132.833904] Starting dynamic subtest: 0-rcs0 [134.610288] Dynamic subtest 0-rcs0: SUCCESS (1.778s) [134.621499] Starting dynamic subtest: 1-vcs0 [136.440065] Dynamic subtest 1-vcs0: SUCCESS (1.830s) [136.442757] Subtest oa-exponents: SUCCESS (3.608s) [136.485281] [007/132] (886s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [136.589661] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [137.656285] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.066s) [137.866941] [008/132] (885s left) kms_pipe_crc_basic (suspend-read-crc) [137.976211] Starting subtest: suspend-read-crc [137.977886] Starting dynamic subtest: pipe-A-eDP-1 [154.945349] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.253s) [154.955548] Starting dynamic subtest: pipe-B-eDP-1 [172.946669] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.083s) [172.956413] Starting dynamic subtest: pipe-C-eDP-1 [190.898463] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.986s) [190.908305] Starting dynamic subtest: pipe-D-eDP-1 [208.890014] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.005s) [208.892723] Subtest suspend-read-crc: SUCCESS (15.332s) [209.800005] [009/132] (813s left) perf_pmu (interrupts-sync) [209.849594] Starting subtest: interrupts-sync [211.001599] Subtest interrupts-sync: SUCCESS (1.114s) [211.149883] [FACT perf_pmu (interrupts-sync)] new: kernel.kmod_is_loaded.vgem: true [211.153150] [010/132] (812s left) gem_exec_reloc (basic-gtt-active) [211.218253] Subtest basic-gtt-active: SKIP (0.000s) [211.301273] [011/132] (812s left) kms_flip (modeset-vs-vblank-race) [211.397384] Starting subtest: modeset-vs-vblank-race [211.399811] Starting dynamic subtest: A-eDP1 [216.157225] Dynamic subtest A-eDP1: SUCCESS (4.658s) [216.171809] Starting dynamic subtest: B-eDP1 [220.929301] Dynamic subtest B-eDP1: SUCCESS (4.715s) [220.940064] Starting dynamic subtest: C-eDP1 [225.649327] Dynamic subtest C-eDP1: SUCCESS (4.688s) [225.659342] Starting dynamic subtest: D-eDP1 [230.296525] Dynamic subtest D-eDP1: SUCCESS (4.629s) [230.298914] Subtest modeset-vs-vblank-race: SUCCESS (18.699s) [231.590797] [012/132] (792s left) gem_sync (basic-each) [231.674067] Starting subtest: basic-each [233.938694] Subtest basic-each: SUCCESS (2.271s) [234.149848] [013/132] (789s left) gem_ctx_persistence (legacy-engines-mixed) [234.210135] Starting subtest: legacy-engines-mixed [234.212504] Starting dynamic subtest: render [234.338913] Dynamic subtest render: SUCCESS (0.128s) [234.349968] Starting dynamic subtest: bsd1 [234.391276] Dynamic subtest bsd1: SUCCESS (0.051s) [234.401698] Starting dynamic subtest: bsd2 [234.466641] Dynamic subtest bsd2: SUCCESS (0.073s) [234.477420] Starting dynamic subtest: blt [234.557746] Dynamic subtest blt: SUCCESS (0.089s) [234.568359] Starting dynamic subtest: vebox [234.639804] Dynamic subtest vebox: SUCCESS (0.079s) [234.642439] Subtest legacy-engines-mixed: SUCCESS (0.430s) [234.778806] [014/132] (788s left) kms_plane (plane-panning-bottom-right) [234.887865] Starting subtest: plane-panning-bottom-right [234.889872] Starting dynamic subtest: pipe-A [236.541665] Dynamic subtest pipe-A: SUCCESS (1.651s) [236.551107] Starting dynamic subtest: pipe-B [238.789124] Dynamic subtest pipe-B: SUCCESS (2.243s) [238.791300] Subtest plane-panning-bottom-right: SUCCESS (3.896s) [239.639817] [015/132] (784s left) prime_vgem (wait) [239.738989] Starting subtest: wait [239.741452] Starting dynamic subtest: rcs0 [239.791216] Dynamic subtest rcs0: SUCCESS (0.052s) [239.799433] Starting dynamic subtest: bcs0 [239.869084] Dynamic subtest bcs0: SUCCESS (0.077s) [239.877516] Starting dynamic subtest: vcs0 [239.916136] Dynamic subtest vcs0: SUCCESS (0.046s) [239.924112] Starting dynamic subtest: vcs1 [239.960711] Dynamic subtest vcs1: SUCCESS (0.044s) [239.968742] Starting dynamic subtest: vecs0 [240.006816] Dynamic subtest vecs0: SUCCESS (0.046s) [240.015045] Starting dynamic subtest: ccs0 [240.052602] Dynamic subtest ccs0: SUCCESS (0.045s) [240.054843] Subtest wait: SUCCESS (0.314s) [240.211908] [016/132] (783s left) i915_query (query-topology-matches-eu-total) [240.282353] Starting subtest: query-topology-matches-eu-total [240.284687] Subtest query-topology-matches-eu-total: SUCCESS (0.000s) [240.415177] [017/132] (783s left) syncobj_timeline (reset-signaled) [240.468740] Starting subtest: reset-signaled [240.471049] Subtest reset-signaled: SUCCESS (0.000s) [240.596306] [018/132] (783s left) syncobj_timeline (multi-wait-all-available-signaled) [240.648071] Starting subtest: multi-wait-all-available-signaled [240.649939] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s) [240.828956] [019/132] (783s left) kms_cursor_crc (cursor-random-256x256) [241.049871] Starting subtest: cursor-random-256x256 [241.051687] Starting dynamic subtest: pipe-A-eDP-1 [244.372463] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.318s) [244.396966] Starting dynamic subtest: pipe-D-eDP-1 [248.418294] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.025s) [248.420439] Subtest cursor-random-256x256: SUCCESS (7.367s) [249.300169] [020/132] (774s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs) [249.368068] Starting subtest: mixed-tiled-to-y-tiled-ccs [249.372400] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s) [249.576455] [021/132] (774s left) kms_rotation_crc (primary-y-tiled-reflect-x-180) [249.675334] Starting subtest: primary-y-tiled-reflect-x-180 [249.735070] Subtest primary-y-tiled-reflect-x-180: SKIP (0.060s) [249.859899] [022/132] (774s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs) [249.922858] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs [249.927193] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s) [250.131262] [023/132] (774s left) gem_exec_schedule (reorder-wide) [250.314069] Subtest reorder-wide: SKIP (0.000s) [250.514445] [024/132] (773s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary) [250.633902] Starting subtest: fbc-indfb-scaledprimary [253.595574] Subtest fbc-indfb-scaledprimary: SUCCESS (2.966s) [254.817306] [025/132] (769s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen) [254.919310] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen [261.441444] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.525s) [261.625530] [026/132] (762s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs) [261.723047] Starting subtest: crc-primary-suspend-yf-tiled-ccs [261.725366] Starting dynamic subtest: pipe-A-eDP-1 [261.734457] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [261.743778] Starting dynamic subtest: pipe-B-eDP-1 [261.752717] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [261.760688] Starting dynamic subtest: pipe-C-eDP-1 [261.769110] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [261.776883] Starting dynamic subtest: pipe-D-eDP-1 [261.785088] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [261.789196] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.004s) [263.908861] [027/132] (760s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf) [268.879808] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf [268.884704] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s) [269.740299] [028/132] (754s left) kms_cursor_crc (cursor-random-64x21) [269.936711] Starting subtest: cursor-random-64x21 [269.981973] Subtest cursor-random-64x21: SKIP (0.040s) [270.255495] [029/132] (754s left) prime_mmap (test_dup) [270.305149] Starting subtest: test_dup [270.307054] Starting dynamic subtest: test_dup-smem [270.348007] Dynamic subtest test_dup-smem: SUCCESS (0.045s) [270.350473] Subtest test_dup: SUCCESS (0.046s) [270.443879] [030/132] (754s left) gem_exec_schedule (u-submit-late-slice) [270.515241] Starting subtest: u-submit-late-slice [270.517101] Starting dynamic subtest: rcs0 [270.541086] Dynamic subtest rcs0: SUCCESS (0.033s) [270.549496] Starting dynamic subtest: bcs0 [270.567864] Dynamic subtest bcs0: SUCCESS (0.026s) [270.576372] Starting dynamic subtest: vcs0 [270.595944] Dynamic subtest vcs0: SUCCESS (0.027s) [270.604218] Starting dynamic subtest: vcs1 [270.624741] Dynamic subtest vcs1: SUCCESS (0.028s) [270.633084] Starting dynamic subtest: vecs0 [270.651366] Dynamic subtest vecs0: SUCCESS (0.026s) [270.659364] Starting dynamic subtest: ccs0 [270.680507] Dynamic subtest ccs0: SUCCESS (0.029s) [270.682397] Subtest u-submit-late-slice: SUCCESS (0.174s) [272.286539] [031/132] (752s left) syncobj_timeline (etime-multi-wait-all-submitted) [272.354659] Starting subtest: etime-multi-wait-all-submitted [272.459806] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s) [272.567134] [032/132] (751s left) fbdev (write) [272.611891] Starting subtest: write [272.971498] Subtest write: SUCCESS (0.358s) [273.060135] [033/132] (751s left) syncobj_wait (invalid-signal-zero-handles) [273.114258] Starting subtest: invalid-signal-zero-handles [273.116730] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [273.250992] [034/132] (751s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite) [273.348338] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite [273.352395] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [273.469433] [035/132] (751s left) gem_exec_balancer (sliced) [273.540489] Starting subtest: sliced [273.544942] Subtest sliced: SKIP (0.000s) [273.865795] [036/132] (750s left) gem_ccs (large-ctrl-surf-copy) [273.926471] Starting subtest: large-ctrl-surf-copy [273.931466] Subtest large-ctrl-surf-copy: SKIP (0.000s) [274.078565] [037/132] (750s left) gem_fenced_exec_thrash (2-spare-fences) [274.140315] Subtest 2-spare-fences: SKIP (0.000s) [274.235509] [038/132] (750s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [274.358411] Starting subtest: basic-busy-flip-before-cursor-varying-size [274.362992] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [274.527135] [039/132] (750s left) kms_big_fb (4-tiled-8bpp-rotate-0) [274.624117] Starting subtest: 4-tiled-8bpp-rotate-0 [275.751120] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.126s) [275.902410] [040/132] (748s left) gem_mmap_wc (copy) [275.965754] Subtest copy: SKIP (0.000s) [276.026341] [041/132] (748s left) kms_cursor_legacy (cursor-vs-flip-toggle) [276.148839] Starting subtest: cursor-vs-flip-toggle [306.755828] Subtest cursor-vs-flip-toggle: SUCCESS (30.598s) [307.577355] [042/132] (717s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [307.677702] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip [307.681894] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [307.787577] [043/132] (717s left) kms_addfb_basic (unused-handle) [307.843576] Starting subtest: unused-handle [307.845560] Subtest unused-handle: SUCCESS (0.000s) [308.006422] [044/132] (717s left) gem_ctx_shared (q-smoketest-all) [308.072797] Starting subtest: Q-smoketest-all [340.446748] Subtest Q-smoketest-all: SUCCESS (32.326s) [342.237606] [045/132] (682s left) kms_flip (2x-flip-vs-expired-vblank) [342.356670] Starting subtest: 2x-flip-vs-expired-vblank [342.364696] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s) [342.467398] [046/132] (682s left) kms_force_connector_basic (force-edid) [342.533993] Starting subtest: force-edid [342.850678] Subtest force-edid: SUCCESS (0.317s) [343.021952] [047/132] (682s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [343.117830] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [344.071226] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.955s) [344.222650] [048/132] (680s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5) [344.328935] Starting subtest: planes-unity-scaling-downscale-factor-0-5 [344.330684] Starting dynamic subtest: pipe-A [344.350783] Dynamic subtest pipe-A: SKIP (0.017s) [344.358745] Starting dynamic subtest: pipe-B [344.400218] Dynamic subtest pipe-B: SKIP (0.050s) [344.407861] Starting dynamic subtest: pipe-C [344.434569] Dynamic subtest pipe-C: SKIP (0.032s) [344.444664] Starting dynamic subtest: pipe-D [344.464745] Dynamic subtest pipe-D: SKIP (0.031s) [344.468208] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.134s) [344.582599] [049/132] (680s left) kms_chamelium_audio (hdmi-audio) [344.971793] Subtest hdmi-audio: SKIP (0.000s) [345.750134] [050/132] (679s left) gem_set_tiling_vs_gtt [345.886242] [051/132] (679s left) gem_lmem_swapping (massive) [345.922879] Subtest massive: SKIP (0.000s) [345.965468] [052/132] (679s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render) [346.066289] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render [352.685380] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.620s) [352.810599] [053/132] (672s left) prime_busy (after) [352.883202] Starting subtest: after [352.888348] Starting dynamic subtest: rcs0 [352.938571] Dynamic subtest rcs0: SUCCESS (0.050s) [352.949867] Starting dynamic subtest: bcs0 [353.015590] Dynamic subtest bcs0: SUCCESS (0.076s) [353.026207] Starting dynamic subtest: vcs0 [353.057492] Dynamic subtest vcs0: SUCCESS (0.042s) [353.072583] Starting dynamic subtest: vcs1 [353.100464] Dynamic subtest vcs1: SUCCESS (0.042s) [353.111074] Starting dynamic subtest: vecs0 [353.141511] Dynamic subtest vecs0: SUCCESS (0.040s) [353.151734] Starting dynamic subtest: ccs0 [353.182328] Dynamic subtest ccs0: SUCCESS (0.040s) [353.290234] Subtest after: SUCCESS (0.405s) [353.393882] [054/132] (672s left) syncobj_wait (invalid-signal-illegal-handle) [353.453212] Starting subtest: invalid-signal-illegal-handle [353.455592] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [353.577148] [055/132] (671s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [353.673300] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [353.675539] Starting dynamic subtest: pipe-A-default-mode [353.959163] Dynamic subtest pipe-A-default-mode: SKIP (0.284s) [353.963509] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.285s) [354.801298] [056/132] (670s left) gem_gtt_cpu_tlb [354.935429] [057/132] (670s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [355.029951] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt [355.034102] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [355.136898] [058/132] (670s left) gen9_exec_parse (batch-zero-length) [355.183107] Subtest batch-zero-length: SKIP (0.000s) [355.275956] [059/132] (670s left) gem_softpin (evict-snoop-interruptible) [355.336884] Starting subtest: evict-snoop-interruptible [355.341199] Subtest evict-snoop-interruptible: SKIP (0.000s) [355.494627] [060/132] (670s left) dmabuf_sync_file (export-basic) [355.538521] Starting subtest: export-basic [355.540691] Subtest export-basic: SUCCESS (0.001s) [355.619288] [061/132] (670s left) kms_dither (fb-8bpc-vs-panel-6bpc) [355.716156] Starting subtest: fb-8bpc-vs-panel-6bpc [355.718059] Starting dynamic subtest: pipe-A-eDP-1 [357.107947] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.391s) [357.110292] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.393s) [357.993537] [062/132] (667s left) syncobj_timeline (host-signal-ordered) [358.065554] Starting subtest: host-signal-ordered [358.068079] Subtest host-signal-ordered: SUCCESS (0.000s) [358.213797] [063/132] (667s left) kms_chamelium_hpd (common-hpd-after-suspend) [358.598532] Subtest common-hpd-after-suspend: SKIP (0.000s) [359.451806] [064/132] (666s left) kms_flip (2x-flip-vs-fences) [359.548258] Starting subtest: 2x-flip-vs-fences [359.552487] Subtest 2x-flip-vs-fences: SKIP (0.000s) [359.687592] [065/132] (666s left) perf (mi-rpc) [359.796891] Starting subtest: mi-rpc [359.801470] Subtest mi-rpc: SKIP (0.000s) [359.840623] [066/132] (666s left) sw_sync (sync_random_merge) [359.877596] Starting subtest: sync_random_merge [359.912442] Subtest sync_random_merge: SUCCESS (0.038s) [359.998401] [067/132] (666s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite) [360.101021] Starting subtest: psr-rgb101010-draw-pwrite [367.700932] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.601s) [367.829271] [068/132] (658s left) kms_getfb (getfb2-handle-closed) [367.930892] Starting subtest: getfb2-handle-closed [367.933090] Subtest getfb2-handle-closed: SUCCESS (0.000s) [368.074910] [069/132] (658s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt) [368.173743] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt [368.177792] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [368.293837] [070/132] (657s left) kms_addfb_basic (invalid-smem-bo-on-discrete) [368.349577] Starting subtest: invalid-smem-bo-on-discrete [368.353803] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s) [368.517203] [071/132] (657s left) perf (gen12-invalid-class-instance) [368.612607] Starting subtest: gen12-invalid-class-instance [368.623573] Subtest gen12-invalid-class-instance: SUCCESS (0.009s) [368.658296] [072/132] (657s left) gem_exec_schedule (implicit-read-write) [368.730759] Starting subtest: implicit-read-write [368.732508] Starting dynamic subtest: rcs0 [368.861870] Dynamic subtest rcs0: SUCCESS (0.139s) [368.872931] Starting dynamic subtest: vcs0 [368.942957] Dynamic subtest vcs0: SUCCESS (0.080s) [368.954330] Starting dynamic subtest: vcs1 [369.019105] Dynamic subtest vcs1: SUCCESS (0.075s) [369.029701] Starting dynamic subtest: vecs0 [369.101490] Dynamic subtest vecs0: SUCCESS (0.082s) [369.112114] Starting dynamic subtest: ccs0 [369.181116] Dynamic subtest ccs0: SUCCESS (0.078s) [369.183076] Subtest implicit-read-write: SUCCESS (0.459s) [370.255537] [073/132] (655s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [370.346430] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s) [370.479446] [074/132] (655s left) kms_atomic (plane-cursor-legacy) [370.587032] Starting subtest: plane-cursor-legacy [370.589254] Starting dynamic subtest: pipe-A-eDP-1 [370.951132] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.365s) [370.953453] Subtest plane-cursor-legacy: SUCCESS (0.367s) [371.795533] [075/132] (654s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc) [371.910983] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc [371.913267] Starting dynamic subtest: pipe-A-eDP-1 [371.935855] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [371.945776] Starting dynamic subtest: pipe-B-eDP-1 [371.966695] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [371.974965] Starting dynamic subtest: pipe-C-eDP-1 [371.996305] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [372.005276] Starting dynamic subtest: pipe-D-eDP-1 [372.028363] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [372.032554] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s) [374.198563] [076/132] (652s left) gem_exec_balancer (bonded-pair) [374.272778] Starting subtest: bonded-pair [374.277427] Subtest bonded-pair: SKIP (0.000s) [374.643127] [077/132] (651s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu) [374.749280] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu [374.753687] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [374.893376] [078/132] (651s left) gem_ctx_exec (basic-invalid-context) [374.954059] Starting subtest: basic-invalid-context [374.958931] Subtest basic-invalid-context: SUCCESS (0.005s) [375.123078] [079/132] (651s left) kms_content_protection (lic-type-0-hdcp14) [375.285435] Starting subtest: lic-type-0-hdcp14 [375.289936] Subtest lic-type-0-hdcp14: SKIP (0.000s) [375.453504] [080/132] (651s left) gem_eio (wait-immediate) [375.690865] Starting subtest: wait-immediate [376.078709] Subtest wait-immediate: SUCCESS (0.387s) [376.255519] [081/132] (650s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible) [376.363811] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible [376.375564] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.010s) [376.470221] [082/132] (650s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling) [376.569425] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling [376.571196] Starting dynamic subtest: pipe-A-default-mode [377.702681] Dynamic subtest pipe-A-default-mode: SKIP (1.129s) [377.706977] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.130s) [377.884597] [083/132] (648s left) kms_psr (psr2-basic) [377.980065] Starting subtest: psr2-basic [377.982357] Starting dynamic subtest: eDP-1 [379.625785] Dynamic subtest eDP-1: SUCCESS (1.646s) [379.628034] Subtest psr2-basic: SUCCESS (1.648s) [380.487870] [084/132] (646s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs) [380.583734] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs [380.585852] Starting dynamic subtest: pipe-A-eDP-1 [380.607472] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [380.617649] Starting dynamic subtest: pipe-B-eDP-1 [380.638253] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [380.646548] Starting dynamic subtest: pipe-C-eDP-1 [380.667260] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [380.675523] Starting dynamic subtest: pipe-D-eDP-1 [380.697200] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [380.701211] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.005s) [382.893197] [085/132] (643s left) kms_vblank (query-forked-busy) [382.993581] Starting subtest: query-forked-busy [382.995776] Starting dynamic subtest: pipe-A-eDP-1 [384.870891] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.874s) [384.887207] Starting dynamic subtest: pipe-D-eDP-1 [387.397883] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.525s) [387.399801] Subtest query-forked-busy: SUCCESS (4.403s) [388.308796] [086/132] (638s left) kms_pm_dc (dc9-dpms) [389.415278] Starting subtest: dc9-dpms [390.821974] Subtest dc9-dpms: SUCCESS (1.409s) [390.923263] [087/132] (635s left) gem_userptr_blits (set-cache-level) [390.998415] Starting subtest: set-cache-level [391.003086] Subtest set-cache-level: SKIP (0.000s) [391.158961] [088/132] (635s left) gem_exec_fence (keep-in-fence) [391.337370] Starting subtest: keep-in-fence [391.339848] Starting dynamic subtest: rcs0 [391.391633] Dynamic subtest rcs0: SUCCESS (0.054s) [391.402881] Starting dynamic subtest: bcs0 [391.446024] Dynamic subtest bcs0: SUCCESS (0.052s) [391.458039] Starting dynamic subtest: vcs0 [393.177103] Dynamic subtest vcs0: SUCCESS (1.727s) [393.187642] Starting dynamic subtest: vcs1 [395.161344] Dynamic subtest vcs1: SUCCESS (1.981s) [395.173514] Starting dynamic subtest: vecs0 [397.146342] Dynamic subtest vecs0: SUCCESS (1.982s) [397.158169] Starting dynamic subtest: ccs0 [397.379064] Dynamic subtest ccs0: SUCCESS (0.231s) [397.382000] Subtest keep-in-fence: SUCCESS (6.041s) [399.220022] [089/132] (627s left) kms_pm_rpm (modeset-pc8-residency-stress) [401.260378] Starting subtest: modeset-pc8-residency-stress [401.265393] Subtest modeset-pc8-residency-stress: SKIP (0.000s) [401.649382] [090/132] (625s left) kms_psr (fbc-psr2-sprite-plane-move) [401.747093] Starting subtest: fbc-psr2-sprite-plane-move [401.749364] Starting dynamic subtest: eDP-1 [401.753448] Dynamic subtest eDP-1: SKIP (0.000s) [401.757602] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s) [401.853544] [091/132] (625s left) gem_exec_create (forked) [401.921705] Starting subtest: forked [401.924124] Starting dynamic subtest: smem [423.129387] Dynamic subtest smem: SUCCESS (21.201s) [423.132070] Subtest forked: SUCCESS (21.205s) [423.340048] [092/132] (603s left) kms_psr (pr-cursor-mmap-cpu) [423.441193] Starting subtest: pr-cursor-mmap-cpu [423.445779] Subtest pr-cursor-mmap-cpu: SKIP (0.000s) [423.539065] [093/132] (603s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [423.636518] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [423.640632] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [423.786731] [094/132] (603s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb) [428.873099] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb [428.877776] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s) [429.713892] [095/132] (597s left) gem_exec_reloc (basic-wc-read) [429.788910] Subtest basic-wc-read: SKIP (0.000s) [429.870672] [096/132] (597s left) kms_psr (psr-cursor-plane-move) [429.973702] Starting subtest: psr-cursor-plane-move [429.975797] Starting dynamic subtest: eDP-1 [431.648695] Dynamic subtest eDP-1: SUCCESS (1.673s) [431.650967] Subtest psr-cursor-plane-move: SUCCESS (1.674s) [432.507057] [097/132] (594s left) kms_flip (basic-plain-flip) [432.603761] Starting subtest: basic-plain-flip [432.605672] Starting dynamic subtest: A-eDP1 [434.259396] Dynamic subtest A-eDP1: SUCCESS (1.657s) [434.270472] Starting dynamic subtest: B-eDP1 [435.960198] Dynamic subtest B-eDP1: SUCCESS (1.700s) [435.970683] Starting dynamic subtest: C-eDP1 [437.654375] Dynamic subtest C-eDP1: SUCCESS (1.693s) [437.664628] Starting dynamic subtest: D-eDP1 [439.349981] Dynamic subtest D-eDP1: SUCCESS (1.693s) [439.355017] Subtest basic-plain-flip: SUCCESS (6.756s) [440.598326] [098/132] (586s left) kms_flip (plain-flip-fb-recreate) [440.701796] Starting subtest: plain-flip-fb-recreate [440.704083] Starting dynamic subtest: A-eDP1 [449.545144] Dynamic subtest A-eDP1: SUCCESS (8.848s) [449.555591] Starting dynamic subtest: B-eDP1 [458.448359] Dynamic subtest B-eDP1: SUCCESS (8.904s) [458.458572] Starting dynamic subtest: C-eDP1 [467.329798] Dynamic subtest C-eDP1: SUCCESS (8.881s) [467.339740] Starting dynamic subtest: D-eDP1 [476.226463] Dynamic subtest D-eDP1: SUCCESS (8.896s) [476.232462] Subtest plain-flip-fb-recreate: SUCCESS (35.540s) [477.501907] [099/132] (549s left) i915_suspend (debugfs-reader) [477.558439] Starting subtest: debugfs-reader [495.014291] Subtest debugfs-reader: SUCCESS (3.899s) [495.218586] [100/132] (532s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu) [495.345058] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu [495.349587] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [495.489524] [101/132] (532s left) gem_exec_nop (basic-sequential) [495.564996] Starting subtest: basic-sequential [510.151190] Subtest basic-sequential: SUCCESS (14.342s) [510.407531] [102/132] (517s left) gem_pxp (create-regular-buffer) [510.524777] Starting subtest: create-regular-buffer [510.527016] Subtest create-regular-buffer: SUCCESS (0.000s) [510.668250] [103/132] (517s left) syncobj_basic (test-valid-cycle) [510.729834] Starting subtest: test-valid-cycle [510.732142] Subtest test-valid-cycle: SUCCESS (0.000s) [510.865592] [104/132] (516s left) gem_exec_suspend (basic-s3-devices) [510.937469] Starting subtest: basic-S3-devices [510.939403] Starting dynamic subtest: smem [521.657505] Dynamic subtest smem: SUCCESS (10.688s) [521.660304] Subtest basic-S3-devices: SUCCESS (10.690s) [521.868927] [105/132] (505s left) template (a) [521.942044] Starting subtest: A [521.944288] Subtest A: SUCCESS (0.000s) [522.072427] [106/132] (505s left) gem_mmap_gtt (bad-object) [522.125951] Subtest bad-object: SKIP (0.000s) [522.191561] [107/132] (505s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt) [522.276168] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt [522.280317] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [522.418384] [108/132] (505s left) api_intel_bb (intel-bb-blit-y) [522.464824] Starting subtest: intel-bb-blit-y [525.999640] Subtest intel-bb-blit-y: SUCCESS (3.533s) [526.153752] [109/132] (501s left) kms_selftest (drm_format_helper) [526.194004] Starting subtest: drm_format_helper [526.246504] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8 [526.417197] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.170s) [526.427273] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332 [526.561866] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.143s) [526.570209] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565 [526.717653] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.154s) [526.728292] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555 [526.882226] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.165s) [526.892679] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555 [527.030226] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.145s) [527.040837] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551 [527.189715] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.160s) [527.198218] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888 [527.340234] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.147s) [527.350845] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888 [527.458526] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.118s) [527.467043] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888 [527.606560] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.148s) [527.615221] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010 [527.758657] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.151s) [527.767240] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010 [527.898385] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.139s) [527.906502] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono [528.058899] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.160s) [528.067047] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab [528.203320] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.144s) [528.211846] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888 [528.338887] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.134s) [528.348428] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888 [528.475512] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.136s) [528.483688] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset [528.624644] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.149s) [528.632704] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy [528.767577] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.141s) [528.769497] Subtest drm_format_helper: SUCCESS (2.571s) [528.999984] [110/132] (498s left) gem_mmap_offset (oob-read) [529.057239] Starting subtest: oob-read [529.059466] Subtest oob-read: SUCCESS (0.000s) [529.194753] [111/132] (498s left) gem_spin_batch (resubmit-all) [529.259705] Starting subtest: resubmit-all [529.262136] Starting dynamic subtest: rcs0 [529.278231] Dynamic subtest rcs0: SUCCESS (0.018s) [529.291264] Starting dynamic subtest: bcs0 [529.293824] Dynamic subtest bcs0: SUCCESS (0.003s) [529.302518] Starting dynamic subtest: vcs0 [529.304716] Dynamic subtest vcs0: SUCCESS (0.002s) [529.313108] Starting dynamic subtest: vcs1 [529.315332] Dynamic subtest vcs1: SUCCESS (0.002s) [529.323731] Starting dynamic subtest: vecs0 [529.325953] Dynamic subtest vecs0: SUCCESS (0.002s) [529.334728] Starting dynamic subtest: ccs0 [529.336937] Dynamic subtest ccs0: SUCCESS (0.003s) [529.339123] Subtest resubmit-all: SUCCESS (0.034s) [530.748725] [112/132] (497s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [530.860874] Starting subtest: cursorB-vs-flipA-toggle [530.865567] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s) [531.029732] [113/132] (497s left) gen9_exec_parse (valid-registers) [531.093183] Subtest valid-registers: SKIP (0.000s) [531.183717] [114/132] (496s left) kms_pipe_crc_basic (disable-crc-after-crtc) [531.266417] Starting subtest: disable-crc-after-crtc [531.268394] Starting dynamic subtest: pipe-A-eDP-1 [532.941294] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.672s) [532.951803] Starting dynamic subtest: pipe-B-eDP-1 [535.242212] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.299s) [535.254974] Starting dynamic subtest: pipe-C-eDP-1 [537.451849] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.207s) [537.460780] Starting dynamic subtest: pipe-D-eDP-1 [539.649160] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.195s) [539.651508] Subtest disable-crc-after-crtc: SUCCESS (8.379s) [540.482301] [115/132] (487s left) gem_exec_fence (syncobj-unused-fence) [540.885290] Starting subtest: syncobj-unused-fence [540.891293] Subtest syncobj-unused-fence: SUCCESS (0.005s) [542.844249] [116/132] (485s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move) [542.935527] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move [542.939957] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [543.071884] [117/132] (485s left) gem_set_tiling_vs_pwrite [543.199133] [118/132] (485s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [543.292743] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [543.297957] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [543.424486] [119/132] (484s left) vgem_basic (busy-fence) [543.462846] Starting subtest: busy-fence [543.464762] Subtest busy-fence: SUCCESS (0.000s) [543.548974] [120/132] (484s left) i915_pm_rpm (debugfs-read) [545.767764] Starting subtest: debugfs-read [546.933735] Subtest debugfs-read: SUCCESS (1.161s) [547.304738] [121/132] (481s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt) [547.408509] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt [552.004410] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.597s) [553.173521] [122/132] (475s left) perf_pmu (busy-start) [553.222313] Starting subtest: busy-start [553.224448] Starting dynamic subtest: rcs0 [555.770696] Dynamic subtest rcs0: SUCCESS (2.548s) [555.781358] Starting dynamic subtest: bcs0 [558.321873] Dynamic subtest bcs0: SUCCESS (2.547s) [558.333181] Starting dynamic subtest: vcs0 [561.657472] Dynamic subtest vcs0: SUCCESS (3.330s) [561.669444] Starting dynamic subtest: vcs1 [565.689537] Dynamic subtest vcs1: SUCCESS (4.027s) [565.701320] Starting dynamic subtest: vecs0 [569.657576] Dynamic subtest vecs0: SUCCESS (3.964s) [569.669443] Starting dynamic subtest: ccs0 [572.206341] Dynamic subtest ccs0: SUCCESS (2.547s) [572.208725] Subtest busy-start: SUCCESS (18.976s) [572.327093] [123/132] (456s left) kms_addfb_basic (size-max) [572.425209] Starting subtest: size-max [572.427063] Subtest size-max: SUCCESS (0.000s) [572.554605] [124/132] (455s left) kms_psr (fbc-pr-primary-blt) [572.645202] Starting subtest: fbc-pr-primary-blt [572.649235] Subtest fbc-pr-primary-blt: SKIP (0.000s) [572.742346] [125/132] (455s left) kms_color_pipeline (plane-ctm3x4) [572.842096] Starting subtest: plane-ctm3x4 [572.859245] Starting dynamic subtest: pipe-A-eDP-1 [573.477336] Dynamic subtest pipe-A-eDP-1: FAIL (0.300s) [574.213902] Starting dynamic subtest: pipe-B-eDP-1 [575.015123] Dynamic subtest pipe-B-eDP-1: FAIL (0.301s) [575.600996] Starting dynamic subtest: pipe-C-eDP-1 [576.412023] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s) [576.897810] Starting dynamic subtest: pipe-D-eDP-1 [577.713410] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s) [577.715576] Subtest plane-ctm3x4: FAIL (4.570s) [578.271438] [126/132] (450s left) kms_chamelium_hpd (vga-hpd-without-ddc) [578.654457] Subtest vga-hpd-without-ddc: SKIP (0.000s) [579.426137] [127/132] (449s left) api_intel_allocator (standalone) [579.482162] Starting subtest: standalone [579.495908] Subtest standalone: SUCCESS (0.014s) [579.648889] [128/132] (449s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [579.748597] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [586.149382] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.400s) [586.301478] [129/132] (442s left) kms_big_fb (y-tiled-addfb) [586.416060] Starting subtest: y-tiled-addfb [586.420590] Subtest y-tiled-addfb: SKIP (0.000s) [586.556004] [130/132] (442s left) kms_prime (basic-crc-vgem) [586.639275] Starting subtest: basic-crc-vgem [586.641392] Starting dynamic subtest: second-to-first [587.548174] Dynamic subtest second-to-first: SUCCESS (0.912s) [587.550122] Subtest basic-crc-vgem: SUCCESS (0.913s) [587.665354] [131/132] (441s left) i915_hangman (engine-error-state-capture) [587.732711] Starting subtest: engine-error-state-capture [587.734362] Starting dynamic subtest: rcs0 [591.639996] Dynamic subtest rcs0: SUCCESS (3.905s) [591.652785] Starting dynamic subtest: bcs0 [597.656665] Dynamic subtest bcs0: SUCCESS (6.011s) [597.668956] Starting dynamic subtest: vcs0 [603.608390] Dynamic subtest vcs0: SUCCESS (5.946s) [603.621364] Starting dynamic subtest: vcs1 [609.624327] Dynamic subtest vcs1: SUCCESS (6.010s) [609.636439] Starting dynamic subtest: vecs0 [615.640502] Dynamic subtest vecs0: SUCCESS (6.010s) [615.652989] Starting dynamic subtest: ccs0 [621.656963] Dynamic subtest ccs0: SUCCESS (6.011s) [621.660171] Subtest engine-error-state-capture: SUCCESS (33.910s) [621.764519] [132/132] (407s left) kms_invalid_mode (bad-vsync-end) [621.871994] Starting subtest: bad-vsync-end [621.874198] Starting dynamic subtest: pipe-A-eDP-1 [622.153798] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s) [622.163137] Starting dynamic subtest: pipe-B-eDP-1 [622.165019] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [622.173750] Starting dynamic subtest: pipe-C-eDP-1 [622.176031] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [622.184848] Starting dynamic subtest: pipe-D-eDP-1 [622.187061] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [622.189251] Subtest bad-vsync-end: SUCCESS (0.289s) [622.990517] Closing watchdogs results: parsing output: 0/ for test: syncobj_timeline results: parsing output: 1/ for test: kms_ccs results: parsing output: 2/ for test: kms_frontbuffer_tracking results: parsing output: 3/ for test: kms_big_fb results: parsing output: 4/ for test: kms_busy results: parsing output: 5/ for test: perf results: parsing output: 6/ for test: kms_big_fb results: parsing output: 7/ for test: kms_pipe_crc_basic results: parsing output: 8/ for test: perf_pmu results: parsing output: 9/ for test: gem_exec_reloc results: parsing output: 10/ for test: kms_flip results: parsing output: 11/ for test: gem_sync results: parsing output: 12/ for test: gem_ctx_persistence results: parsing output: 13/ for test: kms_plane results: parsing output: 14/ for test: prime_vgem results: parsing output: 15/ for test: i915_query results: parsing output: 16/ for test: syncobj_timeline results: parsing output: 17/ for test: syncobj_timeline results: parsing output: 18/ for test: kms_cursor_crc results: parsing output: 19/ for test: gem_render_copy results: parsing output: 20/ for test: kms_rotation_crc results: parsing output: 21/ for test: gem_render_copy results: parsing output: 22/ for test: gem_exec_schedule results: parsing output: 23/ for test: kms_frontbuffer_tracking results: parsing output: 24/ for test: kms_frontbuffer_tracking results: parsing output: 25/ for test: kms_ccs results: parsing output: 26/ for test: kms_psr2_sf results: parsing output: 27/ for test: kms_cursor_crc results: parsing output: 28/ for test: prime_mmap results: parsing output: 29/ for test: gem_exec_schedule results: parsing output: 30/ for test: syncobj_timeline results: parsing output: 31/ for test: fbdev results: parsing output: 32/ for test: syncobj_wait results: parsing output: 33/ for test: kms_frontbuffer_tracking results: parsing output: 34/ for test: gem_exec_balancer results: parsing output: 35/ for test: gem_ccs results: parsing output: 36/ for test: gem_fenced_exec_thrash results: parsing output: 37/ for test: kms_cursor_legacy results: parsing output: 38/ for test: kms_big_fb results: parsing output: 39/ for test: gem_mmap_wc results: parsing output: 40/ for test: kms_cursor_legacy results: parsing output: 41/ for test: kms_big_fb results: parsing output: 42/ for test: kms_addfb_basic results: parsing output: 43/ for test: gem_ctx_shared results: parsing output: 44/ for test: kms_flip results: parsing output: 45/ for test: kms_force_connector_basic results: parsing output: 46/ for test: kms_big_fb results: parsing output: 47/ for test: kms_plane_scaling results: parsing output: 48/ for test: kms_chamelium_audio results: parsing output: 49/ for test: gem_set_tiling_vs_gtt results: parsing output: 50/ for test: gem_lmem_swapping results: parsing output: 51/ for test: kms_frontbuffer_tracking results: parsing output: 52/ for test: prime_busy results: parsing output: 53/ for test: syncobj_wait results: parsing output: 54/ for test: kms_flip_scaled_crc results: parsing output: 55/ for test: gem_gtt_cpu_tlb results: parsing output: 56/ for test: kms_frontbuffer_tracking results: parsing output: 57/ for test: gen9_exec_parse results: parsing output: 58/ for test: gem_softpin results: parsing output: 59/ for test: dmabuf_sync_file results: parsing output: 60/ for test: kms_dither results: parsing output: 61/ for test: syncobj_timeline results: parsing output: 62/ for test: kms_chamelium_hpd results: parsing output: 63/ for test: kms_flip results: parsing output: 64/ for test: perf results: parsing output: 65/ for test: sw_sync results: parsing output: 66/ for test: kms_frontbuffer_tracking results: parsing output: 67/ for test: kms_getfb results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: kms_addfb_basic results: parsing output: 70/ for test: perf results: parsing output: 71/ for test: gem_exec_schedule results: parsing output: 72/ for test: kms_addfb_basic results: parsing output: 73/ for test: kms_atomic results: parsing output: 74/ for test: kms_ccs results: parsing output: 75/ for test: gem_exec_balancer results: parsing output: 76/ for test: kms_frontbuffer_tracking results: parsing output: 77/ for test: gem_ctx_exec results: parsing output: 78/ for test: kms_content_protection results: parsing output: 79/ for test: gem_eio results: parsing output: 80/ for test: kms_flip results: parsing output: 81/ for test: kms_flip_scaled_crc results: parsing output: 82/ for test: kms_psr results: parsing output: 83/ for test: kms_ccs results: parsing output: 84/ for test: kms_vblank results: parsing output: 85/ for test: kms_pm_dc results: parsing output: 86/ for test: gem_userptr_blits results: parsing output: 87/ for test: gem_exec_fence results: parsing output: 88/ for test: kms_pm_rpm results: parsing output: 89/ for test: kms_psr results: parsing output: 90/ for test: gem_exec_create results: parsing output: 91/ for test: kms_psr results: parsing output: 92/ for test: kms_frontbuffer_tracking results: parsing output: 93/ for test: kms_psr2_sf results: parsing output: 94/ for test: gem_exec_reloc results: parsing output: 95/ for test: kms_psr results: parsing output: 96/ for test: kms_flip results: parsing output: 97/ for test: kms_flip results: parsing output: 98/ for test: i915_suspend results: parsing output: 99/ for test: kms_frontbuffer_tracking results: parsing output: 100/ for test: gem_exec_nop results: parsing output: 101/ for test: gem_pxp results: parsing output: 102/ for test: syncobj_basic results: parsing output: 103/ for test: gem_exec_suspend results: parsing output: 104/ for test: template results: parsing output: 105/ for test: gem_mmap_gtt results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: api_intel_bb results: parsing output: 108/ for test: kms_selftest results: parsing output: 109/ for test: gem_mmap_offset results: parsing output: 110/ for test: gem_spin_batch results: parsing output: 111/ for test: kms_cursor_legacy results: parsing output: 112/ for test: gen9_exec_parse results: parsing output: 113/ for test: kms_pipe_crc_basic results: parsing output: 114/ for test: gem_exec_fence results: parsing output: 115/ for test: kms_frontbuffer_tracking results: parsing output: 116/ for test: gem_set_tiling_vs_pwrite results: parsing output: 117/ for test: kms_frontbuffer_tracking results: parsing output: 118/ for test: vgem_basic results: parsing output: 119/ for test: i915_pm_rpm results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: perf_pmu results: parsing output: 122/ for test: kms_addfb_basic results: parsing output: 123/ for test: kms_psr results: parsing output: 124/ for test: kms_color_pipeline results: parsing output: 125/ for test: kms_chamelium_hpd results: parsing output: 126/ for test: api_intel_allocator results: parsing output: 127/ for test: kms_frontbuffer_tracking results: parsing output: 128/ for test: kms_big_fb results: parsing output: 129/ for test: kms_prime results: parsing output: 130/ for test: i915_hangman results: parsing output: 131/ for test: kms_invalid_mode Done.