[58.962698] Initializing watchdogs [58.962743] /dev/watchdog0 [58.977090] [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] [58.981412] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [58.984017] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [58.988082] [001/156] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-render) [59.130211] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-render [59.134714] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [59.224397] [002/156] (960s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-onoff) [59.332206] Starting subtest: hdr-1p-primscrn-spr-indfb-onoff [59.336208] Subtest hdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [59.420667] [003/156] (960s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf) [59.527359] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf [59.529698] Starting dynamic subtest: pipe-A-eDP-1 [59.628840] Dynamic subtest pipe-A-eDP-1: SKIP (0.104s) [59.637529] Starting dynamic subtest: pipe-B-eDP-1 [60.827877] Dynamic subtest pipe-B-eDP-1: SKIP (1.195s) [60.832648] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.301s) [62.028715] [004/156] (957s left) kms_pm_backlight (fade) [62.118016] Starting subtest: fade [62.192181] Starting dynamic subtest: eDP-1 [65.202352] Dynamic subtest eDP-1: SUCCESS (3.004s) [65.204679] Subtest fade: SUCCESS (3.080s) [66.067017] [005/156] (953s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [66.173054] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [66.177283] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [66.251529] [006/156] (953s left) gem_exec_fence (submit67) [66.431328] Starting subtest: submit67 [66.436030] Subtest submit67: SKIP (0.000s) [68.370335] [FACT gem_exec_fence (submit67)] new: kernel.kmod_is_loaded.vgem: true [68.373337] [007/156] (951s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs) [68.475923] Starting subtest: missing-ccs-buffer-yf-tiled-ccs [68.478314] Starting dynamic subtest: pipe-A-eDP-1 [68.501396] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [68.509896] Starting dynamic subtest: pipe-B-eDP-1 [68.531078] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [68.538498] Starting dynamic subtest: pipe-C-eDP-1 [68.559698] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [68.567355] Starting dynamic subtest: pipe-D-eDP-1 [68.589408] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [68.593527] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.003s) [70.730283] [008/156] (949s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe) [71.117617] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s) [71.945068] [009/156] (947s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling) [72.035367] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling [72.039747] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s) [72.106163] [010/156] (947s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu) [72.206986] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu [76.456700] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.254s) [77.662305] [011/156] (942s left) syncobj_wait (invalid-wait-illegal-handle) [77.711852] Starting subtest: invalid-wait-illegal-handle [77.714184] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [77.792834] [012/156] (942s left) perf (buffer-fill) [77.910616] Starting subtest: buffer-fill [77.912608] Starting dynamic subtest: 0-rcs0 [80.178335] Dynamic subtest 0-rcs0: SUCCESS (2.267s) [80.186505] Starting dynamic subtest: 1-vcs1 [84.608864] Dynamic subtest 1-vcs1: SUCCESS (4.429s) [84.611428] Subtest buffer-fill: SUCCESS (6.697s) [84.659576] [013/156] (935s left) kms_dirtyfb (default-dirtyfb-ioctl) [84.757931] Starting subtest: default-dirtyfb-ioctl [84.760873] Starting dynamic subtest: A-eDP-1 [86.348642] Dynamic subtest A-eDP-1: SUCCESS (1.589s) [86.351059] Subtest default-dirtyfb-ioctl: SUCCESS (1.589s) [87.212701] [014/156] (932s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite) [87.328772] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite [87.332782] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [87.430368] [015/156] (932s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-msflip-blt) [87.545698] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-msflip-blt [87.550083] Subtest fbcpsrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [87.635688] [016/156] (932s left) gem_mmap_gtt (big-copy-xy) [87.698309] Subtest big-copy-XY: SKIP (0.000s) [87.752936] [017/156] (932s left) kms_psr (pr-primary-page-flip) [87.854643] Starting subtest: pr-primary-page-flip [87.858639] Subtest pr-primary-page-flip: SKIP (0.000s) [87.922508] [018/156] (932s left) gem_spin_batch (resubmit-new-all) [87.994400] Starting subtest: resubmit-new-all [87.996887] Starting dynamic subtest: rcs0 [88.012196] Dynamic subtest rcs0: SUCCESS (0.018s) [88.020459] Starting dynamic subtest: bcs0 [88.025297] Dynamic subtest bcs0: SUCCESS (0.013s) [88.035825] Starting dynamic subtest: vcs0 [88.040363] Dynamic subtest vcs0: SUCCESS (0.013s) [88.049520] Starting dynamic subtest: vcs1 [88.051846] Dynamic subtest vcs1: SUCCESS (0.012s) [88.061234] Starting dynamic subtest: vecs0 [88.065741] Dynamic subtest vecs0: SUCCESS (0.013s) [88.075813] Starting dynamic subtest: ccs0 [88.078191] Dynamic subtest ccs0: SUCCESS (0.010s) [88.080311] Subtest resubmit-new-all: SUCCESS (0.082s) [89.313611] [019/156] (930s left) gem_mmap (basic-small-bo) [89.371362] Subtest basic-small-bo: SKIP (0.000s) [89.438252] [020/156] (930s left) kms_frontbuffer_tracking (fbchdr-2p-rte) [89.545736] Starting subtest: fbchdr-2p-rte [89.549939] Subtest fbchdr-2p-rte: SKIP (0.000s) [89.660191] [021/156] (930s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [89.765766] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [90.390543] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.625s) [90.564755] [022/156] (929s left) i915_hangman (hangcheck-unterminated) [90.628740] Starting subtest: hangcheck-unterminated [102.235372] Subtest hangcheck-unterminated: SUCCESS (11.599s) [102.367684] [023/156] (917s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled) [102.424503] Starting subtest: multi-wait-all-for-submit-submitted-signaled [102.528531] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s) [102.612781] [024/156] (917s left) kms_async_flips (async-flip-with-page-flip-events-tiled) [102.701092] Starting subtest: async-flip-with-page-flip-events-tiled [103.072497] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs [105.869031] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.794s) [105.881282] Starting dynamic subtest: pipe-A-eDP-1-4 [108.055086] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.185s) [108.066569] Starting dynamic subtest: pipe-A-eDP-1-4 [110.065318] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s) [110.075547] Starting dynamic subtest: pipe-A-eDP-1-x [112.256374] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.190s) [112.643553] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs [115.403472] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.770s) [115.412407] Starting dynamic subtest: pipe-B-eDP-1-4 [117.589761] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s) [117.598362] Starting dynamic subtest: pipe-B-eDP-1-4 [119.599578] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.010s) [119.609268] Starting dynamic subtest: pipe-B-eDP-1-x [121.790890] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s) [122.164946] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs [124.935085] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.777s) [124.943831] Starting dynamic subtest: pipe-C-eDP-1-4 [127.109681] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.174s) [127.121094] Starting dynamic subtest: pipe-C-eDP-1-4 [129.119766] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.010s) [129.128863] Starting dynamic subtest: pipe-C-eDP-1-x [131.310615] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.190s) [131.676765] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs [134.461951] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.793s) [134.470667] Starting dynamic subtest: pipe-D-eDP-1-4 [136.636818] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s) [136.648123] Starting dynamic subtest: pipe-D-eDP-1-4 [138.647078] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s) [138.657509] Starting dynamic subtest: pipe-D-eDP-1-x [140.825159] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.179s) [140.827649] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.119s) [142.042511] [025/156] (878s left) gem_ctx_persistence (engines-persistence) [142.105505] Starting subtest: engines-persistence [142.107823] Starting dynamic subtest: rcs0 [142.918107] Dynamic subtest rcs0: SUCCESS (0.812s) [142.930287] Starting dynamic subtest: bcs0 [143.673662] Dynamic subtest bcs0: SUCCESS (0.755s) [143.685302] Starting dynamic subtest: vcs0 [144.454338] Dynamic subtest vcs0: SUCCESS (0.780s) [144.465168] Starting dynamic subtest: vcs1 [145.209036] Dynamic subtest vcs1: SUCCESS (0.753s) [145.220890] Starting dynamic subtest: vecs0 [145.975640] Dynamic subtest vecs0: SUCCESS (0.765s) [145.987422] Starting dynamic subtest: ccs0 [146.724904] Dynamic subtest ccs0: SUCCESS (0.749s) [146.727461] Subtest engines-persistence: SUCCESS (4.619s) [146.862359] [026/156] (873s left) kms_color_pipeline (plane-lut1d-post-ctm3x4) [146.966412] Starting subtest: plane-lut1d-post-ctm3x4 [147.096636] Starting dynamic subtest: pipe-A-plane-0 [147.283368] Dynamic subtest pipe-A-plane-0: SUCCESS (0.189s) [147.292715] Starting dynamic subtest: pipe-A-plane-1 [147.416692] Dynamic subtest pipe-A-plane-1: SUCCESS (0.132s) [147.430147] Starting dynamic subtest: pipe-A-plane-2 [147.550474] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s) [148.779653] Starting dynamic subtest: pipe-B-plane-0 [148.915233] Dynamic subtest pipe-B-plane-0: SUCCESS (0.145s) [148.924493] Starting dynamic subtest: pipe-B-plane-1 [149.048300] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s) [149.057327] Starting dynamic subtest: pipe-B-plane-2 [149.182105] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s) [150.363087] Starting dynamic subtest: pipe-C-plane-0 [150.489123] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s) [150.498267] Starting dynamic subtest: pipe-C-plane-1 [150.622765] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s) [150.632849] Starting dynamic subtest: pipe-C-plane-2 [150.779060] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s) [152.003315] Starting dynamic subtest: pipe-D-plane-0 [152.151259] Dynamic subtest pipe-D-plane-0: SUCCESS (0.156s) [152.162202] Starting dynamic subtest: pipe-D-plane-1 [152.284481] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s) [152.293222] Starting dynamic subtest: pipe-D-plane-2 [152.417523] Dynamic subtest pipe-D-plane-2: SUCCESS (0.132s) [152.708412] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.739s) [153.584438] [027/156] (866s left) gem_exec_schedule (u-fairslice-all) [153.659175] Starting subtest: u-fairslice-all [155.720164] Subtest u-fairslice-all: SUCCESS (2.062s) [156.010058] [028/156] (864s left) kms_big_fb (y-tiled-32bpp-rotate-180) [156.115122] Starting subtest: y-tiled-32bpp-rotate-180 [156.119144] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s) [156.217816] [029/156] (864s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu) [156.310692] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu [156.315071] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [156.404848] [030/156] (864s left) kms_cursor_crc (cursor-offscreen-512x512) [156.694749] Starting subtest: cursor-offscreen-512x512 [156.699373] Subtest cursor-offscreen-512x512: SKIP (0.001s) [156.874880] [031/156] (863s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt) [156.977371] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt [156.981078] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [157.071295] [032/156] (863s left) kms_invalid_mode (bad-htotal) [157.172945] Starting subtest: bad-htotal [157.174970] Starting dynamic subtest: pipe-A-eDP-1 [157.457632] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s) [157.467451] Starting dynamic subtest: pipe-B-eDP-1 [157.469773] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [157.478309] Starting dynamic subtest: pipe-C-eDP-1 [157.480274] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [157.488026] Starting dynamic subtest: pipe-D-eDP-1 [157.490054] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [157.491994] Subtest bad-htotal: SUCCESS (0.287s) [158.305662] [033/156] (862s left) gem_ccs (block-copy-compressed) [158.358431] Starting subtest: block-copy-compressed [158.363130] Subtest block-copy-compressed: SKIP (0.000s) [158.478585] [034/156] (862s left) kms_psr (psr-sprite-mmap-gtt) [158.563992] Starting subtest: psr-sprite-mmap-gtt [158.566227] Starting dynamic subtest: eDP-1 [160.013614] Dynamic subtest eDP-1: SKIP (1.447s) [160.018565] Subtest psr-sprite-mmap-gtt: SKIP (1.448s) [160.194109] [035/156] (860s left) gem_exec_schedule (fifo) [160.257682] Starting subtest: fifo [160.259556] Starting dynamic subtest: rcs0 [160.344846] Dynamic subtest rcs0: SUCCESS (0.096s) [160.358088] Starting dynamic subtest: vcs0 [160.425875] Dynamic subtest vcs0: SUCCESS (0.080s) [160.437263] Starting dynamic subtest: vcs1 [160.502649] Dynamic subtest vcs1: SUCCESS (0.076s) [160.514786] Starting dynamic subtest: vecs0 [160.585692] Dynamic subtest vecs0: SUCCESS (0.083s) [160.597218] Starting dynamic subtest: ccs0 [160.664027] Dynamic subtest ccs0: SUCCESS (0.076s) [160.667040] Subtest fifo: SUCCESS (0.414s) [162.396808] [036/156] (858s left) kms_pipe_crc_basic (suspend-read-crc) [162.479938] Starting subtest: suspend-read-crc [162.482274] Starting dynamic subtest: pipe-A-eDP-1 [180.044786] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.633s) [180.054484] Starting dynamic subtest: pipe-B-eDP-1 [198.051557] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.152s) [198.061420] Starting dynamic subtest: pipe-C-eDP-1 [215.991300] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.130s) [216.004061] Starting dynamic subtest: pipe-D-eDP-1 [234.009011] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.082s) [234.011381] Subtest suspend-read-crc: SUCCESS (12.002s) [234.863254] [037/156] (786s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip) [234.971532] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip [234.975590] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s) [235.074199] [038/156] (785s left) kms_chamelium_edid (vga-edid-read) [235.462102] Subtest vga-edid-read: SKIP (0.000s) [236.293723] [039/156] (784s left) gem_exec_flush (basic-wb-prw-default) [236.348711] Starting subtest: basic-wb-prw-default [241.828633] Subtest basic-wb-prw-default: SUCCESS (5.454s) [242.017821] [040/156] (779s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move) [242.122771] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move [242.127019] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [242.220860] [041/156] (778s left) kms_flip (2x-flip-vs-panning) [242.312744] Starting subtest: 2x-flip-vs-panning [242.323495] Subtest 2x-flip-vs-panning: SKIP (0.010s) [242.401886] [042/156] (778s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc) [242.506272] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc [242.510736] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [242.609391] [043/156] (778s left) gem_pxp (create-protected-buffer) [242.736368] Starting subtest: create-protected-buffer [242.739009] Subtest create-protected-buffer: SUCCESS (0.000s) [242.866471] [044/156] (778s left) gem_exec_reloc (basic-softpin) [242.923144] Subtest basic-softpin: SKIP (0.000s) [242.998526] [045/156] (778s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [243.090427] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc [243.092209] Starting dynamic subtest: pipe-A-eDP-1 [243.109760] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [243.119502] Starting dynamic subtest: pipe-B-eDP-1 [243.135513] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [243.144595] Starting dynamic subtest: pipe-C-eDP-1 [243.161244] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [243.169703] Starting dynamic subtest: pipe-D-eDP-1 [243.185615] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [243.187171] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s) [245.410031] [046/156] (775s left) kms_dsc (dsc-with-bpc-formats) [245.500656] Starting subtest: dsc-with-bpc-formats [245.613889] Subtest dsc-with-bpc-formats: SKIP (0.003s) [245.654545] [047/156] (775s left) gem_tiled_swapping (non-threaded) [245.721424] Subtest non-threaded: SKIP (0.000s) [245.775413] [048/156] (775s left) kms_big_fb (linear-64bpp-rotate-0) [245.876803] Starting subtest: linear-64bpp-rotate-0 [247.324854] Subtest linear-64bpp-rotate-0: SUCCESS (1.445s) [247.493965] [049/156] (773s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier) [247.587599] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier [247.614545] Starting dynamic subtest: pipe-A-plane-0 [247.881939] Dynamic subtest pipe-A-plane-0: SUCCESS (0.266s) [247.892781] Starting dynamic subtest: pipe-A-plane-3 [248.092396] Dynamic subtest pipe-A-plane-3: SUCCESS (0.210s) [248.102137] Starting dynamic subtest: pipe-A-plane-5 [248.105861] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [249.204519] Starting dynamic subtest: pipe-B-plane-0 [249.485100] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s) [249.495354] Starting dynamic subtest: pipe-B-plane-3 [249.697033] Dynamic subtest pipe-B-plane-3: SUCCESS (0.211s) [249.709830] Starting dynamic subtest: pipe-B-plane-5 [249.714412] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [250.016273] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.426s) [250.889826] [050/156] (770s left) gen7_exec_parse (oacontrol-tracking) [250.942385] Subtest oacontrol-tracking: SKIP (0.000s) [251.011491] [051/156] (770s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff) [251.104204] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff [258.068505] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (6.962s) [258.215377] [052/156] (763s left) api_intel_bb (misplaced-blitter) [258.277693] Starting subtest: misplaced-blitter [258.282971] Subtest misplaced-blitter: SUCCESS (0.008s) [258.404626] [053/156] (763s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt) [258.500542] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt [258.504567] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [258.580785] [054/156] (763s left) gem_exec_params (secure-non-master) [258.651024] Starting subtest: secure-non-master [258.655157] Subtest secure-non-master: SKIP (0.000s) [258.754557] [055/156] (762s left) sysfs_heartbeat_interval (idempotent) [258.810923] Starting subtest: idempotent [258.813411] Starting dynamic subtest: vecs0 [258.815824] Dynamic subtest vecs0: SUCCESS (0.001s) [258.827804] Starting dynamic subtest: ccs0 [258.830070] Dynamic subtest ccs0: SUCCESS (0.001s) [258.839821] Starting dynamic subtest: vcs0 [258.841754] Dynamic subtest vcs0: SUCCESS (0.000s) [258.851111] Starting dynamic subtest: bcs0 [258.853460] Dynamic subtest bcs0: SUCCESS (0.001s) [258.863836] Starting dynamic subtest: rcs0 [258.865917] Dynamic subtest rcs0: SUCCESS (0.000s) [258.874920] Starting dynamic subtest: vcs1 [258.877027] Dynamic subtest vcs1: SUCCESS (0.000s) [258.879102] Subtest idempotent: SUCCESS (0.008s) [258.918169] [056/156] (762s left) core_setmaster (master-drop-set-user) [259.030687] Starting subtest: master-drop-set-user [259.042598] Subtest master-drop-set-user: SUCCESS (0.013s) [259.080709] [057/156] (762s left) i915_drm_fdinfo (idle) [259.112381] Starting subtest: idle [259.114450] Starting dynamic subtest: rcs0 [259.671530] Dynamic subtest rcs0: SUCCESS (0.558s) [259.682024] Starting dynamic subtest: bcs0 [260.178872] Dynamic subtest bcs0: SUCCESS (0.503s) [260.189401] Starting dynamic subtest: vcs0 [260.689961] Dynamic subtest vcs0: SUCCESS (0.503s) [260.701888] Starting dynamic subtest: vcs1 [261.192748] Dynamic subtest vcs1: SUCCESS (0.503s) [261.206223] Starting dynamic subtest: vecs0 [261.697832] Dynamic subtest vecs0: SUCCESS (0.503s) [261.712123] Starting dynamic subtest: ccs0 [262.203726] Dynamic subtest ccs0: SUCCESS (0.503s) [262.206608] Subtest idle: SUCCESS (3.089s) [262.284338] [058/156] (759s left) gem_render_copy (yf-tiled-ccs-to-x-tiled) [262.339995] Starting subtest: yf-tiled-ccs-to-x-tiled [262.344108] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s) [262.545166] [059/156] (759s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait) [264.064838] Starting subtest: modeset-non-lpsp-stress-no-wait [264.130690] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.064s) [264.830332] [060/156] (757s left) gem_exec_balancer (bonded-false-hang) [265.098283] Starting subtest: bonded-false-hang [265.103204] Subtest bonded-false-hang: SKIP (0.000s) [265.213724] [061/156] (756s left) gem_mmap_gtt (pf-nonblock) [265.278201] Subtest pf-nonblock: SKIP (0.000s) [265.345440] [062/156] (756s left) kms_psr2_sf (psr2-cursor-plane-update-sf) [265.454209] Starting subtest: psr2-cursor-plane-update-sf [265.456565] Starting dynamic subtest: pipe-A-eDP-1 [266.025560] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.577s) [266.040099] Starting dynamic subtest: pipe-B-eDP-1 [267.319770] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.292s) [267.321720] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.871s) [268.188456] [063/156] (753s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt) [268.289114] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt [268.293261] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [268.390821] [064/156] (753s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff) [268.493733] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff [268.497726] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [268.581731] [065/156] (753s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary) [268.680740] Starting subtest: psr-indfb-scaledprimary [274.397838] Subtest psr-indfb-scaledprimary: SUCCESS (5.724s) [274.533877] [066/156] (747s left) gem_render_copy (x-tiled) [274.589583] Starting subtest: x-tiled [274.591889] Starting dynamic subtest: smem [274.630529] Dynamic subtest smem: SUCCESS (0.040s) [274.633083] Subtest x-tiled: SUCCESS (0.041s) [274.814694] [067/156] (747s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area) [274.905956] Starting subtest: psr2-overlay-primary-update-sf-dmg-area [274.908168] Starting dynamic subtest: pipe-A-eDP-1 [280.777223] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.874s) [280.788422] Starting dynamic subtest: pipe-B-eDP-1 [287.452801] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.674s) [287.455226] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.551s) [288.327657] [068/156] (733s left) gem_caching (read-writes) [288.384736] Subtest read-writes: SKIP (0.000s) [288.467685] [069/156] (733s left) kms_pm_rpm (fences) [289.991365] Starting subtest: fences [289.996248] Subtest fences: SKIP (0.000s) [290.779468] [070/156] (731s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [290.824439] Starting subtest: multi-wait-for-submit-available-signaled [290.826579] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s) [290.918191] [071/156] (731s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted) [290.967653] Starting subtest: multi-wait-all-for-submit-unsubmitted [291.070429] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.102s) [291.150242] [072/156] (731s left) kms_vblank (wait-forked) [291.242886] Starting subtest: wait-forked [291.245205] Starting dynamic subtest: pipe-A-eDP-1 [293.056998] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.814s) [293.074209] Starting dynamic subtest: pipe-D-eDP-1 [295.546191] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.486s) [295.548781] Subtest wait-forked: SUCCESS (4.304s) [296.408014] [073/156] (726s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-blt) [296.508175] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-blt [296.512346] Subtest hdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [296.604328] [074/156] (725s left) kms_big_fb (x-tiled-8bpp-rotate-180) [296.691118] Starting subtest: x-tiled-8bpp-rotate-180 [297.882917] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.191s) [297.998559] [075/156] (724s left) syncobj_timeline (wait-for-submit-snapshot) [298.048770] Starting subtest: wait-for-submit-snapshot [298.129553] Subtest wait-for-submit-snapshot: SUCCESS (0.081s) [298.223659] [076/156] (724s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu) [298.322213] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu [298.325638] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [298.424213] [077/156] (724s left) gem_ctx_sseu (engines) [298.484419] Subtest engines: SKIP (0.000s) [298.553329] [078/156] (724s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render) [298.657086] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render [305.910802] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.255s) [306.024582] [079/156] (716s left) gem_softpin (softpin) [306.092230] Starting subtest: softpin [338.318515] Subtest softpin: SUCCESS (32.217s) [338.440199] [080/156] (684s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt) [338.553107] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt [338.557533] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [338.656898] [081/156] (684s left) syncobj_basic (bad-flags-fd-to-handle) [338.704406] Starting subtest: bad-flags-fd-to-handle [338.706356] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s) [338.825248] [082/156] (684s left) gem_partial_pwrite_pread (write-uncached) [338.890892] Subtest write-uncached: SKIP (0.000s) [338.973626] [083/156] (683s left) sw_sync (sync_busy) [339.009893] Starting subtest: sync_busy [339.012336] Subtest sync_busy: SUCCESS (0.002s) [339.067305] [084/156] (683s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite) [339.177721] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite [344.050005] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.879s) [345.255430] [085/156] (677s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt) [345.359131] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt [345.363483] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [345.452508] [086/156] (677s left) i915_pm_rps (min-max-config-idle) [345.509875] Starting subtest: min-max-config-idle [345.513934] Subtest min-max-config-idle: SKIP (0.001s) [345.583592] [087/156] (677s left) gem_ctx_persistence (legacy-engines-queued) [345.635464] Starting subtest: legacy-engines-queued [345.637832] Starting dynamic subtest: render [345.701947] Dynamic subtest render: SUCCESS (0.065s) [345.713648] Starting dynamic subtest: bsd1 [347.730767] Dynamic subtest bsd1: SUCCESS (2.024s) [347.744259] Starting dynamic subtest: bsd2 [349.713575] Dynamic subtest bsd2: SUCCESS (1.979s) [349.726095] Starting dynamic subtest: blt [349.756744] Dynamic subtest blt: SUCCESS (0.042s) [349.766426] Starting dynamic subtest: vebox [351.697588] Dynamic subtest vebox: SUCCESS (1.937s) [351.701201] Subtest legacy-engines-queued: SUCCESS (6.062s) [351.779750] [088/156] (671s left) kms_frontbuffer_tracking (fbcpsr-tiling-y) [351.899770] Starting subtest: fbcpsr-tiling-y [351.903634] Subtest fbcpsr-tiling-y: SKIP (0.000s) [351.995724] [089/156] (671s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt) [352.095479] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt [358.904683] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.814s) [359.031789] [090/156] (664s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move) [359.137688] Starting subtest: fbc-2p-primscrn-cur-indfb-move [359.142061] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s) [359.243571] [091/156] (663s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [359.342443] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [366.626223] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.288s) [366.787506] [092/156] (656s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [366.849854] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [366.851817] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s) [366.954806] [093/156] (656s left) kms_universal_plane (disable-primary-vs-flip) [367.054754] Starting subtest: disable-primary-vs-flip [367.057004] Starting dynamic subtest: pipe-A-eDP-1 [368.601409] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.547s) [368.614706] Starting dynamic subtest: pipe-B-eDP-1 [370.879286] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.277s) [370.891438] Starting dynamic subtest: pipe-C-eDP-1 [373.126084] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.242s) [373.138768] Starting dynamic subtest: pipe-D-eDP-1 [375.392306] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.268s) [375.394931] Subtest disable-primary-vs-flip: SUCCESS (8.339s) [376.289000] [094/156] (647s left) gem_wait (wait) [376.352917] Starting subtest: wait [376.355087] Starting dynamic subtest: all-engines [377.713858] Dynamic subtest all-engines: SUCCESS (1.361s) [377.725201] Starting dynamic subtest: rcs0 [378.759089] Dynamic subtest rcs0: SUCCESS (1.044s) [378.771115] Starting dynamic subtest: bcs0 [379.805793] Dynamic subtest bcs0: SUCCESS (1.044s) [379.817072] Starting dynamic subtest: vcs0 [380.850033] Dynamic subtest vcs0: SUCCESS (1.042s) [380.863445] Starting dynamic subtest: vcs1 [381.896827] Dynamic subtest vcs1: SUCCESS (1.044s) [381.908613] Starting dynamic subtest: vecs0 [382.941650] Dynamic subtest vecs0: SUCCESS (1.042s) [382.953818] Starting dynamic subtest: ccs0 [383.991816] Dynamic subtest ccs0: SUCCESS (1.047s) [383.994755] Subtest wait: SUCCESS (7.641s) [384.196533] [095/156] (639s left) kms_content_protection (dp-mst-type-0) [384.344369] Starting subtest: dp-mst-type-0 [384.348931] Subtest dp-mst-type-0: SKIP (0.000s) [384.460053] [096/156] (638s left) gem_ccs (suspend-resume) [384.516422] Starting subtest: suspend-resume [384.520975] Subtest suspend-resume: SKIP (0.000s) [384.653620] [097/156] (638s left) kms_cursor_crc (cursor-tearing-framebuffer-change) [384.765716] Starting subtest: cursor-tearing-framebuffer-change [384.768057] Starting dynamic subtest: pipe-A-eDP-1 [385.345170] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.578s) [385.357062] Starting dynamic subtest: pipe-D-eDP-1 [386.602535] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.255s) [386.604993] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.836s) [387.439262] [098/156] (636s left) kms_plane_scaling (planes-downscale-factor-0-5) [387.526426] Starting subtest: planes-downscale-factor-0-5 [387.528680] Starting dynamic subtest: pipe-A [387.546547] Dynamic subtest pipe-A: SKIP (0.017s) [387.557011] Starting dynamic subtest: pipe-B [387.575598] Dynamic subtest pipe-B: SKIP (0.029s) [387.586615] Starting dynamic subtest: pipe-C [387.607356] Dynamic subtest pipe-C: SKIP (0.029s) [387.617359] Starting dynamic subtest: pipe-D [387.635079] Dynamic subtest pipe-D: SKIP (0.029s) [387.639506] Subtest planes-downscale-factor-0-5: SKIP (0.107s) [387.740453] [099/156] (635s left) gem_mmap (bad-size) [387.796469] Subtest bad-size: SKIP (0.000s) [387.853447] [100/156] (635s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier) [387.944640] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier [387.948786] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s) [388.017971] [101/156] (635s left) gem_ctx_create (basic) [388.086792] Starting subtest: basic [388.088980] Subtest basic: SUCCESS (0.000s) [388.267859] [102/156] (635s left) syncobj_timeline (multi-wait-all-for-submit-signaled) [388.326034] Starting subtest: multi-wait-all-for-submit-signaled [388.328544] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s) [388.446544] [103/156] (635s left) kms_async_flips (test-time-stamp-atomic) [388.540271] Starting subtest: test-time-stamp-atomic [388.920464] Starting dynamic subtest: pipe-A-eDP-1 [389.734754] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.813s) [390.116606] Starting dynamic subtest: pipe-B-eDP-1 [390.967063] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.857s) [391.344258] Starting dynamic subtest: pipe-C-eDP-1 [392.103276] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.767s) [392.479279] Starting dynamic subtest: pipe-D-eDP-1 [393.253625] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.781s) [393.256103] Subtest test-time-stamp-atomic: SUCCESS (4.713s) [394.416121] [104/156] (629s left) i915_power (sanity) [394.472082] Starting subtest: sanity [398.511699] Subtest sanity: SUCCESS (4.030s) [399.794185] [105/156] (624s left) perf_pmu (most-busy-check-all) [399.835100] Starting subtest: most-busy-check-all [399.837229] Starting dynamic subtest: rcs0 [401.716828] Dynamic subtest rcs0: SUCCESS (1.879s) [401.730651] Starting dynamic subtest: bcs0 [403.763519] Dynamic subtest bcs0: SUCCESS (2.042s) [403.783252] Starting dynamic subtest: vcs0 [405.746842] Dynamic subtest vcs0: SUCCESS (1.980s) [405.759347] Starting dynamic subtest: vcs1 [407.729735] Dynamic subtest vcs1: SUCCESS (1.979s) [407.741682] Starting dynamic subtest: vecs0 [409.714731] Dynamic subtest vecs0: SUCCESS (1.981s) [409.729165] Starting dynamic subtest: ccs0 [411.762813] Dynamic subtest ccs0: SUCCESS (2.045s) [411.765827] Subtest most-busy-check-all: SUCCESS (11.925s) [411.862225] [106/156] (611s left) kms_chamelium_audio (dp-audio-edid) [412.245218] Subtest dp-audio-edid: SKIP (0.000s) [413.021303] [107/156] (610s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move) [413.111549] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move [413.115739] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [413.213616] [108/156] (610s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted) [413.272992] Starting subtest: etime-single-wait-all-for-submit-submitted [413.381779] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s) [413.459849] [109/156] (610s left) gem_exec_fence (expired-history) [413.892167] Starting subtest: expired-history [421.491095] Subtest expired-history: SUCCESS (7.597s) [422.036478] [110/156] (601s left) kms_force_connector_basic (force-edid) [422.119898] Starting subtest: force-edid [422.498754] Subtest force-edid: SUCCESS (0.378s) [422.656925] [111/156] (601s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs) [422.746782] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs [422.749107] Starting dynamic subtest: pipe-A-eDP-1 [422.772237] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [422.786400] Starting dynamic subtest: pipe-B-eDP-1 [422.807161] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [422.817395] Starting dynamic subtest: pipe-C-eDP-1 [422.838745] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [422.849116] Starting dynamic subtest: pipe-D-eDP-1 [422.870982] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [422.875386] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.005s) [424.963393] [112/156] (599s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-wc) [425.060527] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-wc [425.064592] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [425.151949] [113/156] (599s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted) [425.201816] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted [425.309905] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.108s) [425.388227] [114/156] (598s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt) [425.494250] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt [425.498712] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [425.615809] [115/156] (598s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [425.728486] Starting subtest: cursorA-vs-flipA-atomic-transitions [436.886037] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.154s) [437.797595] [116/156] (586s left) gem_mmap_wc (write-cpu-read-wc) [437.863444] Subtest write-cpu-read-wc: SKIP (0.000s) [437.922236] [117/156] (586s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy) [438.048164] Starting subtest: 2x-cursor-vs-flip-legacy [438.052629] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s) [438.194003] [118/156] (586s left) sriov_basic (bind-unbind-vf) [438.258139] Starting subtest: bind-unbind-vf [438.260587] Starting dynamic subtest: vf-1 [438.265069] Dynamic subtest vf-1: SKIP (0.001s) [438.275511] Starting dynamic subtest: vf-2 [438.279519] Dynamic subtest vf-2: SKIP (0.001s) [438.289350] Starting dynamic subtest: vf-3 [438.293667] Dynamic subtest vf-3: SKIP (0.001s) [438.303142] Starting dynamic subtest: vf-4 [438.307012] Dynamic subtest vf-4: SKIP (0.001s) [438.316460] Starting dynamic subtest: vf-5 [438.320377] Dynamic subtest vf-5: SKIP (0.001s) [438.329644] Starting dynamic subtest: vf-6 [438.333912] Dynamic subtest vf-6: SKIP (0.001s) [438.345892] Starting dynamic subtest: vf-7 [438.349887] Dynamic subtest vf-7: SKIP (0.001s) [438.359897] Starting dynamic subtest: vf-random [438.363900] Dynamic subtest vf-random: SKIP (0.001s) [438.373525] Starting dynamic subtest: vf-last [438.377528] Dynamic subtest vf-last: SKIP (0.000s) [438.381443] Subtest bind-unbind-vf: SKIP (0.012s) [438.419579] [119/156] (585s left) kms_psr (pr-basic) [438.514412] Starting subtest: pr-basic [438.518734] Subtest pr-basic: SKIP (0.000s) [438.605557] [120/156] (585s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite) [438.704648] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite [438.708256] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [438.799984] [121/156] (585s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt) [438.900554] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt [443.364089] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.467s) [444.608229] [122/156] (579s left) kms_legacy_colorkey (basic) [444.696734] Starting subtest: basic [444.698991] Starting dynamic subtest: pipe-A [444.740203] Dynamic subtest pipe-A: SUCCESS (0.045s) [444.752741] Starting dynamic subtest: pipe-B [444.754656] Dynamic subtest pipe-B: SUCCESS (0.001s) [444.764633] Starting dynamic subtest: pipe-C [444.766914] Dynamic subtest pipe-C: SUCCESS (0.001s) [444.776876] Starting dynamic subtest: pipe-D [444.779072] Dynamic subtest pipe-D: SUCCESS (0.000s) [444.781277] Subtest basic: SUCCESS (0.052s) [444.825612] [123/156] (579s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-render) [444.917082] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-render [444.921136] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [445.002758] [124/156] (579s left) gem_exec_whisper (basic-queues-forked) [445.069171] Starting subtest: basic-queues-forked [469.688759] Subtest basic-queues-forked: SUCCESS (24.616s) [469.889940] [125/156] (554s left) gem_mmap_gtt (basic-read-write-distinct) [469.948274] Subtest basic-read-write-distinct: SKIP (0.000s) [470.017676] [126/156] (554s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [470.124647] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [470.128976] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [470.210892] [127/156] (554s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite) [470.302814] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite [470.306996] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [470.408846] [128/156] (554s left) kms_frontbuffer_tracking (fbchdr-shrfb-scaledprimary) [470.504970] Starting subtest: fbchdr-shrfb-scaledprimary [470.509249] Subtest fbchdr-shrfb-scaledprimary: SKIP (0.000s) [470.597929] [129/156] (554s left) api_intel_bb (blit-noreloc-keep-cache) [470.654055] Starting subtest: blit-noreloc-keep-cache [470.669177] Subtest blit-noreloc-keep-cache: SUCCESS (0.015s) [470.783864] [130/156] (554s left) kms_flip (plain-flip-ts-check-interruptible) [470.878735] Starting subtest: plain-flip-ts-check-interruptible [470.880817] Starting dynamic subtest: A-eDP1 [479.734400] Dynamic subtest A-eDP1: SUCCESS (8.855s) [479.745592] Starting dynamic subtest: B-eDP1 [488.635884] Dynamic subtest B-eDP1: SUCCESS (8.901s) [488.651982] Starting dynamic subtest: C-eDP1 [497.520137] Dynamic subtest C-eDP1: SUCCESS (8.882s) [497.531761] Starting dynamic subtest: D-eDP1 [506.417826] Dynamic subtest D-eDP1: SUCCESS (8.897s) [506.420364] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.546s) [507.669954] [131/156] (517s left) gem_exec_schedule (pi-common) [507.856689] Starting subtest: pi-common [507.858819] Starting dynamic subtest: rcs0 [507.887532] Dynamic subtest rcs0: SUCCESS (0.035s) [507.908160] Starting dynamic subtest: bcs0 [507.926999] Dynamic subtest bcs0: SUCCESS (0.039s) [507.937612] Starting dynamic subtest: vcs0 [507.964561] Dynamic subtest vcs0: SUCCESS (0.036s) [507.977356] Starting dynamic subtest: vcs1 [507.996652] Dynamic subtest vcs1: SUCCESS (0.031s) [508.011440] Starting dynamic subtest: vecs0 [508.027332] Dynamic subtest vecs0: SUCCESS (0.029s) [508.042364] Starting dynamic subtest: ccs0 [508.055931] Dynamic subtest ccs0: SUCCESS (0.028s) [508.058174] Subtest pi-common: SUCCESS (0.205s) [508.296846] [132/156] (516s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy) [508.411730] Starting subtest: fbcpsr-modesetfrombusy [514.578122] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.173s) [514.727222] [133/156] (510s left) perf (blocking-parameterized) [514.829203] Starting subtest: blocking-parameterized [534.849389] Subtest blocking-parameterized: SUCCESS (20.017s) [534.903896] [134/156] (490s left) kms_hdr (bpc-switch-suspend) [535.005407] Starting subtest: bpc-switch-suspend [535.007750] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010 [555.306952] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (5.642s) [555.318620] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F [576.294836] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.636s) [576.297281] Subtest bpc-switch-suspend: SUCCESS (11.282s) [576.395078] [135/156] (448s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt) [576.512900] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt [576.516802] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [576.619057] [136/156] (448s left) kms_busy (basic-hang) [576.713961] Starting subtest: basic-hang [576.715932] Starting dynamic subtest: flip-pipe-A [577.290524] Dynamic subtest flip-pipe-A: SUCCESS (0.572s) [577.303988] Starting dynamic subtest: modeset-pipe-A [580.703866] Dynamic subtest modeset-pipe-A: SUCCESS (3.390s) [580.716314] Starting dynamic subtest: flip-pipe-D [581.996034] Dynamic subtest flip-pipe-D: SUCCESS (1.282s) [582.007045] Starting dynamic subtest: modeset-pipe-D [585.344678] Dynamic subtest modeset-pipe-D: SUCCESS (3.336s) [585.346891] Subtest basic-hang: SUCCESS (8.585s) [586.217443] [137/156] (438s left) gem_mmap (short-mmap) [586.276880] Subtest short-mmap: SKIP (0.000s) [586.346178] [138/156] (438s left) sw_sync (timeline_closed_signaled) [586.383657] Starting subtest: timeline_closed_signaled [586.386092] Subtest timeline_closed_signaled: SUCCESS (0.000s) [586.439377] [139/156] (438s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-pgflip-blt) [586.536721] Starting subtest: fbchdr-2p-primscrn-indfb-pgflip-blt [586.540663] Subtest fbchdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [586.634004] [140/156] (438s left) kms_getfb (getfb-repeated-different-handles) [586.729695] Starting subtest: getfb-repeated-different-handles [586.731976] Subtest getfb-repeated-different-handles: SUCCESS (0.000s) [586.850379] [141/156] (438s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt) [586.940648] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt [586.944714] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [587.043037] [142/156] (438s left) kms_setmode (invalid-clone-single-crtc-stealing) [587.093358] Starting subtest: invalid-clone-single-crtc-stealing [587.099887] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s) [587.200036] [143/156] (438s left) gem_exec_reloc (basic-write-wc) [587.256331] Subtest basic-write-wc: SKIP (0.000s) [587.336338] [144/156] (438s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic) [587.452753] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic [587.456421] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s) [587.600983] [145/156] (437s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [587.651626] Starting subtest: y-tiled-to-vebox-y-tiled [587.655624] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [587.839141] [146/156] (437s left) gem_exec_whisper (basic-contexts-forked-all) [587.900547] Starting subtest: basic-contexts-forked-all [608.626117] Subtest basic-contexts-forked-all: SUCCESS (20.702s) [608.811897] [147/156] (416s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary) [608.929749] Starting subtest: psr-shrfb-scaledprimary [614.657016] Subtest psr-shrfb-scaledprimary: SUCCESS (5.733s) [614.794529] [148/156] (410s left) kms_psr (fbc-psr2-sprite-blt) [614.890659] Starting subtest: fbc-psr2-sprite-blt [614.892951] Starting dynamic subtest: eDP-1 [614.897367] Dynamic subtest eDP-1: SKIP (0.000s) [614.901805] Subtest fbc-psr2-sprite-blt: SKIP (0.001s) [614.967200] [149/156] (410s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing) [615.055679] Starting subtest: plane-use-after-nonblocking-unbind-fencing [615.058142] Starting dynamic subtest: pipe-A-eDP-1 [615.121283] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.066s) [615.419387] Starting dynamic subtest: pipe-B-eDP-1 [616.193153] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.783s) [616.482112] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.425s) [617.331990] [150/156] (408s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [617.435410] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [618.458092] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.023s) [618.588438] [151/156] (407s left) perf (enable-disable) [618.665636] Starting subtest: enable-disable [618.667654] Starting dynamic subtest: 0-rcs0 [620.931589] Dynamic subtest 0-rcs0: SUCCESS (2.264s) [620.942604] Starting dynamic subtest: 1-vcs1 [625.416893] Dynamic subtest 1-vcs1: SUCCESS (4.484s) [625.419489] Subtest enable-disable: SUCCESS (6.750s) [625.470739] [152/156] (400s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [625.562250] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu [625.566745] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [625.648961] [153/156] (400s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs) [625.755646] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs [625.757885] Starting dynamic subtest: pipe-A-eDP-1 [625.780193] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [625.793283] Starting dynamic subtest: pipe-B-eDP-1 [625.814362] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [625.824390] Starting dynamic subtest: pipe-C-eDP-1 [625.846540] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [625.858766] Starting dynamic subtest: pipe-D-eDP-1 [625.880188] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [625.884545] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.006s) [628.024581] [154/156] (397s left) kms_cursor_crc (cursor-rapid-movement-512x512) [628.321362] Starting subtest: cursor-rapid-movement-512x512 [628.325856] Subtest cursor-rapid-movement-512x512: SKIP (0.001s) [628.486734] [155/156] (397s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling) [628.585990] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling [628.590117] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s) [628.659022] [156/156] (397s left) kms_psr (psr-primary-mmap-gtt) [628.746427] Starting subtest: psr-primary-mmap-gtt [628.748611] Starting dynamic subtest: eDP-1 [630.201664] Dynamic subtest eDP-1: SKIP (1.454s) [630.206629] Subtest psr-primary-mmap-gtt: SKIP (1.457s) [630.359268] Closing watchdogs results: parsing output: 0/ for test: kms_frontbuffer_tracking results: parsing output: 1/ for test: kms_frontbuffer_tracking results: parsing output: 2/ for test: kms_psr2_sf results: parsing output: 3/ for test: kms_pm_backlight results: parsing output: 4/ for test: kms_frontbuffer_tracking results: parsing output: 5/ for test: gem_exec_fence results: parsing output: 6/ for test: kms_ccs results: parsing output: 7/ for test: kms_chamelium_hpd results: parsing output: 8/ for test: kms_flip_scaled_crc results: parsing output: 9/ for test: kms_frontbuffer_tracking results: parsing output: 10/ for test: syncobj_wait results: parsing output: 11/ for test: perf results: parsing output: 12/ for test: kms_dirtyfb results: parsing output: 13/ for test: kms_frontbuffer_tracking results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: gem_mmap_gtt results: parsing output: 16/ for test: kms_psr results: parsing output: 17/ for test: gem_spin_batch results: parsing output: 18/ for test: gem_mmap results: parsing output: 19/ for test: kms_frontbuffer_tracking results: parsing output: 20/ for test: kms_big_fb results: parsing output: 21/ for test: i915_hangman results: parsing output: 22/ for test: syncobj_wait results: parsing output: 23/ for test: kms_async_flips results: parsing output: 24/ for test: gem_ctx_persistence results: parsing output: 25/ for test: kms_color_pipeline results: parsing output: 26/ for test: gem_exec_schedule results: parsing output: 27/ for test: kms_big_fb results: parsing output: 28/ for test: kms_frontbuffer_tracking results: parsing output: 29/ for test: kms_cursor_crc results: parsing output: 30/ for test: kms_frontbuffer_tracking results: parsing output: 31/ for test: kms_invalid_mode results: parsing output: 32/ for test: gem_ccs results: parsing output: 33/ for test: kms_psr results: parsing output: 34/ for test: gem_exec_schedule results: parsing output: 35/ for test: kms_pipe_crc_basic results: parsing output: 36/ for test: kms_big_fb results: parsing output: 37/ for test: kms_chamelium_edid results: parsing output: 38/ for test: gem_exec_flush results: parsing output: 39/ for test: kms_frontbuffer_tracking results: parsing output: 40/ for test: kms_flip results: parsing output: 41/ for test: kms_frontbuffer_tracking results: parsing output: 42/ for test: gem_pxp results: parsing output: 43/ for test: gem_exec_reloc results: parsing output: 44/ for test: kms_ccs results: parsing output: 45/ for test: kms_dsc results: parsing output: 46/ for test: gem_tiled_swapping results: parsing output: 47/ for test: kms_big_fb results: parsing output: 48/ for test: kms_plane results: parsing output: 49/ for test: gen7_exec_parse results: parsing output: 50/ for test: kms_frontbuffer_tracking results: parsing output: 51/ for test: api_intel_bb results: parsing output: 52/ for test: kms_frontbuffer_tracking results: parsing output: 53/ for test: gem_exec_params results: parsing output: 54/ for test: sysfs_heartbeat_interval results: parsing output: 55/ for test: core_setmaster results: parsing output: 56/ for test: i915_drm_fdinfo results: parsing output: 57/ for test: gem_render_copy results: parsing output: 58/ for test: kms_pm_rpm results: parsing output: 59/ for test: gem_exec_balancer results: parsing output: 60/ for test: gem_mmap_gtt results: parsing output: 61/ for test: kms_psr2_sf results: parsing output: 62/ for test: kms_frontbuffer_tracking results: parsing output: 63/ for test: kms_frontbuffer_tracking results: parsing output: 64/ for test: kms_frontbuffer_tracking results: parsing output: 65/ for test: gem_render_copy results: parsing output: 66/ for test: kms_psr2_sf results: parsing output: 67/ for test: gem_caching results: parsing output: 68/ for test: kms_pm_rpm results: parsing output: 69/ for test: syncobj_timeline results: parsing output: 70/ for test: syncobj_wait results: parsing output: 71/ for test: kms_vblank results: parsing output: 72/ for test: kms_frontbuffer_tracking results: parsing output: 73/ for test: kms_big_fb results: parsing output: 74/ for test: syncobj_timeline results: parsing output: 75/ for test: kms_frontbuffer_tracking results: parsing output: 76/ for test: gem_ctx_sseu results: parsing output: 77/ for test: kms_frontbuffer_tracking results: parsing output: 78/ for test: gem_softpin results: parsing output: 79/ for test: kms_frontbuffer_tracking results: parsing output: 80/ for test: syncobj_basic results: parsing output: 81/ for test: gem_partial_pwrite_pread results: parsing output: 82/ for test: sw_sync results: parsing output: 83/ for test: kms_frontbuffer_tracking results: parsing output: 84/ for test: kms_frontbuffer_tracking results: parsing output: 85/ for test: i915_pm_rps results: parsing output: 86/ for test: gem_ctx_persistence results: parsing output: 87/ for test: kms_frontbuffer_tracking results: parsing output: 88/ for test: kms_frontbuffer_tracking results: parsing output: 89/ for test: kms_frontbuffer_tracking results: parsing output: 90/ for test: kms_frontbuffer_tracking results: parsing output: 91/ for test: syncobj_wait results: parsing output: 92/ for test: kms_universal_plane results: parsing output: 93/ for test: gem_wait results: parsing output: 94/ for test: kms_content_protection results: parsing output: 95/ for test: gem_ccs results: parsing output: 96/ for test: kms_cursor_crc results: parsing output: 97/ for test: kms_plane_scaling results: parsing output: 98/ for test: gem_mmap results: parsing output: 99/ for test: kms_plane results: parsing output: 100/ for test: gem_ctx_create results: parsing output: 101/ for test: syncobj_timeline results: parsing output: 102/ for test: kms_async_flips results: parsing output: 103/ for test: i915_power results: parsing output: 104/ for test: perf_pmu results: parsing output: 105/ for test: kms_chamelium_audio results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: syncobj_timeline results: parsing output: 108/ for test: gem_exec_fence results: parsing output: 109/ for test: kms_force_connector_basic results: parsing output: 110/ for test: kms_ccs results: parsing output: 111/ for test: kms_frontbuffer_tracking results: parsing output: 112/ for test: syncobj_timeline results: parsing output: 113/ for test: kms_frontbuffer_tracking results: parsing output: 114/ for test: kms_cursor_legacy results: parsing output: 115/ for test: gem_mmap_wc results: parsing output: 116/ for test: kms_cursor_legacy results: parsing output: 117/ for test: sriov_basic results: parsing output: 118/ for test: kms_psr results: parsing output: 119/ for test: kms_frontbuffer_tracking results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: kms_legacy_colorkey results: parsing output: 122/ for test: kms_frontbuffer_tracking results: parsing output: 123/ for test: gem_exec_whisper results: parsing output: 124/ for test: gem_mmap_gtt results: parsing output: 125/ for test: kms_frontbuffer_tracking results: parsing output: 126/ for test: kms_frontbuffer_tracking results: parsing output: 127/ for test: kms_frontbuffer_tracking results: parsing output: 128/ for test: api_intel_bb results: parsing output: 129/ for test: kms_flip results: parsing output: 130/ for test: gem_exec_schedule results: parsing output: 131/ for test: kms_frontbuffer_tracking results: parsing output: 132/ for test: perf results: parsing output: 133/ for test: kms_hdr results: parsing output: 134/ for test: kms_frontbuffer_tracking results: parsing output: 135/ for test: kms_busy results: parsing output: 136/ for test: gem_mmap results: parsing output: 137/ for test: sw_sync results: parsing output: 138/ for test: kms_frontbuffer_tracking results: parsing output: 139/ for test: kms_getfb results: parsing output: 140/ for test: kms_frontbuffer_tracking results: parsing output: 141/ for test: kms_setmode results: parsing output: 142/ for test: gem_exec_reloc results: parsing output: 143/ for test: kms_cursor_legacy results: parsing output: 144/ for test: gem_render_copy results: parsing output: 145/ for test: gem_exec_whisper results: parsing output: 146/ for test: kms_frontbuffer_tracking results: parsing output: 147/ for test: kms_psr results: parsing output: 148/ for test: kms_atomic_transition results: parsing output: 149/ for test: kms_big_fb results: parsing output: 150/ for test: perf results: parsing output: 151/ for test: kms_frontbuffer_tracking results: parsing output: 152/ for test: kms_ccs results: parsing output: 153/ for test: kms_cursor_crc results: parsing output: 154/ for test: kms_flip_scaled_crc results: parsing output: 155/ for test: kms_psr Done.