[55.586989] Initializing watchdogs [55.587048] /dev/watchdog0 [55.594525] [001/130] (960s left) gem_sync (basic-store-each) [55.669598] Starting subtest: basic-store-each [58.097089] Subtest basic-store-each: SUCCESS (2.464s) [58.260640] [002/130] (957s left) perf_pmu (init-busy) [58.299780] Starting subtest: init-busy [58.302256] Starting dynamic subtest: rcs0 [58.304759] Dynamic subtest rcs0: SUCCESS (0.002s) [58.314916] Starting dynamic subtest: bcs0 [58.316842] Dynamic subtest bcs0: SUCCESS (0.000s) [58.322998] Starting dynamic subtest: vcs0 [58.325297] Dynamic subtest vcs0: SUCCESS (0.001s) [58.330951] Starting dynamic subtest: vcs1 [58.332931] Dynamic subtest vcs1: SUCCESS (0.001s) [58.338386] Starting dynamic subtest: vecs0 [58.340290] Dynamic subtest vecs0: SUCCESS (0.001s) [58.345507] Starting dynamic subtest: ccs0 [58.347419] Dynamic subtest ccs0: SUCCESS (0.000s) [58.349374] Subtest init-busy: SUCCESS (0.005s) [58.412052] [003/130] (957s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted) [58.475226] Starting subtest: invalid-multi-wait-unsubmitted-submitted [58.477631] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s) [58.565241] [004/130] (957s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt) [58.679170] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt [58.683194] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [58.762285] [005/130] (957s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc) [58.861625] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc [63.570092] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.742s) [63.672457] [006/130] (952s left) i915_pm_rc6_residency (rc6-idle) [63.740187] Starting subtest: rc6-idle [63.766127] Starting dynamic subtest: gt0-rcs0 [75.983976] Dynamic subtest gt0-rcs0: SUCCESS (12.243s) [75.990355] Starting dynamic subtest: gt0-bcs0 [88.236059] Dynamic subtest gt0-bcs0: SUCCESS (12.256s) [88.242560] Starting dynamic subtest: gt0-ccs0 [100.489757] Dynamic subtest gt0-ccs0: SUCCESS (12.254s) [100.498073] Starting dynamic subtest: gt1-vcs0 [112.710092] Dynamic subtest gt1-vcs0: SUCCESS (12.219s) [112.717218] Starting dynamic subtest: gt1-vecs0 [125.153981] Dynamic subtest gt1-vecs0: SUCCESS (12.444s) [125.156929] Subtest rc6-idle: SUCCESS (61.445s) [125.883729] [007/130] (890s left) drm_fdinfo (virtual-busy-idle) [125.925766] Starting subtest: virtual-busy-idle [125.930258] Subtest virtual-busy-idle: SKIP (0.000s) [125.971629] [008/130] (890s left) gen9_exec_parse (valid-registers) [126.029301] Subtest valid-registers: SKIP (0.000s) [126.087228] [009/130] (890s left) i915_query (query-topology-known-pci-ids) [126.144062] Starting subtest: query-topology-known-pci-ids [126.148715] Subtest query-topology-known-pci-ids: SKIP (0.000s) [126.226114] [010/130] (889s left) syncobj_timeline (single-wait-for-submit-available-signaled) [126.278789] Starting subtest: single-wait-for-submit-available-signaled [126.280712] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s) [126.367154] [011/130] (889s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite) [126.480315] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite [126.484783] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [126.566350] [012/130] (889s left) sw_sync (timeline_closed_signaled) [126.604613] Starting subtest: timeline_closed_signaled [126.607056] Subtest timeline_closed_signaled: SUCCESS (0.000s) [126.629684] [013/130] (889s left) kms_psr (fbc-psr-basic) [126.720402] Starting subtest: fbc-psr-basic [126.722816] Starting dynamic subtest: eDP-1 [126.727141] Dynamic subtest eDP-1: SKIP (0.000s) [126.731436] Subtest fbc-psr-basic: SKIP (0.000s) [126.770487] [014/130] (889s left) kms_big_fb (yf-tiled-16bpp-rotate-90) [126.872048] Starting subtest: yf-tiled-16bpp-rotate-90 [126.875799] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s) [126.956965] [015/130] (889s left) gem_userptr_blits (relocations) [127.023482] Starting subtest: relocations [127.027939] Subtest relocations: SKIP (0.001s) [127.155374] [016/130] (889s left) syncobj_wait (invalid-reset-illegal-handle) [127.212182] Starting subtest: invalid-reset-illegal-handle [127.213838] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s) [127.292605] [017/130] (888s left) kms_psr (psr2-suspend) [127.382760] Starting subtest: psr2-suspend [127.384948] Starting dynamic subtest: eDP-1 [145.481064] Dynamic subtest eDP-1: SUCCESS (2.923s) [145.483479] Subtest psr2-suspend: SUCCESS (2.923s) [146.232260] [018/130] (885s left) kms_lease (setcrtc-implicit-plane) [146.332909] Starting subtest: setcrtc-implicit-plane [146.335197] Starting dynamic subtest: pipe-A-eDP-1 [146.774230] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.447s) [146.787386] Starting dynamic subtest: pipe-B-eDP-1 [147.836519] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.083s) [147.845502] Starting dynamic subtest: pipe-C-eDP-1 [148.938572] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s) [148.952996] Starting dynamic subtest: pipe-D-eDP-1 [150.047077] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s) [150.049442] Subtest setcrtc-implicit-plane: SUCCESS (3.777s) [150.855901] [019/130] (880s left) kms_cursor_crc (cursor-size-change) [150.950145] Starting subtest: cursor-size-change [150.952467] Starting dynamic subtest: pipe-A-eDP-1 [151.749327] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.806s) [151.756630] Starting dynamic subtest: pipe-D-eDP-1 [153.184087] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.449s) [153.186506] Subtest cursor-size-change: SUCCESS (2.257s) [153.975774] [020/130] (877s left) kms_joiner (invalid-modeset-force-ultra-joiner) [154.077665] Starting subtest: invalid-modeset-force-ultra-joiner [154.082178] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s) [154.120976] [021/130] (877s left) kms_dp_aux_dev [154.465733] [022/130] (876s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu) [154.573877] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu [154.578448] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [154.663669] [023/130] (876s left) kms_pm_rpm (modeset-stress-extra-wait) [156.185868] Starting subtest: modeset-stress-extra-wait [210.380674] Subtest modeset-stress-extra-wait: SUCCESS (54.245s) [210.446321] [024/130] (820s left) gem_render_copy (y-tiled-to-vebox-linear) [210.505116] Starting subtest: y-tiled-to-vebox-linear [210.509616] Subtest y-tiled-to-vebox-linear: SKIP (0.000s) [210.667209] [025/130] (820s left) gem_exec_schedule (semaphore-power) [210.851910] Subtest semaphore-power: SKIP (0.000s) [211.010109] [026/130] (820s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs) [211.101812] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs [211.104090] Starting dynamic subtest: pipe-A-eDP-1 [211.126923] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [211.134987] Starting dynamic subtest: pipe-B-eDP-1 [211.155863] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [211.162625] Starting dynamic subtest: pipe-C-eDP-1 [211.183956] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [211.190569] Starting dynamic subtest: pipe-D-eDP-1 [211.212981] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [211.217329] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.002s) [213.269268] [027/130] (818s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180) [213.378361] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180 [213.382854] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s) [213.443299] [028/130] (818s left) prime_vgem (fence-flip-hang) [213.552460] Subtest fence-flip-hang: SKIP (0.000s) [213.633567] [029/130] (817s left) syncobj_eventfd (binary-wait) [213.681745] Starting subtest: binary-wait [213.691639] Subtest binary-wait: SUCCESS (0.010s) [213.759335] [030/130] (817s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling) [213.860963] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling [213.863250] Starting dynamic subtest: pipe-A-default-mode [214.154783] Dynamic subtest pipe-A-default-mode: SKIP (0.283s) [214.159689] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.284s) [214.990974] [031/130] (816s left) kms_plane_scaling (planes-downscale-factor-0-75) [215.085453] Starting subtest: planes-downscale-factor-0-75 [215.087882] Starting dynamic subtest: pipe-A [215.105358] Dynamic subtest pipe-A: SKIP (0.015s) [215.115902] Starting dynamic subtest: pipe-B [215.131573] Dynamic subtest pipe-B: SKIP (0.026s) [215.139323] Starting dynamic subtest: pipe-C [215.155870] Dynamic subtest pipe-C: SKIP (0.024s) [215.163144] Starting dynamic subtest: pipe-D [215.180424] Dynamic subtest pipe-D: SKIP (0.024s) [215.184173] Subtest planes-downscale-factor-0-75: SKIP (0.090s) [215.270629] [032/130] (816s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip) [215.387507] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip [215.391785] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s) [215.473250] [033/130] (816s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff) [215.580179] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff [218.514428] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (2.940s) [218.628434] [034/130] (812s left) gem_ctx_exec (basic-close-race) [218.686481] Starting subtest: basic-close-race [224.307124] Subtest basic-close-race: SUCCESS (5.622s) [224.408158] [035/130] (807s left) kms_flip (2x-plain-flip-fb-recreate) [224.517321] Starting subtest: 2x-plain-flip-fb-recreate [224.527696] Subtest 2x-plain-flip-fb-recreate: SKIP (0.010s) [224.587630] [036/130] (807s left) kms_big_fb (4-tiled-64bpp-rotate-270) [224.700330] Starting subtest: 4-tiled-64bpp-rotate-270 [224.724333] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.021s) [224.813738] [037/130] (806s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt) [224.916813] Starting subtest: psr-rgb101010-draw-mmap-gtt [224.921275] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s) [225.009336] [038/130] (806s left) gem_ctx_isolation (preservation) [225.070518] Starting subtest: preservation [225.072961] Starting dynamic subtest: rcs0 [225.202782] Dynamic subtest rcs0: SUCCESS (0.132s) [225.213297] Starting dynamic subtest: bcs0 [225.382761] Dynamic subtest bcs0: SUCCESS (0.180s) [225.393104] Starting dynamic subtest: vcs0 [225.555076] Dynamic subtest vcs0: SUCCESS (0.172s) [225.566317] Starting dynamic subtest: vcs1 [227.247257] Dynamic subtest vcs1: SUCCESS (1.693s) [227.255387] Starting dynamic subtest: vecs0 [229.242287] Dynamic subtest vecs0: SUCCESS (1.996s) [229.251345] Starting dynamic subtest: ccs0 [231.217388] Dynamic subtest ccs0: SUCCESS (1.976s) [231.220523] Subtest preservation: SUCCESS (6.149s) [231.463773] [039/130] (800s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs) [231.562946] Starting subtest: random-ccs-data-4-tiled-lnl-ccs [231.567485] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s) [232.625291] [040/130] (799s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc) [232.728596] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc [232.732831] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [232.822663] [041/130] (798s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic) [232.940361] Starting subtest: 2x-cursor-vs-flip-atomic [232.944824] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s) [233.055728] [042/130] (798s left) syncobj_timeline (wait-any-interrupted) [233.119691] Starting subtest: wait-any-interrupted [233.228948] Subtest wait-any-interrupted: SUCCESS (0.108s) [233.284168] [043/130] (798s left) kms_psr (psr-primary-blt) [233.395197] Starting subtest: psr-primary-blt [233.397563] Starting dynamic subtest: eDP-1 [235.138596] Dynamic subtest eDP-1: SUCCESS (1.741s) [235.140632] Subtest psr-primary-blt: SUCCESS (1.741s) [235.977744] [044/130] (795s left) i915_pm_rpm (system-suspend-devices) [238.303866] Starting subtest: system-suspend-devices [245.871127] Subtest system-suspend-devices: SUCCESS (7.570s) [246.206821] [045/130] (785s left) kms_frontbuffer_tracking (fbc-suspend) [246.308890] Starting subtest: fbc-suspend [282.204230] Subtest fbc-suspend: SUCCESS (6.046s) [282.308290] [046/130] (779s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc) [282.409162] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc [282.411467] Starting dynamic subtest: pipe-A-eDP-1 [282.433968] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [282.441776] Starting dynamic subtest: pipe-B-eDP-1 [282.462420] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [282.469022] Starting dynamic subtest: pipe-C-eDP-1 [282.490399] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [282.497090] Starting dynamic subtest: pipe-D-eDP-1 [282.518565] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [282.522942] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s) [284.566676] [047/130] (777s left) gem_sync (basic-store-all) [284.651877] Starting subtest: basic-store-all [287.130281] Subtest basic-store-all: SUCCESS (2.514s) [287.294349] [048/130] (774s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-gtt) [287.403839] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-gtt [287.408459] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [287.495678] [049/130] (774s left) kms_cursor_legacy (cursora-vs-flipb-atomic) [287.622650] Starting subtest: cursorA-vs-flipB-atomic [287.627241] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s) [287.712008] [050/130] (773s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling) [287.809255] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling [287.811449] Starting dynamic subtest: pipe-A-default-mode [290.422344] Dynamic subtest pipe-A-default-mode: SUCCESS (2.630s) [290.424759] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.631s) [291.181138] [051/130] (770s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt) [291.277399] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt [291.281346] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [291.346800] [052/130] (770s left) gem_mmap_gtt (basic-read-write) [291.403737] Subtest basic-read-write: SKIP (0.000s) [291.448355] [053/130] (770s left) gem_render_copy (x-tiled-to-vebox-y-tiled) [291.508219] Starting subtest: x-tiled-to-vebox-y-tiled [291.512514] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s) [291.671046] [054/130] (770s left) kms_prop_blob (blob-prop-lifetime) [291.732317] Starting subtest: blob-prop-lifetime [291.734647] Subtest blob-prop-lifetime: SUCCESS (0.001s) [291.808494] [055/130] (769s left) kms_plane (plane-position-covered) [291.899108] Starting subtest: plane-position-covered [292.399091] Starting dynamic subtest: pipe-A-plane-1 [293.624569] Dynamic subtest pipe-A-plane-1: SUCCESS (1.231s) [293.635057] Starting dynamic subtest: pipe-A-plane-2 [294.852329] Dynamic subtest pipe-A-plane-2: SUCCESS (1.232s) [294.862084] Starting dynamic subtest: pipe-A-plane-3 [296.079024] Dynamic subtest pipe-A-plane-3: SUCCESS (1.231s) [296.087974] Starting dynamic subtest: pipe-A-plane-4 [297.293025] Dynamic subtest pipe-A-plane-4: SUCCESS (1.216s) [297.302865] Starting dynamic subtest: pipe-A-plane-5 [297.316234] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s) [298.454089] Starting dynamic subtest: pipe-B-plane-1 [299.660220] Dynamic subtest pipe-B-plane-1: SUCCESS (1.216s) [299.670940] Starting dynamic subtest: pipe-B-plane-2 [300.875124] Dynamic subtest pipe-B-plane-2: SUCCESS (1.218s) [300.884010] Starting dynamic subtest: pipe-B-plane-3 [302.109115] Dynamic subtest pipe-B-plane-3: SUCCESS (1.232s) [302.118326] Starting dynamic subtest: pipe-B-plane-4 [303.332733] Dynamic subtest pipe-B-plane-4: SUCCESS (1.227s) [303.340805] Starting dynamic subtest: pipe-B-plane-5 [303.357582] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s) [303.359788] Subtest plane-position-covered: SUCCESS (11.488s) [304.119777] [056/130] (757s left) gem_pread (self) [304.185065] Subtest self: SKIP (0.000s) [304.227297] [057/130] (757s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted) [304.290040] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted [304.395889] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.104s) [304.451078] [058/130] (757s left) gem_ctx_shared (q-in-order) [304.529123] Starting subtest: Q-in-order [304.531575] Starting dynamic subtest: rcs0 [304.629710] Dynamic subtest rcs0: SUCCESS (0.100s) [304.640019] Starting dynamic subtest: vcs0 [304.706940] Dynamic subtest vcs0: SUCCESS (0.077s) [304.723026] Starting dynamic subtest: vcs1 [304.782019] Dynamic subtest vcs1: SUCCESS (0.074s) [304.797927] Starting dynamic subtest: vecs0 [304.856365] Dynamic subtest vecs0: SUCCESS (0.073s) [304.870909] Starting dynamic subtest: ccs0 [304.927833] Dynamic subtest ccs0: SUCCESS (0.071s) [304.930438] Subtest Q-in-order: SUCCESS (0.400s) [306.082168] [059/130] (755s left) gem_ctx_shared (single-timeline) [306.144353] Starting subtest: single-timeline [306.165161] Subtest single-timeline: SUCCESS (0.012s) [306.371862] [060/130] (755s left) kms_properties (invalid-properties-atomic) [306.474287] Starting subtest: invalid-properties-atomic [306.713612] Subtest invalid-properties-atomic: SUCCESS (0.238s) [306.766873] [061/130] (755s left) gem_exec_reloc (basic-wc-read) [306.823610] Subtest basic-wc-read: SKIP (0.000s) [306.865716] [062/130] (754s left) kms_content_protection (legacy) [307.002067] Starting subtest: legacy [310.840256] Subtest legacy: SKIP (3.838s) [312.019923] [063/130] (749s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs) [312.117313] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs [312.119686] Starting dynamic subtest: pipe-A-eDP-1 [312.129152] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [312.138410] Starting dynamic subtest: pipe-B-eDP-1 [312.147051] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [312.154671] Starting dynamic subtest: pipe-C-eDP-1 [312.163363] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [312.170669] Starting dynamic subtest: pipe-D-eDP-1 [312.179482] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [312.183684] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s) [314.271018] [064/130] (747s left) kms_chamelium_frames (dp-frame-dump) [314.679478] Subtest dp-frame-dump: SKIP (0.000s) [315.478277] [065/130] (746s left) gem_partial_pwrite_pread (reads-uncached) [315.543633] Subtest reads-uncached: SKIP (0.000s) [315.595433] [066/130] (746s left) kms_psr (pr-primary-render) [315.692403] Starting subtest: pr-primary-render [315.696737] Subtest pr-primary-render: SKIP (0.000s) [315.746218] [067/130] (746s left) kms_vblank (ts-continuation-modeset-hang) [315.848038] Starting subtest: ts-continuation-modeset-hang [315.850223] Starting dynamic subtest: pipe-A-eDP-1 [343.736126] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.889s) [343.750233] Starting dynamic subtest: pipe-D-eDP-1 [358.786160] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.054s) [358.788525] Subtest ts-continuation-modeset-hang: SUCCESS (42.948s) [359.596860] [068/130] (702s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [359.708759] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc [359.712895] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [359.804183] [069/130] (702s left) gem_exec_basic (basic) [359.867459] Starting subtest: basic [359.869658] Starting dynamic subtest: rcs0-smem [359.874072] Dynamic subtest rcs0-smem: SUCCESS (0.005s) [359.882260] Starting dynamic subtest: bcs0-smem [359.884472] Dynamic subtest bcs0-smem: SUCCESS (0.002s) [359.891690] Starting dynamic subtest: vcs0-smem [359.893855] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [359.900995] Starting dynamic subtest: vcs1-smem [359.903273] Dynamic subtest vcs1-smem: SUCCESS (0.002s) [359.910397] Starting dynamic subtest: vecs0-smem [359.912534] Dynamic subtest vecs0-smem: SUCCESS (0.002s) [359.919682] Starting dynamic subtest: ccs0-smem [359.921869] Dynamic subtest ccs0-smem: SUCCESS (0.001s) [359.924080] Subtest basic: SUCCESS (0.017s) [360.987102] [070/130] (700s left) gem_vm_create (invalid-create) [361.059518] Starting subtest: invalid-create [361.062000] Subtest invalid-create: SUCCESS (0.002s) [361.156429] [071/130] (700s left) gem_exec_params (rel-constants-invalid) [361.222827] Starting subtest: rel-constants-invalid [361.225057] Subtest rel-constants-invalid: SUCCESS (0.000s) [361.299130] [072/130] (700s left) syncobj_wait (invalid-signal-zero-handles) [361.363906] Starting subtest: invalid-signal-zero-handles [361.366445] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [361.453096] [073/130] (700s left) syncobj_wait (multi-wait-all-signaled) [361.504510] Starting subtest: multi-wait-all-signaled [361.506433] Subtest multi-wait-all-signaled: SUCCESS (0.000s) [361.578476] [074/130] (700s left) gem_partial_pwrite_pread (write) [361.634157] Subtest write: SKIP (0.000s) [361.703589] [075/130] (700s left) api_intel_allocator (fork-simple-stress) [361.774861] Starting subtest: fork-simple-stress [367.178062] Subtest fork-simple-stress: SUCCESS (5.405s) [367.281227] [076/130] (694s left) kms_cursor_crc (cursor-sliding-32x10) [367.439610] Starting subtest: cursor-sliding-32x10 [367.476257] Subtest cursor-sliding-32x10: SKIP (0.032s) [367.724321] [077/130] (694s left) gem_mmap_gtt (basic) [367.789793] Subtest basic: SKIP (0.000s) [367.834253] [078/130] (694s left) kms_ccs (crc-primary-basic-4-tiled-dg2-mc-ccs) [367.931291] Starting subtest: crc-primary-basic-4-tiled-dg2-mc-ccs [367.933524] Starting dynamic subtest: pipe-A-eDP-1 [367.955997] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [367.966551] Starting dynamic subtest: pipe-B-eDP-1 [367.988017] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [367.996159] Starting dynamic subtest: pipe-C-eDP-1 [368.018173] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [368.026887] Starting dynamic subtest: pipe-D-eDP-1 [368.049768] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [368.054087] Subtest crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.005s) [370.142447] [079/130] (691s left) gem_eio (wait-10ms) [370.367235] Starting subtest: wait-10ms [370.697414] Subtest wait-10ms: SUCCESS (0.331s) [370.798916] [080/130] (691s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc) [370.906544] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc [370.910440] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [370.982530] [081/130] (691s left) kms_scaling_modes (scaling-mode-full) [371.080464] Starting subtest: scaling-mode-full [371.082887] Starting dynamic subtest: pipe-A-eDP-1 [371.179642] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.099s) [371.192133] Starting dynamic subtest: pipe-B-eDP-1 [372.379545] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.197s) [372.391316] Starting dynamic subtest: pipe-C-eDP-1 [373.483002] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.103s) [373.493703] Starting dynamic subtest: pipe-D-eDP-1 [374.610359] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s) [374.612697] Subtest scaling-mode-full: SUCCESS (3.532s) [375.735555] [082/130] (686s left) syncobj_timeline (host-signal-ordered) [375.789974] Starting subtest: host-signal-ordered [375.791946] Subtest host-signal-ordered: SUCCESS (0.001s) [375.856653] [083/130] (686s left) gem_userptr_blits (sync-overlap) [375.928701] Starting subtest: sync-overlap [375.930930] Subtest sync-overlap: SUCCESS (0.000s) [376.044745] [084/130] (686s left) gem_spin_batch (resubmit) [376.103258] Starting subtest: resubmit [376.105391] Starting dynamic subtest: rcs0 [376.109568] Dynamic subtest rcs0: SUCCESS (0.006s) [376.117829] Starting dynamic subtest: bcs0 [376.120002] Dynamic subtest bcs0: SUCCESS (0.002s) [376.128063] Starting dynamic subtest: vcs0 [376.130219] Dynamic subtest vcs0: SUCCESS (0.002s) [376.137829] Starting dynamic subtest: vcs1 [376.139955] Dynamic subtest vcs1: SUCCESS (0.003s) [376.147609] Starting dynamic subtest: vecs0 [376.149787] Dynamic subtest vecs0: SUCCESS (0.002s) [376.157408] Starting dynamic subtest: ccs0 [376.159582] Dynamic subtest ccs0: SUCCESS (0.002s) [376.161800] Subtest resubmit: SUCCESS (0.021s) [376.335133] [085/130] (685s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu) [376.449351] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu [376.453356] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [376.547274] [086/130] (685s left) kms_vrr (seamless-rr-switch-vrr) [376.648277] Starting subtest: seamless-rr-switch-vrr [376.654645] Subtest seamless-rr-switch-vrr: SKIP (0.000s) [376.709412] [087/130] (685s left) kms_psr (fbc-psr2-cursor-blt) [376.825066] Starting subtest: fbc-psr2-cursor-blt [376.827067] Starting dynamic subtest: eDP-1 [376.830904] Dynamic subtest eDP-1: SKIP (0.000s) [376.834218] Subtest fbc-psr2-cursor-blt: SKIP (0.001s) [376.880668] [088/130] (685s left) kms_psr (fbc-pr-cursor-render) [376.968256] Starting subtest: fbc-pr-cursor-render [376.972054] Subtest fbc-pr-cursor-render: SKIP (0.000s) [377.025284] [089/130] (685s left) gem_exec_whisper (basic-queues-priority-all) [377.087951] Starting subtest: basic-queues-priority-all [399.325383] Subtest basic-queues-priority-all: SUCCESS (22.227s) [399.493314] [090/130] (662s left) kms_big_fb (linear-32bpp-rotate-0) [399.605859] Starting subtest: linear-32bpp-rotate-0 [401.045368] Subtest linear-32bpp-rotate-0: SUCCESS (1.436s) [401.166333] [091/130] (661s left) gem_userptr_blits (readonly-unsync) [401.238801] Subtest readonly-unsync: SKIP (0.000s) [401.355654] [092/130] (661s left) sw_sync (sync_multi_producer_single_consumer) [401.391632] Starting subtest: sync_multi_producer_single_consumer [402.465685] Subtest sync_multi_producer_single_consumer: SUCCESS (1.076s) [402.506582] [093/130] (659s left) kms_chamelium_hpd (vga-hpd) [402.904606] Subtest vga-hpd: SKIP (0.000s) [403.653257] [094/130] (658s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move) [403.765736] Starting subtest: psr-1p-primscrn-cur-indfb-move [408.631224] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (4.868s) [408.738866] [095/130] (653s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area) [413.685307] Starting subtest: fbc-psr2-plane-move-sf-dmg-area [413.687503] Starting dynamic subtest: pipe-A-eDP-1 [414.480436] Dynamic subtest pipe-A-eDP-1: SKIP (0.791s) [414.489924] Starting dynamic subtest: pipe-B-eDP-1 [415.607407] Dynamic subtest pipe-B-eDP-1: SKIP (1.125s) [415.611981] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.920s) [416.766401] [096/130] (645s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen) [416.876739] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen [416.880949] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [416.960325] [097/130] (645s left) syncobj_timeline (multi-wait-all-signaled) [417.015131] Starting subtest: multi-wait-all-signaled [417.017671] Subtest multi-wait-all-signaled: SUCCESS (0.001s) [417.112915] [098/130] (645s left) gem_busy (busy) [417.174705] Starting subtest: busy [417.177027] Starting dynamic subtest: all-engines [417.251815] Dynamic subtest all-engines: SUCCESS (0.077s) [417.262696] Starting dynamic subtest: rcs0 [419.024112] Dynamic subtest rcs0: SUCCESS (1.771s) [419.035360] Starting dynamic subtest: bcs0 [419.086784] Dynamic subtest bcs0: SUCCESS (0.060s) [419.096668] Starting dynamic subtest: vcs0 [419.126275] Dynamic subtest vcs0: SUCCESS (0.038s) [419.135576] Starting dynamic subtest: vcs1 [421.006067] Dynamic subtest vcs1: SUCCESS (1.876s) [421.016361] Starting dynamic subtest: vecs0 [423.053047] Dynamic subtest vecs0: SUCCESS (2.048s) [423.063432] Starting dynamic subtest: ccs0 [425.036289] Dynamic subtest ccs0: SUCCESS (1.982s) [425.038942] Subtest busy: SUCCESS (7.859s) [425.383483] [099/130] (637s left) kms_pm_lpsp (kms-lpsp) [426.495560] Starting subtest: kms-lpsp [426.497912] Starting dynamic subtest: pipe-A-eDP-1 [426.629760] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.135s) [426.925339] Subtest kms-lpsp: SUCCESS (0.431s) [427.692824] [100/130] (634s left) gem_exec_balancer (individual) [427.754472] Starting subtest: individual [429.032594] Subtest individual: SUCCESS (1.277s) [429.317207] [101/130] (633s left) gem_softpin (evict-snoop) [429.371595] Starting subtest: evict-snoop [429.376154] Subtest evict-snoop: SKIP (0.000s) [429.465998] [102/130] (633s left) gem_render_copy (y-tiled-ccs-to-x-tiled) [429.532145] Starting subtest: y-tiled-ccs-to-x-tiled [429.536756] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s) [429.695414] [103/130] (632s left) kms_hdr (static-toggle) [429.792189] Starting subtest: static-toggle [429.798462] Subtest static-toggle: SKIP (0.000s) [429.846660] [104/130] (632s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling) [429.950224] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling [429.952439] Starting dynamic subtest: pipe-A-default-mode [430.242704] Dynamic subtest pipe-A-default-mode: SKIP (0.286s) [430.247432] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.287s) [431.013044] [105/130] (631s left) perf_pmu (most-busy-check-all) [431.059115] Starting subtest: most-busy-check-all [431.061482] Starting dynamic subtest: rcs0 [433.033230] Dynamic subtest rcs0: SUCCESS (1.972s) [433.046842] Starting dynamic subtest: bcs0 [435.016214] Dynamic subtest bcs0: SUCCESS (1.981s) [435.028448] Starting dynamic subtest: vcs0 [437.000748] Dynamic subtest vcs0: SUCCESS (1.982s) [437.012469] Starting dynamic subtest: vcs1 [438.983017] Dynamic subtest vcs1: SUCCESS (1.981s) [438.994486] Starting dynamic subtest: vecs0 [441.032847] Dynamic subtest vecs0: SUCCESS (2.048s) [441.045011] Starting dynamic subtest: ccs0 [443.017768] Dynamic subtest ccs0: SUCCESS (1.982s) [443.020729] Subtest most-busy-check-all: SUCCESS (11.959s) [443.104249] [106/130] (619s left) kms_big_fb (x-tiled-32bpp-rotate-90) [443.225455] Starting subtest: x-tiled-32bpp-rotate-90 [443.245328] Subtest x-tiled-32bpp-rotate-90: SKIP (0.019s) [443.360627] [107/130] (619s left) kms_big_fb (4-tiled-8bpp-rotate-180) [443.467513] Starting subtest: 4-tiled-8bpp-rotate-180 [444.546944] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.079s) [444.671557] [108/130] (618s left) syncobj_basic (bad-pad-handle-to-fd) [444.724001] Starting subtest: bad-pad-handle-to-fd [444.726353] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s) [444.809268] [109/130] (617s left) kms_addfb_basic (invalid-smem-bo-on-discrete) [444.874198] Starting subtest: invalid-smem-bo-on-discrete [444.878083] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s) [444.989861] [110/130] (617s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render) [445.096245] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render [445.099678] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [445.188795] [111/130] (617s left) kms_cursor_crc (cursor-random-128x128) [445.374448] Starting subtest: cursor-random-128x128 [445.378453] Starting dynamic subtest: pipe-A-eDP-1 [448.684565] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.307s) [448.707951] Starting dynamic subtest: pipe-D-eDP-1 [452.706260] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.005s) [452.708471] Subtest cursor-random-128x128: SUCCESS (7.332s) [453.561524] [112/130] (609s left) gem_exec_gttfill (engines) [453.630159] Starting subtest: engines [453.632661] Starting dynamic subtest: rcs0 [476.463533] Dynamic subtest rcs0: SUCCESS (22.838s) [476.475379] Starting dynamic subtest: bcs0 [499.991088] Dynamic subtest bcs0: SUCCESS (23.532s) [500.000396] Starting dynamic subtest: vcs0 [523.550704] Dynamic subtest vcs0: SUCCESS (23.553s) [523.559480] Starting dynamic subtest: vcs1 [547.135641] Dynamic subtest vcs1: SUCCESS (23.577s) [547.151739] Starting dynamic subtest: vecs0 [570.672339] Dynamic subtest vecs0: SUCCESS (23.533s) [570.687689] Starting dynamic subtest: ccs0 [594.233663] Dynamic subtest ccs0: SUCCESS (23.560s) [594.236041] Subtest engines: SUCCESS (140.603s) [594.404141] [113/130] (468s left) gem_exec_schedule (noreorder-priority) [594.651872] Starting subtest: noreorder-priority [594.654355] Starting dynamic subtest: rcs0 [595.809218] Dynamic subtest rcs0: SUCCESS (1.155s) [595.818933] Starting dynamic subtest: vcs0 [597.031731] Dynamic subtest vcs0: SUCCESS (1.221s) [597.044362] Starting dynamic subtest: vcs1 [599.061430] Dynamic subtest vcs1: SUCCESS (2.029s) [599.073293] Starting dynamic subtest: vecs0 [601.054307] Dynamic subtest vecs0: SUCCESS (1.991s) [601.065644] Starting dynamic subtest: ccs0 [602.207948] Dynamic subtest ccs0: SUCCESS (1.153s) [602.210477] Subtest noreorder-priority: SUCCESS (7.557s) [602.374775] [114/130] (460s left) gem_mmap_offset (close-race) [602.435952] Starting subtest: close-race [622.573933] Subtest close-race: SUCCESS (20.141s) [622.689439] [115/130] (440s left) i915_query (hwconfig_table) [622.757070] Starting subtest: hwconfig_table [623.059504] Subtest hwconfig_table: SUCCESS (0.002s) [623.078410] [116/130] (439s left) kms_flip (plain-flip-ts-check) [623.192224] Starting subtest: plain-flip-ts-check [623.194548] Starting dynamic subtest: A-eDP1 [632.043511] Dynamic subtest A-eDP1: SUCCESS (8.852s) [632.055044] Starting dynamic subtest: B-eDP1 [640.860144] Dynamic subtest B-eDP1: SUCCESS (8.817s) [640.871096] Starting dynamic subtest: C-eDP1 [649.665835] Dynamic subtest C-eDP1: SUCCESS (8.805s) [649.677007] Starting dynamic subtest: D-eDP1 [658.489109] Dynamic subtest D-eDP1: SUCCESS (8.824s) [658.491524] Subtest plain-flip-ts-check: SUCCESS (35.305s) [659.633902] [117/130] (403s left) gem_eio (in-flight-10ms) [659.836156] Starting subtest: in-flight-10ms [667.816526] Subtest in-flight-10ms: SUCCESS (7.982s) [667.919008] [118/130] (395s left) kms_plane (pixel-format) [668.030217] Starting subtest: pixel-format [668.059051] Starting dynamic subtest: pipe-A-plane-0 [672.980096] Dynamic subtest pipe-A-plane-0: SUCCESS (4.922s) [672.988951] Starting dynamic subtest: pipe-A-plane-3 [675.624039] Dynamic subtest pipe-A-plane-3: SUCCESS (2.644s) [675.632697] Starting dynamic subtest: pipe-A-plane-5 [675.790649] Dynamic subtest pipe-A-plane-5: SUCCESS (0.166s) [676.927776] Starting dynamic subtest: pipe-B-plane-0 [681.617814] Dynamic subtest pipe-B-plane-0: SUCCESS (4.700s) [681.629651] Starting dynamic subtest: pipe-B-plane-3 [684.196125] Dynamic subtest pipe-B-plane-3: SUCCESS (2.578s) [684.207378] Starting dynamic subtest: pipe-B-plane-5 [684.361845] Dynamic subtest pipe-B-plane-5: SUCCESS (0.165s) [684.668046] Subtest pixel-format: SUCCESS (16.639s) [685.470592] [119/130] (377s left) api_intel_bb (misplaced-blitter) [685.531981] Starting subtest: misplaced-blitter [685.537863] Subtest misplaced-blitter: SUCCESS (0.008s) [685.676221] [120/130] (377s left) gem_exec_fence (submit-chain) [685.856150] Starting subtest: submit-chain [685.874124] Subtest submit-chain: SUCCESS (0.018s) [687.069554] [121/130] (375s left) prime_self_import (reimport-vs-gem_close-race) [687.103956] Starting subtest: reimport-vs-gem_close-race [692.211203] Subtest reimport-vs-gem_close-race: SUCCESS (5.106s) [692.271554] [122/130] (370s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt) [692.376497] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt [697.587262] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (5.212s) [697.724120] [123/130] (365s left) perf_pmu (all-busy-check-all) [697.774646] Starting subtest: all-busy-check-all [699.015540] Subtest all-busy-check-all: SUCCESS (1.242s) [699.087170] [124/130] (363s left) kms_chamelium_color (ctm-blue-to-red) [699.186311] Subtest ctm-blue-to-red: SKIP (0.000s) [699.211145] [125/130] (363s left) kms_cursor_edge_walk (64x64-left-edge) [699.318245] Starting subtest: 64x64-left-edge [699.320569] Starting dynamic subtest: pipe-A-eDP-1 [702.768672] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.449s) [702.780500] Starting dynamic subtest: pipe-D-eDP-1 [705.885810] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.114s) [705.887913] Subtest 64x64-left-edge: SUCCESS (6.566s) [706.686933] [126/130] (356s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt) [706.780289] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt [711.486278] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.709s) [711.623913] [127/130] (351s left) gem_mmap_gtt (close-race) [711.690015] Subtest close-race: SKIP (0.000s) [711.730736] [128/130] (351s left) drm_fdinfo (context-close-stress) [711.772781] Starting subtest: context-close-stress [722.516495] Subtest context-close-stress: SUCCESS (10.747s) [722.574105] [129/130] (340s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled) [722.803699] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled [722.806278] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s) [722.882316] [130/130] (340s left) gem_lmem_swapping (parallel-multi) [722.931439] Subtest parallel-multi: SKIP (0.000s) [722.952569] Closing watchdogs Done.