[57.243832] Initializing watchdogs [57.243880] /dev/watchdog0 [57.259245] [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] [57.263965] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [57.266405] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [57.269934] [001/133] (960s left) gem_workarounds (suspend-resume) [57.362044] Starting subtest: suspend-resume [73.226565] Subtest suspend-resume: SUCCESS (1.319s) [73.374822] [002/133] (944s left) gem_lmem_swapping (verify) [73.440692] Subtest verify: SKIP (0.000s) [73.486525] [003/133] (944s left) gem_exec_schedule (u-semaphore-noskip) [73.575357] Starting subtest: u-semaphore-noskip [73.978628] Subtest u-semaphore-noskip: SUCCESS (0.401s) [74.316935] [004/133] (943s left) core_setmaster (master-drop-set-user) [74.406088] Starting subtest: master-drop-set-user [74.418273] Subtest master-drop-set-user: SUCCESS (0.014s) [74.457406] [005/133] (943s left) gem_flink_basic (bad-flink) [74.512173] Starting subtest: bad-flink [74.514456] Subtest bad-flink: SUCCESS (0.000s) [74.609992] [006/133] (943s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [74.706399] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc [74.708807] Starting dynamic subtest: pipe-A-eDP-1 [74.730349] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [74.741622] Starting dynamic subtest: pipe-B-eDP-1 [74.761389] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [74.769896] Starting dynamic subtest: pipe-C-eDP-1 [74.789534] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [74.797691] Starting dynamic subtest: pipe-D-eDP-1 [74.817351] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [74.819545] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s) [76.928124] [007/133] (941s left) gem_exec_reloc (basic-active) [76.989351] Subtest basic-active: SKIP (0.000s) [77.075733] [008/133] (940s left) kms_pm_rpm (package-g7) [79.047159] Starting subtest: package-g7 [79.053401] Subtest package-g7: SKIP (0.000s) [79.430276] [009/133] (938s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite) [79.534730] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite [89.164874] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.573s) [89.305701] [010/133] (928s left) kms_cursor_crc (cursor-size-change) [89.413642] Starting subtest: cursor-size-change [89.415962] Starting dynamic subtest: pipe-A-eDP-1 [90.252522] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.836s) [90.262621] Starting dynamic subtest: pipe-D-eDP-1 [91.772162] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.514s) [91.774362] Subtest cursor-size-change: SUCCESS (2.353s) [92.644488] [011/133] (925s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [92.766292] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc [92.770732] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [92.878108] [012/133] (925s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs) [92.951900] Starting subtest: mixed-tiled-to-yf-tiled-ccs [92.956775] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s) [93.135035] [013/133] (925s left) i915_hangman (hangcheck-unterminated) [93.213245] Starting subtest: hangcheck-unterminated [104.803565] Subtest hangcheck-unterminated: SUCCESS (11.576s) [104.941742] [014/133] (913s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy) [105.019627] Starting subtest: map-fixed-invalidate-overlap-busy [105.023866] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s) [105.133612] [015/133] (913s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [105.232485] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc [105.236912] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [105.314100] [016/133] (913s left) gem_ctx_exec (basic) [105.382218] Starting subtest: basic [105.397152] Subtest basic: SUCCESS (0.015s) [105.522862] [017/133] (912s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier-source-clamping) [105.609148] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier-source-clamping [105.613510] Subtest pixel-format-4-tiled-lnl-ccs-modifier-source-clamping: SKIP (0.000s) [105.711785] [018/133] (912s left) gem_ppgtt (shrink-vs-evict-pinned) [105.778130] Starting subtest: shrink-vs-evict-pinned [111.774272] Subtest shrink-vs-evict-pinned: SUCCESS (5.997s) [111.858652] [019/133] (906s left) kms_psr (fbc-psr-primary-mmap-cpu) [111.954721] Starting subtest: fbc-psr-primary-mmap-cpu [111.957029] Starting dynamic subtest: eDP-1 [111.961416] Dynamic subtest eDP-1: SKIP (0.000s) [111.965760] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s) [112.025748] [020/133] (906s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling) [112.121840] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling [112.124212] Starting dynamic subtest: pipe-A-default-mode [114.893245] Dynamic subtest pipe-A-default-mode: SUCCESS (2.770s) [114.895519] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.771s) [115.748471] [021/133] (902s left) kms_flip (flip-vs-expired-vblank-interruptible) [115.839249] Starting subtest: flip-vs-expired-vblank-interruptible [115.841685] Starting dynamic subtest: A-eDP1 [124.461716] Dynamic subtest A-eDP1: SUCCESS (8.621s) [124.473346] Starting dynamic subtest: B-eDP1 [133.093743] Dynamic subtest B-eDP1: SUCCESS (8.629s) [133.103172] Starting dynamic subtest: C-eDP1 [141.716899] Dynamic subtest C-eDP1: SUCCESS (8.620s) [141.726203] Starting dynamic subtest: D-eDP1 [150.340175] Dynamic subtest D-eDP1: SUCCESS (8.620s) [150.345010] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.499s) [151.521680] [022/133] (867s left) gem_tiled_partial_pwrite_pread (writes-after-reads) [151.590842] Subtest writes-after-reads: SKIP (0.000s) [151.660022] [023/133] (867s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s [151.775157] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size [151.779575] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s) [151.918508] [024/133] (866s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling) [152.014039] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling [152.016342] Starting dynamic subtest: pipe-A-default-mode [154.761807] Dynamic subtest pipe-A-default-mode: SUCCESS (2.746s) [154.764002] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.747s) [155.618965] [025/133] (863s left) gem_userptr_blits (input-checking) [155.692209] Starting subtest: input-checking [155.694275] Subtest input-checking: SUCCESS (0.000s) [155.820687] [026/133] (862s left) gem_mmap_offset (bad-extensions) [155.879992] Starting subtest: bad-extensions [155.881923] Subtest bad-extensions: SUCCESS (0.000s) [155.989607] [027/133] (862s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions) [156.107334] Starting subtest: short-flip-before-cursor-atomic-transitions [157.067670] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.957s) [157.939775] [028/133] (860s left) perf (non-zero-reason) [158.072673] Starting subtest: non-zero-reason [158.074551] Starting dynamic subtest: 0-rcs0 [163.482713] Dynamic subtest 0-rcs0: SUCCESS (5.408s) [163.496374] Starting dynamic subtest: 1-vecs0 [167.396466] Dynamic subtest 1-vecs0: SUCCESS (3.912s) [167.399386] Subtest non-zero-reason: SUCCESS (9.321s) [167.439643] [029/133] (851s left) kms_big_fb (yf-tiled-16bpp-rotate-180) [167.548818] Starting subtest: yf-tiled-16bpp-rotate-180 [167.552837] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s) [167.659438] [030/133] (851s left) kms_psr (psr2-sprite-mmap-cpu) [167.763276] Starting subtest: psr2-sprite-mmap-cpu [167.765586] Starting dynamic subtest: eDP-1 [169.444765] Dynamic subtest eDP-1: SUCCESS (1.682s) [169.447022] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.683s) [170.317847] [031/133] (848s left) prime_vgem (basic-gtt) [170.469206] Starting subtest: basic-gtt [170.473940] Subtest basic-gtt: SKIP (0.000s) [170.586829] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true [170.589991] [032/133] (848s left) gem_exec_balancer (noheartbeat) [170.859156] Starting subtest: noheartbeat [170.863943] Subtest noheartbeat: SKIP (0.000s) [170.959925] [033/133] (848s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt) [171.059015] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt [171.063308] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [171.139860] [034/133] (848s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt) [171.241645] Starting subtest: fbc-rgb565-draw-mmap-gtt [171.246194] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s) [171.349964] [035/133] (847s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc) [171.448674] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc [171.453230] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [171.549330] [036/133] (847s left) kms_properties (crtc-properties-atomic) [171.639945] Starting subtest: crtc-properties-atomic [171.641896] Starting dynamic subtest: pipe-A-eDP-1 [172.082942] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.442s) [172.095199] Starting dynamic subtest: pipe-B-eDP-1 [173.278287] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.194s) [173.289250] Starting dynamic subtest: pipe-C-eDP-1 [174.408718] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.132s) [174.419974] Starting dynamic subtest: pipe-D-eDP-1 [175.532604] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s) [175.534957] Subtest crtc-properties-atomic: SUCCESS (3.895s) [176.357991] [037/133] (842s left) gem_madvise (dontneed-before-exec) [176.403943] Starting subtest: dontneed-before-exec [176.420259] Subtest dontneed-before-exec: SKIP (0.015s) [176.480779] [038/133] (842s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted) [176.538887] Starting subtest: etime-single-wait-all-available-unsubmitted [176.641483] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.104s) [176.719213] [039/133] (842s left) syncobj_timeline (wait-any-complex) [176.778587] Starting subtest: wait-any-complex [176.839720] Subtest wait-any-complex: SUCCESS (0.062s) [176.919297] [040/133] (842s left) kms_psr (fbc-psr2-primary-blt) [177.019233] Starting subtest: fbc-psr2-primary-blt [177.021550] Starting dynamic subtest: eDP-1 [177.025743] Dynamic subtest eDP-1: SKIP (0.000s) [177.029939] Subtest fbc-psr2-primary-blt: SKIP (0.001s) [177.091937] [041/133] (842s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc) [177.192432] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc [184.203511] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.015s) [184.318477] [042/133] (835s left) kms_cursor_edge_walk (64x64-right-edge) [184.415265] Starting subtest: 64x64-right-edge [184.417564] Starting dynamic subtest: pipe-A-eDP-1 [187.842113] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.425s) [187.853111] Starting dynamic subtest: pipe-D-eDP-1 [190.944442] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.103s) [190.946849] Subtest 64x64-right-edge: SUCCESS (6.530s) [191.761220] [043/133] (827s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier) [191.857317] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier [191.861662] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s) [191.946169] [044/133] (827s left) kms_rotation_crc (sprite-rotation-90-pos-100-0) [192.032272] Starting subtest: sprite-rotation-90-pos-100-0 [192.036297] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s) [192.108097] [045/133] (827s left) kms_async_flips (async-flip-hang) [192.196298] Starting subtest: async-flip-hang [192.571824] Starting dynamic subtest: pipe-A-eDP-1 [208.291718] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.717s) [208.678461] Starting dynamic subtest: pipe-B-eDP-1 [224.289326] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.614s) [224.664639] Starting dynamic subtest: pipe-C-eDP-1 [240.290130] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.629s) [240.688169] Starting dynamic subtest: pipe-D-eDP-1 [256.290400] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.603s) [256.293008] Subtest async-flip-hang: SUCCESS (64.084s) [257.513155] [046/133] (762s left) kms_plane_alpha_blend (alpha-7efc) [257.598173] Starting subtest: alpha-7efc [257.894843] Starting dynamic subtest: pipe-A-eDP-1 [258.494752] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.599s) [258.521986] Starting dynamic subtest: pipe-D-eDP-1 [259.849311] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.333s) [259.851819] Subtest alpha-7efc: SUCCESS (2.250s) [260.682072] [047/133] (759s left) i915_pm_rpm (reg-read-ioctl) [263.017362] Starting subtest: reg-read-ioctl [265.305741] Subtest reg-read-ioctl: SUCCESS (2.292s) [265.708784] [048/133] (754s left) kms_psr (fbc-pr-primary-mmap-cpu) [265.796221] Starting subtest: fbc-pr-primary-mmap-cpu [265.800866] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s) [265.872374] [049/133] (753s left) gem_ctx_persistence (engines-hang) [265.930063] Starting subtest: engines-hang [265.932479] Starting dynamic subtest: rcs0 [266.059963] Dynamic subtest rcs0: SUCCESS (0.129s) [266.072526] Starting dynamic subtest: bcs0 [266.161010] Dynamic subtest bcs0: SUCCESS (0.099s) [266.173123] Starting dynamic subtest: vcs0 [266.252124] Dynamic subtest vcs0: SUCCESS (0.089s) [266.264321] Starting dynamic subtest: vcs1 [266.344806] Dynamic subtest vcs1: SUCCESS (0.091s) [266.357419] Starting dynamic subtest: vecs0 [266.432889] Dynamic subtest vecs0: SUCCESS (0.086s) [266.446639] Starting dynamic subtest: ccs0 [266.526761] Dynamic subtest ccs0: SUCCESS (0.091s) [266.529191] Subtest engines-hang: SUCCESS (0.598s) [266.606955] [050/133] (753s left) prime_self_import (basic-with_one_bo_two_files) [266.639304] Starting subtest: basic-with_one_bo_two_files [266.656138] Subtest basic-with_one_bo_two_files: SUCCESS (0.020s) [266.755687] [051/133] (753s left) kms_cdclk (mode-transition) [266.844724] Starting subtest: mode-transition [266.846691] Starting dynamic subtest: pipe-A-eDP-1 [267.138489] Dynamic subtest pipe-A-eDP-1: SKIP (0.290s) [267.149098] Starting dynamic subtest: pipe-B-eDP-1 [267.153922] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [267.168571] Starting dynamic subtest: pipe-C-eDP-1 [267.173170] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [267.182134] Starting dynamic subtest: pipe-D-eDP-1 [267.186402] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [267.190833] Subtest mode-transition: SKIP (0.298s) [268.019308] [052/133] (751s left) prime_mmap (test_forked) [268.077807] Starting subtest: test_forked [268.079733] Starting dynamic subtest: test_forked-smem [268.114837] Dynamic subtest test_forked-smem: SUCCESS (0.039s) [268.117311] Subtest test_forked: SUCCESS (0.041s) [268.224732] [053/133] (751s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip) [268.321437] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip [268.325943] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [268.412778] [054/133] (751s left) kms_big_fb (4-tiled-16bpp-rotate-180) [268.522268] Starting subtest: 4-tiled-16bpp-rotate-180 [269.790020] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.268s) [269.916274] [055/133] (750s left) api_intel_bb (object-noreloc-purge-cache-simple) [269.966854] Starting subtest: object-noreloc-purge-cache-simple [269.971186] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s) [270.093250] [056/133] (749s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc) [270.188248] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc [270.191785] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [270.303995] [057/133] (749s left) gen9_exec_parse (secure-batches) [270.364149] Subtest secure-batches: SKIP (0.000s) [270.434058] [058/133] (749s left) gem_spin_batch (legacy) [270.485309] Starting subtest: legacy [270.487237] Starting dynamic subtest: default [273.727051] Dynamic subtest default: SUCCESS (3.235s) [273.737158] Starting dynamic subtest: render [276.965138] Dynamic subtest render: SUCCESS (3.234s) [276.977271] Starting dynamic subtest: bsd [280.205009] Dynamic subtest bsd: SUCCESS (3.236s) [280.216674] Starting dynamic subtest: bsd1 [283.439667] Dynamic subtest bsd1: SUCCESS (3.231s) [283.451158] Starting dynamic subtest: bsd2 [286.678633] Dynamic subtest bsd2: SUCCESS (3.236s) [286.690305] Starting dynamic subtest: blt [289.914605] Dynamic subtest blt: SUCCESS (3.232s) [289.925963] Starting dynamic subtest: vebox [293.152675] Dynamic subtest vebox: SUCCESS (3.235s) [293.154747] Subtest legacy: SUCCESS (22.658s) [293.905114] [059/133] (726s left) kms_big_fb (y-tiled-64bpp-rotate-180) [294.014826] Starting subtest: y-tiled-64bpp-rotate-180 [294.019052] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s) [294.110292] [060/133] (726s left) kms_big_fb (x-tiled-16bpp-rotate-0) [294.220307] Starting subtest: x-tiled-16bpp-rotate-0 [295.521007] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.301s) [295.644759] [061/133] (724s left) api_intel_allocator (gem-pool) [295.702083] Starting subtest: gem-pool [295.706620] Subtest gem-pool: SUCCESS (0.007s) [295.809053] [062/133] (724s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs) [295.916539] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs [295.920329] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s) [297.065316] [063/133] (723s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs) [297.161286] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs [297.163180] Starting dynamic subtest: pipe-A-eDP-1 [299.373059] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.213s) [299.382753] Starting dynamic subtest: pipe-B-eDP-1 [301.685561] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.312s) [301.695121] Starting dynamic subtest: pipe-C-eDP-1 [303.742608] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.057s) [303.752140] Starting dynamic subtest: pipe-D-eDP-1 [305.810197] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.067s) [305.812357] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.654s) [306.991049] [064/133] (713s left) gem_exec_balancer (sliced) [307.057951] Starting subtest: sliced [307.062572] Subtest sliced: SKIP (0.000s) [307.366754] [065/133] (713s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs) [307.462280] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs [307.464613] Starting dynamic subtest: pipe-A-eDP-1 [307.524463] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [307.535427] Starting dynamic subtest: pipe-B-eDP-1 [307.593044] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [307.603653] Starting dynamic subtest: pipe-C-eDP-1 [307.660994] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [307.671794] Starting dynamic subtest: pipe-D-eDP-1 [307.729471] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [307.733658] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.009s) [309.766058] [066/133] (710s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite) [309.869460] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite [309.873598] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [309.970410] [067/133] (710s left) gem_request_retire (retire-vma-not-inactive) [310.030555] Starting subtest: retire-vma-not-inactive [310.090180] Subtest retire-vma-not-inactive: SUCCESS (0.056s) [310.235938] [068/133] (710s left) kms_psr (pr-cursor-plane-move) [310.328990] Starting subtest: pr-cursor-plane-move [310.333368] Subtest pr-cursor-plane-move: SKIP (0.000s) [310.403224] [069/133] (710s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt) [310.500665] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt [317.817529] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.321s) [317.957517] [070/133] (702s left) syncobj_wait (invalid-reset-illegal-handle) [318.018139] Starting subtest: invalid-reset-illegal-handle [318.020392] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s) [318.157213] [071/133] (702s left) kms_properties (get_properties-sanity-non-atomic) [318.262332] Starting subtest: get_properties-sanity-non-atomic [318.264542] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s) [318.337492] [072/133] (702s left) sysfs_timeslice_duration (idempotent) [318.413238] Starting subtest: idempotent [318.415501] Starting dynamic subtest: vecs0 [318.417790] Dynamic subtest vecs0: SUCCESS (0.000s) [318.427747] Starting dynamic subtest: ccs0 [318.429926] Dynamic subtest ccs0: SUCCESS (0.000s) [318.439124] Starting dynamic subtest: vcs0 [318.441195] Dynamic subtest vcs0: SUCCESS (0.000s) [318.450455] Starting dynamic subtest: bcs0 [318.452612] Dynamic subtest bcs0: SUCCESS (0.000s) [318.461805] Starting dynamic subtest: rcs0 [318.463936] Dynamic subtest rcs0: SUCCESS (0.000s) [318.472813] Starting dynamic subtest: vcs1 [318.474644] Dynamic subtest vcs1: SUCCESS (0.000s) [318.476879] Subtest idempotent: SUCCESS (0.006s) [318.525220] [073/133] (702s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20) [318.614071] Starting subtest: planes-downscale-factor-0-5-upscale-20x20 [318.616217] Starting dynamic subtest: pipe-A [318.635936] Dynamic subtest pipe-A: SKIP (0.018s) [318.645700] Starting dynamic subtest: pipe-B [318.669434] Dynamic subtest pipe-B: SKIP (0.032s) [318.678924] Starting dynamic subtest: pipe-C [318.701204] Dynamic subtest pipe-C: SKIP (0.031s) [318.710279] Starting dynamic subtest: pipe-D [318.731626] Dynamic subtest pipe-D: SKIP (0.030s) [318.735756] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.115s) [318.811120] [074/133] (702s left) gem_exec_fence (syncobj-invalid-flags) [319.256344] Starting subtest: syncobj-invalid-flags [319.258338] Subtest syncobj-invalid-flags: SUCCESS (0.000s) [320.921034] [075/133] (700s left) gem_ringfill (legacy-basic) [326.856996] Starting subtest: legacy-basic [326.859761] Starting dynamic subtest: default [326.909022] Dynamic subtest default: SUCCESS (0.052s) [326.921163] Starting dynamic subtest: render [326.951804] Dynamic subtest render: SUCCESS (0.040s) [326.962818] Starting dynamic subtest: bsd [326.995602] Dynamic subtest bsd: SUCCESS (0.041s) [327.006685] Starting dynamic subtest: bsd1 [327.036551] Dynamic subtest bsd1: SUCCESS (0.039s) [327.049323] Starting dynamic subtest: bsd2 [327.081044] Dynamic subtest bsd2: SUCCESS (0.042s) [327.094200] Starting dynamic subtest: blt [327.126868] Dynamic subtest blt: SUCCESS (0.044s) [327.137861] Starting dynamic subtest: vebox [327.168922] Dynamic subtest vebox: SUCCESS (0.040s) [327.171262] Subtest legacy-basic: SUCCESS (0.315s) [327.249000] [076/133] (693s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0) [327.352986] Starting subtest: primary-yf-tiled-reflect-x-0 [327.413168] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.059s) [327.518429] [077/133] (693s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping) [327.612225] Starting subtest: pixel-format-y-tiled-modifier-source-clamping [327.616305] Subtest pixel-format-y-tiled-modifier-source-clamping: SKIP (0.000s) [327.688174] [078/133] (693s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area) [327.784339] Starting subtest: pr-overlay-plane-update-sf-dmg-area [327.788267] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s) [327.852070] [079/133] (693s left) kms_cursor_crc (cursor-random-32x10) [328.006892] Starting subtest: cursor-random-32x10 [328.054576] Subtest cursor-random-32x10: SKIP (0.043s) [328.308428] [080/133] (692s left) gem_flink_basic (double-flink) [328.362237] Starting subtest: double-flink [328.363744] Subtest double-flink: SUCCESS (0.000s) [328.459184] [081/133] (692s left) gem_ctx_param (basic) [328.503517] Starting subtest: basic [328.505494] Subtest basic: SUCCESS (0.001s) [328.599105] [082/133] (692s left) kms_psr (psr-primary-mmap-cpu) [328.687586] Starting subtest: psr-primary-mmap-cpu [328.689485] Starting dynamic subtest: eDP-1 [330.375133] Dynamic subtest eDP-1: SUCCESS (1.686s) [330.377275] Subtest psr-primary-mmap-cpu: SUCCESS (1.687s) [331.245949] [083/133] (690s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner) [331.346594] Starting subtest: switch-modeset-ultra-joiner-big-joiner [331.350729] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s) [331.429575] [084/133] (689s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [331.528864] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [331.533216] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [331.628002] [085/133] (689s left) kms_properties (invalid-properties-atomic) [331.733023] Starting subtest: invalid-properties-atomic [332.047256] Subtest invalid-properties-atomic: SUCCESS (0.314s) [332.122170] [086/133] (689s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf) [332.222852] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf [332.226843] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s) [332.291299] [087/133] (689s left) kms_invalid_mode (zero-clock) [332.396136] Starting subtest: zero-clock [332.398247] Starting dynamic subtest: pipe-A-eDP-1 [332.679536] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s) [332.689746] Starting dynamic subtest: pipe-B-eDP-1 [332.691650] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [332.700918] Starting dynamic subtest: pipe-C-eDP-1 [332.702862] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [332.714747] Starting dynamic subtest: pipe-D-eDP-1 [332.717064] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [332.719475] Subtest zero-clock: SUCCESS (0.293s) [333.491032] [088/133] (687s left) gem_userptr_blits (unsync-unmap) [333.572404] Subtest unsync-unmap: SKIP (0.000s) [333.704173] [089/133] (687s left) kms_chamelium_edid (dp-edid-resolution-list) [334.096657] Subtest dp-edid-resolution-list: SKIP (0.000s) [334.904589] [090/133] (686s left) syncobj_basic (bad-create-flags) [334.966558] Starting subtest: bad-create-flags [334.968533] Subtest bad-create-flags: SUCCESS (0.000s) [335.078139] [091/133] (686s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt) [335.172502] Starting subtest: fbcpsr-farfromfence-mmap-gtt [335.176755] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s) [335.276853] [092/133] (686s left) gem_exec_reloc (basic-cpu-read-noreloc) [335.343944] Subtest basic-cpu-read-noreloc: SKIP (0.000s) [335.421440] [093/133] (686s left) kms_flip (modeset-vs-vblank-race-interruptible) [335.520328] Starting subtest: modeset-vs-vblank-race-interruptible [335.522515] Starting dynamic subtest: A-eDP1 [340.243287] Dynamic subtest A-eDP1: SUCCESS (4.721s) [340.255727] Starting dynamic subtest: B-eDP1 [344.892417] Dynamic subtest B-eDP1: SUCCESS (4.647s) [344.907976] Starting dynamic subtest: C-eDP1 [349.490705] Dynamic subtest C-eDP1: SUCCESS (4.595s) [349.500996] Starting dynamic subtest: D-eDP1 [354.053687] Dynamic subtest D-eDP1: SUCCESS (4.561s) [354.059106] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.537s) [355.226036] [094/133] (666s left) kms_properties (crtc-properties-legacy) [355.323459] Starting subtest: crtc-properties-legacy [355.325844] Starting dynamic subtest: pipe-A-eDP-1 [355.786708] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.464s) [355.797415] Starting dynamic subtest: pipe-B-eDP-1 [356.984319] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.195s) [356.993851] Starting dynamic subtest: pipe-C-eDP-1 [358.100954] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.115s) [358.111851] Starting dynamic subtest: pipe-D-eDP-1 [359.234508] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s) [359.236759] Subtest crtc-properties-legacy: SUCCESS (3.913s) [360.042766] [095/133] (661s left) sriov_basic (bind-unbind-vf) [360.108932] Starting subtest: bind-unbind-vf [360.111392] Starting dynamic subtest: vf-1 [360.186430] Dynamic subtest vf-1: FAIL (0.016s) [360.197932] Starting dynamic subtest: vf-2 [360.274320] Dynamic subtest vf-2: FAIL (0.004s) [360.285583] Starting dynamic subtest: vf-3 [360.359179] Dynamic subtest vf-3: FAIL (0.004s) [360.369711] Starting dynamic subtest: vf-4 [360.443116] Dynamic subtest vf-4: FAIL (0.004s) [360.454176] Starting dynamic subtest: vf-5 [360.527060] Dynamic subtest vf-5: FAIL (0.004s) [360.537933] Starting dynamic subtest: vf-6 [360.611130] Dynamic subtest vf-6: FAIL (0.004s) [360.622171] Starting dynamic subtest: vf-7 [360.695691] Dynamic subtest vf-7: FAIL (0.004s) [360.706899] Starting dynamic subtest: vf-random [360.777163] Dynamic subtest vf-random: FAIL (0.004s) [360.787690] Starting dynamic subtest: vf-last [360.860350] Dynamic subtest vf-last: FAIL (0.523s) [360.862761] Subtest bind-unbind-vf: FAIL (0.574s) [360.903791] [096/133] (660s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [360.999375] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [361.002908] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [361.111403] [097/133] (660s left) syncobj_timeline (multi-wait-all-available-signaled) [361.168401] Starting subtest: multi-wait-all-available-signaled [361.170335] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s) [361.277971] [098/133] (660s left) gem_lmem_swapping (heavy-random) [361.324226] Subtest heavy-random: SKIP (0.000s) [361.369799] [099/133] (660s left) gem_ctx_persistence (legacy-engines-hostile) [361.434296] Starting subtest: legacy-engines-hostile [361.436407] Starting dynamic subtest: render [361.557972] Dynamic subtest render: SUCCESS (0.122s) [361.576398] Starting dynamic subtest: bsd1 [361.657285] Dynamic subtest bsd1: SUCCESS (0.096s) [361.671447] Starting dynamic subtest: bsd2 [361.759719] Dynamic subtest bsd2: SUCCESS (0.099s) [361.774029] Starting dynamic subtest: blt [361.835549] Dynamic subtest blt: SUCCESS (0.073s) [361.849358] Starting dynamic subtest: vebox [361.950552] Dynamic subtest vebox: SUCCESS (0.112s) [361.952648] Subtest legacy-engines-hostile: SUCCESS (0.518s) [362.030172] [100/133] (659s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt) [362.121891] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt [362.125175] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [362.244389] [101/133] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc) [362.343237] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc [362.346546] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [362.436632] [102/133] (659s left) kms_content_protection (dp-mst-lic-type-0-hdcp14) [362.574582] Starting subtest: dp-mst-lic-type-0-hdcp14 [362.577981] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s) [362.683392] [103/133] (659s left) kms_draw_crc (draw-method-render) [362.776547] Starting subtest: draw-method-render [362.778376] Starting dynamic subtest: xrgb8888-untiled [363.054047] Dynamic subtest xrgb8888-untiled: SUCCESS (0.278s) [363.063835] Starting dynamic subtest: rgb565-untiled [363.276242] Dynamic subtest rgb565-untiled: SUCCESS (0.221s) [363.287993] Starting dynamic subtest: xrgb2101010-untiled [363.509722] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.231s) [363.519644] Starting dynamic subtest: xbgr16161616f-untiled [363.753698] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.243s) [363.763814] Starting dynamic subtest: xrgb8888-xtiled [363.887538] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s) [363.900046] Starting dynamic subtest: rgb565-xtiled [364.020942] Dynamic subtest rgb565-xtiled: SUCCESS (0.130s) [364.030128] Starting dynamic subtest: xrgb2101010-xtiled [364.142755] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s) [364.152541] Starting dynamic subtest: xbgr16161616f-xtiled [364.265014] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s) [364.274674] Starting dynamic subtest: xrgb8888-4tiled [364.409595] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s) [364.419138] Starting dynamic subtest: rgb565-4tiled [364.520867] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s) [364.532292] Starting dynamic subtest: xrgb2101010-4tiled [364.642848] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s) [364.655793] Starting dynamic subtest: xbgr16161616f-4tiled [364.776139] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.131s) [364.777916] Subtest draw-method-render: SUCCESS (2.002s) [364.899236] [104/133] (657s left) gem_fence_thrash (bo-write-verify-threaded-y) [364.938168] Starting subtest: bo-write-verify-threaded-y [364.957947] Subtest bo-write-verify-threaded-y: SKIP (0.018s) [365.033206] [105/133] (657s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [365.130723] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip [366.000109] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.870s) [366.185921] [106/133] (656s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc) [366.285518] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc [366.289572] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [366.386973] [107/133] (655s left) gem_exec_schedule (preempt-other) [366.457707] Starting subtest: preempt-other [366.460090] Starting dynamic subtest: rcs0 [366.520454] Dynamic subtest rcs0: SUCCESS (0.070s) [366.535513] Starting dynamic subtest: bcs0 [366.592633] Dynamic subtest bcs0: SUCCESS (0.071s) [366.606911] Starting dynamic subtest: vcs0 [366.659609] Dynamic subtest vcs0: SUCCESS (0.065s) [366.674514] Starting dynamic subtest: vcs1 [366.725792] Dynamic subtest vcs1: SUCCESS (0.066s) [366.740642] Starting dynamic subtest: vecs0 [366.791563] Dynamic subtest vecs0: SUCCESS (0.065s) [366.805711] Starting dynamic subtest: ccs0 [366.859751] Dynamic subtest ccs0: SUCCESS (0.067s) [366.862374] Subtest preempt-other: SUCCESS (0.411s) [367.908936] [108/133] (654s left) kms_color (ctm-0-75) [368.002479] Starting subtest: ctm-0-75 [368.004744] Starting dynamic subtest: pipe-A-eDP-1 [372.987026] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.985s) [372.997328] Starting dynamic subtest: pipe-B-eDP-1 [378.631470] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.643s) [378.641817] Starting dynamic subtest: pipe-C-eDP-1 [383.982280] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.349s) [383.992729] Starting dynamic subtest: pipe-D-eDP-1 [389.324110] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.341s) [389.326535] Subtest ctm-0-75: SUCCESS (21.322s) [390.161298] [109/133] (632s left) gem_ctx_exec (basic-close-race) [390.232753] Starting subtest: basic-close-race [396.127037] Subtest basic-close-race: SUCCESS (5.893s) [396.256216] [110/133] (626s left) gem_userptr_blits (invalid-mmap-offset-unsync) [396.337876] Subtest invalid-mmap-offset-unsync: SKIP (0.000s) [396.476645] [111/133] (625s left) kms_cursor_crc (cursor-onscreen-64x21) [396.686004] Starting subtest: cursor-onscreen-64x21 [396.731199] Subtest cursor-onscreen-64x21: SKIP (0.043s) [397.001880] [112/133] (625s left) gem_exec_whisper (basic-fds) [397.063695] Starting subtest: basic-fds [404.774776] Subtest basic-fds: SUCCESS (7.705s) [404.961853] [113/133] (617s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt) [405.063592] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt [405.067807] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s) [405.165309] [114/133] (617s left) vgem_basic (debugfs) [405.205095] Starting subtest: debugfs [405.207351] Subtest debugfs: SUCCESS (0.000s) [405.262250] [115/133] (617s left) kms_vblank (query-forked-busy-hang) [405.348514] Starting subtest: query-forked-busy-hang [405.350478] Starting dynamic subtest: pipe-A-eDP-1 [431.567573] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.215s) [431.583261] Starting dynamic subtest: pipe-D-eDP-1 [456.587143] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.012s) [456.589055] Subtest query-forked-busy-hang: SUCCESS (51.231s) [457.418152] [116/133] (565s left) syncobj_wait (multi-wait-all-for-submit-submitted) [457.480679] Starting subtest: multi-wait-all-for-submit-submitted [457.586445] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.104s) [457.682125] [117/133] (565s left) gem_busy (parallel) [457.858682] Starting subtest: parallel [457.861101] Starting dynamic subtest: rcs0 [459.846893] Dynamic subtest rcs0: SUCCESS (1.986s) [459.860377] Starting dynamic subtest: bcs0 [461.830908] Dynamic subtest bcs0: SUCCESS (1.980s) [461.844043] Starting dynamic subtest: vcs0 [463.814508] Dynamic subtest vcs0: SUCCESS (1.980s) [463.828129] Starting dynamic subtest: vcs1 [465.862145] Dynamic subtest vcs1: SUCCESS (2.044s) [465.874852] Starting dynamic subtest: vecs0 [467.846893] Dynamic subtest vecs0: SUCCESS (1.981s) [467.859759] Starting dynamic subtest: ccs0 [469.830986] Dynamic subtest ccs0: SUCCESS (1.980s) [469.833970] Subtest parallel: SUCCESS (11.970s) [470.077143] [118/133] (552s left) i915_query (engine-info) [470.134235] Starting subtest: engine-info [470.136628] Subtest engine-info: SUCCESS (0.004s) [470.250912] [119/133] (552s left) gem_ctx_shared (q-smoketest-all) [470.320177] Starting subtest: Q-smoketest-all [502.725612] Subtest Q-smoketest-all: SUCCESS (32.348s) [503.870430] [120/133] (518s left) kms_big_fb (4-tiled-8bpp-rotate-180) [503.974851] Starting subtest: 4-tiled-8bpp-rotate-180 [505.211921] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.238s) [505.341564] [121/133] (517s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt) [505.448106] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt [505.451485] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [505.549864] [122/133] (517s left) kms_chamelium_hpd (dp-hpd) [505.941020] Subtest dp-hpd: SKIP (0.000s) [506.742832] [123/133] (516s left) gem_reg_read (timestamp-monotonic) [506.802931] Starting subtest: timestamp-monotonic [511.665988] Subtest timestamp-monotonic: SUCCESS (4.863s) [511.742654] [124/133] (511s left) syncobj_eventfd (binary-wait-before-signal) [511.794816] Starting subtest: binary-wait-before-signal [511.825802] Subtest binary-wait-before-signal: SUCCESS (0.031s) [511.917387] [125/133] (511s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled) [511.976260] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled [511.978492] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s) [512.095820] [126/133] (511s left) i915_drm_fdinfo (virtual-busy-hang) [512.138780] Starting subtest: virtual-busy-hang [512.143299] Subtest virtual-busy-hang: SKIP (0.000s) [512.225334] [127/133] (510s left) kms_flip (2x-flip-vs-expired-vblank) [512.318907] Starting subtest: 2x-flip-vs-expired-vblank [512.328281] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s) [512.409536] [128/133] (510s left) kms_pm_rpm (basic-pci-d3-state) [513.927750] Starting subtest: basic-pci-d3-state [515.406867] Subtest basic-pci-d3-state: SUCCESS (1.478s) [515.496327] [129/133] (507s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt) [515.588332] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt [515.592651] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [515.686469] [130/133] (507s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions) [515.815887] Starting subtest: flip-vs-cursor-atomic-transitions [523.787360] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (7.968s) [524.598664] [131/133] (498s left) kms_sequence (get-forked) [524.687426] Starting subtest: get-forked [524.689871] Starting dynamic subtest: pipe-A-eDP-1 [526.571558] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.881s) [526.582683] Starting dynamic subtest: pipe-B-eDP-1 [529.144882] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.573s) [529.156034] Starting dynamic subtest: pipe-C-eDP-1 [531.657330] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.508s) [531.668898] Starting dynamic subtest: pipe-D-eDP-1 [534.218167] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.559s) [534.220197] Subtest get-forked: SUCCESS (9.526s) [535.044131] [132/133] (488s left) kms_flip (plain-flip-fb-recreate) [535.156861] Starting subtest: plain-flip-fb-recreate [535.158981] Starting dynamic subtest: A-eDP1 [544.026272] Dynamic subtest A-eDP1: SUCCESS (8.870s) [544.036977] Starting dynamic subtest: B-eDP1 [552.859323] Dynamic subtest B-eDP1: SUCCESS (8.830s) [552.872048] Starting dynamic subtest: C-eDP1 [561.690134] Dynamic subtest C-eDP1: SUCCESS (8.834s) [561.701771] Starting dynamic subtest: D-eDP1 [570.523009] Dynamic subtest D-eDP1: SUCCESS (8.836s) [570.527352] Subtest plain-flip-fb-recreate: SUCCESS (35.380s) [571.707013] [133/133] (451s left) perf_pmu (busy-idle-no-semaphores) [571.746646] Starting subtest: busy-idle-no-semaphores [571.748706] Starting dynamic subtest: rcs0 [572.407269] Dynamic subtest rcs0: SUCCESS (0.661s) [572.419953] Starting dynamic subtest: bcs0 [573.060371] Dynamic subtest bcs0: SUCCESS (0.651s) [573.073731] Starting dynamic subtest: vcs0 [573.716197] Dynamic subtest vcs0: SUCCESS (0.653s) [573.729172] Starting dynamic subtest: vcs1 [575.406692] Dynamic subtest vcs1: SUCCESS (1.688s) [575.420149] Starting dynamic subtest: vecs0 [577.454178] Dynamic subtest vecs0: SUCCESS (2.045s) [577.467289] Starting dynamic subtest: ccs0 [579.439484] Dynamic subtest ccs0: SUCCESS (1.984s) [579.442226] Subtest busy-idle-no-semaphores: SUCCESS (7.698s) [579.557019] Closing watchdogs results: parsing output: 0/ for test: gem_workarounds results: parsing output: 1/ for test: gem_lmem_swapping results: parsing output: 2/ for test: gem_exec_schedule results: parsing output: 3/ for test: core_setmaster results: parsing output: 4/ for test: gem_flink_basic results: parsing output: 5/ for test: kms_ccs results: parsing output: 6/ for test: gem_exec_reloc results: parsing output: 7/ for test: kms_pm_rpm results: parsing output: 8/ for test: kms_frontbuffer_tracking results: parsing output: 9/ for test: kms_cursor_crc results: parsing output: 10/ for test: kms_frontbuffer_tracking results: parsing output: 11/ for test: gem_render_copy results: parsing output: 12/ for test: i915_hangman results: parsing output: 13/ for test: gem_userptr_blits results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: gem_ctx_exec results: parsing output: 16/ for test: kms_plane results: parsing output: 17/ for test: gem_ppgtt results: parsing output: 18/ for test: kms_psr results: parsing output: 19/ for test: kms_flip_scaled_crc results: parsing output: 20/ for test: kms_flip results: parsing output: 21/ for test: gem_tiled_partial_pwrite_pread results: parsing output: 22/ for test: kms_cursor_legacy results: parsing output: 23/ for test: kms_flip_scaled_crc results: parsing output: 24/ for test: gem_userptr_blits results: parsing output: 25/ for test: gem_mmap_offset results: parsing output: 26/ for test: kms_cursor_legacy results: parsing output: 27/ for test: perf results: parsing output: 28/ for test: kms_big_fb results: parsing output: 29/ for test: kms_psr results: parsing output: 30/ for test: prime_vgem results: parsing output: 31/ for test: gem_exec_balancer results: parsing output: 32/ for test: kms_frontbuffer_tracking results: parsing output: 33/ for test: kms_frontbuffer_tracking results: parsing output: 34/ for test: kms_frontbuffer_tracking results: parsing output: 35/ for test: kms_properties results: parsing output: 36/ for test: gem_madvise results: parsing output: 37/ for test: syncobj_timeline results: parsing output: 38/ for test: syncobj_timeline results: parsing output: 39/ for test: kms_psr results: parsing output: 40/ for test: kms_frontbuffer_tracking results: parsing output: 41/ for test: kms_cursor_edge_walk results: parsing output: 42/ for test: kms_plane results: parsing output: 43/ for test: kms_rotation_crc results: parsing output: 44/ for test: kms_async_flips results: parsing output: 45/ for test: kms_plane_alpha_blend results: parsing output: 46/ for test: i915_pm_rpm results: parsing output: 47/ for test: kms_psr results: parsing output: 48/ for test: gem_ctx_persistence results: parsing output: 49/ for test: prime_self_import results: parsing output: 50/ for test: kms_cdclk results: parsing output: 51/ for test: prime_mmap results: parsing output: 52/ for test: kms_big_fb results: parsing output: 53/ for test: kms_big_fb results: parsing output: 54/ for test: api_intel_bb results: parsing output: 55/ for test: kms_frontbuffer_tracking results: parsing output: 56/ for test: gen9_exec_parse results: parsing output: 57/ for test: gem_spin_batch results: parsing output: 58/ for test: kms_big_fb results: parsing output: 59/ for test: kms_big_fb results: parsing output: 60/ for test: api_intel_allocator results: parsing output: 61/ for test: kms_ccs results: parsing output: 62/ for test: kms_ccs results: parsing output: 63/ for test: gem_exec_balancer results: parsing output: 64/ for test: kms_ccs results: parsing output: 65/ for test: kms_frontbuffer_tracking results: parsing output: 66/ for test: gem_request_retire results: parsing output: 67/ for test: kms_psr results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: syncobj_wait results: parsing output: 70/ for test: kms_properties results: parsing output: 71/ for test: sysfs_timeslice_duration results: parsing output: 72/ for test: kms_plane_scaling results: parsing output: 73/ for test: gem_exec_fence results: parsing output: 74/ for test: gem_ringfill results: parsing output: 75/ for test: kms_rotation_crc results: parsing output: 76/ for test: kms_plane results: parsing output: 77/ for test: kms_psr2_sf results: parsing output: 78/ for test: kms_cursor_crc results: parsing output: 79/ for test: gem_flink_basic results: parsing output: 80/ for test: gem_ctx_param results: parsing output: 81/ for test: kms_psr results: parsing output: 82/ for test: kms_joiner results: parsing output: 83/ for test: kms_big_fb results: parsing output: 84/ for test: kms_properties results: parsing output: 85/ for test: kms_psr2_sf results: parsing output: 86/ for test: kms_invalid_mode results: parsing output: 87/ for test: gem_userptr_blits results: parsing output: 88/ for test: kms_chamelium_edid results: parsing output: 89/ for test: syncobj_basic results: parsing output: 90/ for test: kms_frontbuffer_tracking results: parsing output: 91/ for test: gem_exec_reloc results: parsing output: 92/ for test: kms_flip results: parsing output: 93/ for test: kms_properties results: parsing output: 94/ for test: sriov_basic results: parsing output: 95/ for test: kms_frontbuffer_tracking results: parsing output: 96/ for test: syncobj_timeline results: parsing output: 97/ for test: gem_lmem_swapping results: parsing output: 98/ for test: gem_ctx_persistence results: parsing output: 99/ for test: kms_frontbuffer_tracking results: parsing output: 100/ for test: kms_frontbuffer_tracking results: parsing output: 101/ for test: kms_content_protection results: parsing output: 102/ for test: kms_draw_crc results: parsing output: 103/ for test: gem_fence_thrash results: parsing output: 104/ for test: kms_big_fb results: parsing output: 105/ for test: kms_frontbuffer_tracking results: parsing output: 106/ for test: gem_exec_schedule results: parsing output: 107/ for test: kms_color results: parsing output: 108/ for test: gem_ctx_exec results: parsing output: 109/ for test: gem_userptr_blits results: parsing output: 110/ for test: kms_cursor_crc results: parsing output: 111/ for test: gem_exec_whisper results: parsing output: 112/ for test: kms_frontbuffer_tracking results: parsing output: 113/ for test: vgem_basic results: parsing output: 114/ for test: kms_vblank results: parsing output: 115/ for test: syncobj_wait results: parsing output: 116/ for test: gem_busy results: parsing output: 117/ for test: i915_query results: parsing output: 118/ for test: gem_ctx_shared results: parsing output: 119/ for test: kms_big_fb results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: kms_chamelium_hpd results: parsing output: 122/ for test: gem_reg_read results: parsing output: 123/ for test: syncobj_eventfd results: parsing output: 124/ for test: syncobj_timeline results: parsing output: 125/ for test: i915_drm_fdinfo results: parsing output: 126/ for test: kms_flip results: parsing output: 127/ for test: kms_pm_rpm results: parsing output: 128/ for test: kms_frontbuffer_tracking results: parsing output: 129/ for test: kms_cursor_legacy results: parsing output: 130/ for test: kms_sequence results: parsing output: 131/ for test: kms_flip results: parsing output: 132/ for test: perf_pmu Done.