[58.176523] Initializing watchdogs [58.176615] /dev/watchdog0 [58.191647] [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.195900] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [58.198091] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [58.201182] [001/156] (960s left) syncobj_timeline (signal) [58.289152] Starting subtest: signal [58.291527] Subtest signal: SUCCESS (0.001s) [58.378190] [002/156] (960s left) gem_exec_schedule (semaphore-resolve) [58.449606] Starting subtest: semaphore-resolve [58.517895] Subtest semaphore-resolve: SUCCESS (0.070s) [58.858474] [003/156] (959s left) gem_ctx_exec (basic-norecovery) [58.918627] Starting subtest: basic-norecovery [59.121790] Subtest basic-norecovery: SUCCESS (0.208s) [59.248846] [004/156] (959s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt) [59.354260] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt [59.358485] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s) [59.453412] [005/156] (959s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt) [59.567061] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt [59.571364] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s) [59.656040] [006/156] (959s left) gem_exec_nop (basic-series) [59.726401] Starting subtest: basic-series [76.292991] Subtest basic-series: SUCCESS (16.711s) [76.523201] [007/156] (942s left) kms_getfb (getfb-handle-not-fb) [76.620433] Starting subtest: getfb-handle-not-fb [76.622706] Subtest getfb-handle-not-fb: SUCCESS (0.000s) [76.718998] [008/156] (942s left) gem_exec_params (secure-non-master) [76.776697] Starting subtest: secure-non-master [76.781065] Subtest secure-non-master: SKIP (0.000s) [76.898976] [009/156] (942s left) gem_pxp (create-regular-buffer) [77.022172] Starting subtest: create-regular-buffer [77.024595] Subtest create-regular-buffer: SUCCESS (0.000s) [77.131417] [010/156] (941s left) gem_eio (in-flight-contexts-10ms) [77.367437] Starting subtest: in-flight-contexts-10ms [91.336151] Subtest in-flight-contexts-10ms: SUCCESS (13.975s) [91.518210] [011/156] (927s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-pwrite) [91.623278] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-pwrite [91.627500] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [91.724857] [012/156] (927s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs) [91.829918] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs [91.832285] Starting dynamic subtest: pipe-A-eDP-1 [91.855609] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [91.866634] Starting dynamic subtest: pipe-B-eDP-1 [91.888903] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [91.897048] Starting dynamic subtest: pipe-C-eDP-1 [91.918692] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [91.926631] Starting dynamic subtest: pipe-D-eDP-1 [91.949489] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [91.954175] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.003s) [94.101222] [013/156] (925s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [94.202268] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [94.206267] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [94.292174] [014/156] (924s left) kms_plane (plane-panning-bottom-right-suspend) [94.387296] Starting subtest: plane-panning-bottom-right-suspend [94.389685] Starting dynamic subtest: pipe-A [112.602547] Dynamic subtest pipe-A: SUCCESS (3.020s) [112.613620] Starting dynamic subtest: pipe-B [131.599707] Dynamic subtest pipe-B: SUCCESS (3.597s) [131.602056] Subtest plane-panning-bottom-right-suspend: SUCCESS (6.619s) [132.444722] [015/156] (886s left) gem_exec_async (concurrent-writes) [132.505628] Starting subtest: concurrent-writes [132.507908] Starting dynamic subtest: rcs0 [132.518558] Dynamic subtest rcs0: SUCCESS (0.014s) [132.528564] Starting dynamic subtest: bcs0 [132.530813] Dynamic subtest bcs0: SUCCESS (0.006s) [132.543267] Starting dynamic subtest: vcs0 [132.545434] Dynamic subtest vcs0: SUCCESS (0.005s) [132.554412] Starting dynamic subtest: vcs1 [132.556463] Dynamic subtest vcs1: SUCCESS (0.006s) [132.565034] Starting dynamic subtest: vecs0 [132.567183] Dynamic subtest vecs0: SUCCESS (0.006s) [132.575954] Starting dynamic subtest: ccs0 [132.578153] Dynamic subtest ccs0: SUCCESS (0.005s) [132.580360] Subtest concurrent-writes: SUCCESS (0.045s) [132.763297] [016/156] (886s left) kms_cursor_crc (cursor-rapid-movement-128x128) [132.962027] Starting subtest: cursor-rapid-movement-128x128 [132.964321] Starting dynamic subtest: pipe-A-eDP-1 [134.254186] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.334s) [134.284890] Starting dynamic subtest: pipe-D-eDP-1 [135.005265] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.753s) [135.007540] Subtest cursor-rapid-movement-128x128: SUCCESS (2.116s) [135.814089] [017/156] (883s left) kms_properties (colorop-properties-atomic) [135.918257] Starting subtest: colorop-properties-atomic [135.920603] Starting dynamic subtest: pipe-A-eDP-1 [136.277745] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.370s) [136.289138] Starting dynamic subtest: pipe-B-eDP-1 [137.349658] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.094s) [137.359721] Starting dynamic subtest: pipe-C-eDP-1 [138.454943] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.129s) [138.466691] Starting dynamic subtest: pipe-D-eDP-1 [139.564354] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s) [139.566612] Subtest colorop-properties-atomic: SUCCESS (3.724s) [140.437734] [018/156] (878s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-pgflip-blt) [140.544983] Starting subtest: psrhdr-2p-scndscrn-shrfb-pgflip-blt [140.549488] Subtest psrhdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [140.633118] [019/156] (878s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-msflip-blt) [140.746464] Starting subtest: psrhdr-2p-primscrn-shrfb-msflip-blt [140.750723] Subtest psrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [140.836248] [020/156] (878s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render) [140.941073] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render [140.945463] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [141.046623] [021/156] (878s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite) [141.149133] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite [148.029434] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.947s) [148.152267] [022/156] (871s left) gem_bad_reloc (negative-reloc) [148.217143] Subtest negative-reloc: SKIP (0.000s) [148.284689] [023/156] (871s left) gem_ctx_exec (basic) [148.334081] Starting subtest: basic [148.339102] Subtest basic: SUCCESS (0.007s) [148.454059] [024/156] (871s left) kms_psr (psr-sprite-mmap-gtt) [148.547910] Starting subtest: psr-sprite-mmap-gtt [148.550080] Starting dynamic subtest: eDP-1 [149.940549] Dynamic subtest eDP-1: SKIP (1.396s) [149.945332] Subtest psr-sprite-mmap-gtt: SKIP (1.397s) [150.109563] [025/156] (869s left) gem_exec_balancer (hang) [150.366220] Starting subtest: hang [152.074770] Subtest hang: SUCCESS (1.717s) [152.204374] [026/156] (867s left) kms_plane_alpha_blend (alpha-opaque-fb) [152.306241] Starting subtest: alpha-opaque-fb [152.585714] Starting dynamic subtest: pipe-A-eDP-1 [153.337862] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.754s) [153.349051] Starting dynamic subtest: pipe-D-eDP-1 [154.779230] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.444s) [154.781640] Subtest alpha-opaque-fb: SUCCESS (2.481s) [155.623380] [027/156] (864s left) gem_reg_read (bad-register) [155.674589] Starting subtest: bad-register [155.676998] Subtest bad-register: SUCCESS (0.000s) [155.782626] [028/156] (863s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-blt) [155.882956] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-blt [155.887021] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [155.979774] [029/156] (863s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc) [156.076068] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc [156.078356] Starting dynamic subtest: pipe-A-eDP-1 [156.101098] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [156.112100] Starting dynamic subtest: pipe-B-eDP-1 [156.133918] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [156.142991] Starting dynamic subtest: pipe-C-eDP-1 [156.164366] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [156.174264] Starting dynamic subtest: pipe-D-eDP-1 [156.195004] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [156.199104] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s) [158.391440] [030/156] (861s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs) [158.457793] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs [158.462498] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s) [158.638119] [031/156] (861s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping) [158.727012] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping [158.731513] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s) [158.797749] [032/156] (861s left) gem_exec_fence (syncobj-unused-fence) [159.254700] Starting subtest: syncobj-unused-fence [159.259758] Subtest syncobj-unused-fence: SUCCESS (0.005s) [161.102142] [FACT gem_exec_fence (syncobj-unused-fence)] new: kernel.kmod_is_loaded.vgem: true [161.105703] [033/156] (858s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move) [161.203479] Starting subtest: psr-2p-primscrn-spr-indfb-move [161.207427] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [161.313807] [034/156] (858s left) sw_sync (timeline_closed) [161.349423] Starting subtest: timeline_closed [161.351608] Subtest timeline_closed: SUCCESS (0.000s) [161.408194] [035/156] (858s left) kms_frontbuffer_tracking (psr-suspend) [161.515630] Starting subtest: psr-suspend [200.761385] Subtest psr-suspend: SUCCESS (7.939s) [200.875444] [036/156] (819s left) gem_mmap_gtt (basic) [200.934653] Subtest basic: SKIP (0.000s) [201.000764] [037/156] (819s left) kms_addfb_basic (bo-too-small) [201.090609] Starting subtest: bo-too-small [201.092875] Subtest bo-too-small: SUCCESS (0.000s) [201.199148] [038/156] (818s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu) [201.312498] Starting subtest: psr-rgb565-draw-mmap-cpu [208.272621] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.962s) [208.397684] [039/156] (811s left) syncobj_wait (wait-for-submit-complex) [208.452899] Starting subtest: wait-for-submit-complex [208.513802] Subtest wait-for-submit-complex: SUCCESS (0.062s) [208.590689] [040/156] (811s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled) [208.643918] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled [208.752332] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.108s) [208.828963] [041/156] (811s left) kms_invalid_mode (overflow-vrefresh) [208.934847] Starting subtest: overflow-vrefresh [208.937598] Starting dynamic subtest: pipe-A-eDP-1 [209.220636] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s) [209.232868] Starting dynamic subtest: pipe-B-eDP-1 [209.235265] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [209.245494] Starting dynamic subtest: pipe-C-eDP-1 [209.247867] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [209.257527] Starting dynamic subtest: pipe-D-eDP-1 [209.259933] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [209.262570] Subtest overflow-vrefresh: SUCCESS (0.292s) [210.114861] [042/156] (810s left) intel_hwmon (hwmon-write) [210.170877] Subtest hwmon-write: SKIP (0.000s) [210.233906] [043/156] (810s left) gem_exec_whisper (basic-forked) [210.296197] Starting subtest: basic-forked [217.590125] Subtest basic-forked: SUCCESS (7.291s) [217.779312] [044/156] (802s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled) [217.827863] Starting subtest: multi-wait-all-for-submit-available-signaled [217.830659] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s) [217.935800] [045/156] (802s left) i915_drm_fdinfo (virtual-busy-all) [217.977798] Starting subtest: virtual-busy-all [217.983430] Subtest virtual-busy-all: SKIP (0.000s) [218.048738] [046/156] (802s left) prime_self_import (reimport-vs-gem_close-race) [218.085676] Starting subtest: reimport-vs-gem_close-race [223.185244] Subtest reimport-vs-gem_close-race: SUCCESS (5.098s) [223.269194] [047/156] (797s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu) [223.372811] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu [230.141535] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.775s) [230.268531] [048/156] (790s left) gem_render_copy_redux (flink) [230.324602] Starting subtest: flink [231.083154] Subtest flink: SUCCESS (0.782s) [231.207565] [049/156] (789s left) gen9_exec_parse (bb-secure) [231.269597] Subtest bb-secure: SKIP (0.000s) [231.337354] [050/156] (789s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [231.446891] Starting subtest: cursorB-vs-flipA-toggle [231.451342] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s) [231.584365] [051/156] (789s left) kms_flip (2x-flip-vs-expired-vblank-interruptible) [231.670613] Starting subtest: 2x-flip-vs-expired-vblank-interruptible [231.681404] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.010s) [231.770736] [052/156] (789s left) kms_psr (pr-dpms) [231.867384] Starting subtest: pr-dpms [231.871692] Subtest pr-dpms: SKIP (0.000s) [231.940200] [053/156] (788s left) kms_pm_rpm (pc8-residency) [233.472894] Starting subtest: pc8-residency [233.478647] Subtest pc8-residency: SKIP (0.000s) [234.175449] [054/156] (786s left) kms_psr (fbc-psr-sprite-mmap-gtt) [234.269558] Starting subtest: fbc-psr-sprite-mmap-gtt [234.272039] Starting dynamic subtest: eDP-1 [234.276573] Dynamic subtest eDP-1: SKIP (0.000s) [234.281590] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s) [234.342640] [055/156] (786s left) kms_cursor_crc (cursor-offscreen-32x10) [234.509246] Starting subtest: cursor-offscreen-32x10 [234.555578] Subtest cursor-offscreen-32x10: SKIP (0.044s) [234.807446] [056/156] (786s left) gem_spin_batch (spin-each) [234.862627] Starting subtest: spin-each [238.134767] Subtest spin-each: SUCCESS (3.339s) [238.378175] [057/156] (782s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff) [238.481848] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff [245.489082] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.095s) [245.626543] [058/156] (775s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format) [245.715159] Starting subtest: plane-upscale-20x20-with-pixel-format [245.717696] Starting dynamic subtest: pipe-A [246.759675] Dynamic subtest pipe-A: SUCCESS (1.050s) [246.770450] Starting dynamic subtest: pipe-B [248.894529] Dynamic subtest pipe-B: SUCCESS (2.147s) [248.911898] Starting dynamic subtest: pipe-C [250.080898] Dynamic subtest pipe-C: SUCCESS (1.190s) [250.098246] Starting dynamic subtest: pipe-D [251.309148] Dynamic subtest pipe-D: SUCCESS (1.231s) [251.311804] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.623s) [252.507301] [059/156] (768s left) gem_softpin (softpin) [252.574375] Starting subtest: softpin [284.808518] Subtest softpin: SUCCESS (32.240s) [284.940508] [060/156] (736s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [285.044578] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [285.049650] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [285.158115] [061/156] (736s left) kms_lease (lease-get) [285.253464] Starting subtest: lease-get [285.255812] Starting dynamic subtest: pipe-A-eDP-1 [285.258403] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [285.270558] Starting dynamic subtest: pipe-B-eDP-1 [285.272923] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [285.283686] Starting dynamic subtest: pipe-C-eDP-1 [285.286641] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [285.297363] Starting dynamic subtest: pipe-D-eDP-1 [285.299969] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [285.302373] Subtest lease-get: SUCCESS (0.009s) [285.360423] [062/156] (735s left) syncobj_timeline (wait-for-submit-snapshot) [285.425680] Starting subtest: wait-for-submit-snapshot [285.507334] Subtest wait-for-submit-snapshot: SUCCESS (0.083s) [285.587368] [063/156] (735s left) kms_content_protection (lic-type-0-hdcp14) [285.732527] Starting subtest: lic-type-0-hdcp14 [285.738212] Subtest lic-type-0-hdcp14: SKIP (0.000s) [285.849303] [064/156] (735s left) kms_color (invalid-degamma-lut-sizes) [285.943055] Starting subtest: invalid-degamma-lut-sizes [285.944886] Starting dynamic subtest: pipe-A [286.250875] Dynamic subtest pipe-A: SUCCESS (0.307s) [286.261862] Starting dynamic subtest: pipe-B [286.264567] Dynamic subtest pipe-B: SUCCESS (0.000s) [286.275620] Starting dynamic subtest: pipe-C [286.278192] Dynamic subtest pipe-C: SUCCESS (0.000s) [286.288854] Starting dynamic subtest: pipe-D [286.291622] Dynamic subtest pipe-D: SUCCESS (0.000s) [286.294352] Subtest invalid-degamma-lut-sizes: SUCCESS (0.311s) [287.134009] [065/156] (734s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen) [287.238681] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen [287.243585] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [287.341972] [066/156] (734s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite) [287.437437] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite [287.442464] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [287.535218] [067/156] (733s left) gem_lmem_swapping (heavy-verify-multi) [287.576817] Subtest heavy-verify-multi: SKIP (0.000s) [287.628837] [068/156] (733s left) prime_vgem (basic-fence-read) [287.704473] Starting subtest: basic-fence-read [287.732418] Subtest basic-fence-read: SKIP (0.024s) [287.843994] [069/156] (733s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff) [287.948692] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff [287.953738] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [288.043301] [070/156] (733s left) i915_query (query-topology-matches-eu-total) [288.102905] Starting subtest: query-topology-matches-eu-total [288.105566] Subtest query-topology-matches-eu-total: SUCCESS (0.000s) [288.215437] [071/156] (733s left) syncobj_wait (wait-any-snapshot) [288.275042] Starting subtest: wait-any-snapshot [288.357415] Subtest wait-any-snapshot: SUCCESS (0.083s) [288.439029] [072/156] (733s left) gem_exec_flush (basic-wb-ro-default) [288.507209] Starting subtest: basic-wb-ro-default [293.966896] Subtest basic-wb-ro-default: SUCCESS (5.458s) [294.161574] [073/156] (727s left) gem_fenced_exec_thrash (2-spare-fences) [294.221234] Subtest 2-spare-fences: SKIP (0.000s) [294.286353] [074/156] (727s left) kms_chamelium_hpd (vga-hpd-for-each-pipe) [294.667791] Subtest vga-hpd-for-each-pipe: SKIP (0.000s) [295.449646] [075/156] (726s left) perf_pmu (init-sema) [295.495496] Starting subtest: init-sema [295.498029] Starting dynamic subtest: rcs0 [295.500562] Dynamic subtest rcs0: SUCCESS (0.002s) [295.512921] Starting dynamic subtest: bcs0 [295.515656] Dynamic subtest bcs0: SUCCESS (0.000s) [295.526704] Starting dynamic subtest: vcs0 [295.529222] Dynamic subtest vcs0: SUCCESS (0.001s) [295.541751] Starting dynamic subtest: vcs1 [295.544618] Dynamic subtest vcs1: SUCCESS (0.000s) [295.554901] Starting dynamic subtest: vecs0 [295.557119] Dynamic subtest vecs0: SUCCESS (0.001s) [295.567535] Starting dynamic subtest: ccs0 [295.569865] Dynamic subtest ccs0: SUCCESS (0.001s) [295.572264] Subtest init-sema: SUCCESS (0.008s) [295.616199] [076/156] (726s left) kms_flip (basic-flip-vs-modeset) [295.715928] Starting subtest: basic-flip-vs-modeset [295.718787] Starting dynamic subtest: A-eDP1 [298.256149] Dynamic subtest A-eDP1: SUCCESS (2.542s) [298.268428] Starting dynamic subtest: B-eDP1 [300.413522] Dynamic subtest B-eDP1: SUCCESS (2.156s) [300.424902] Starting dynamic subtest: C-eDP1 [302.590559] Dynamic subtest C-eDP1: SUCCESS (2.176s) [302.602921] Starting dynamic subtest: D-eDP1 [304.772714] Dynamic subtest D-eDP1: SUCCESS (2.179s) [304.775244] Subtest basic-flip-vs-modeset: SUCCESS (9.062s) [305.586678] [077/156] (716s left) kms_cursor_legacy (forked-bo) [305.720026] Starting subtest: forked-bo [305.722708] Starting dynamic subtest: pipe-A [311.184430] Dynamic subtest pipe-A: SUCCESS (5.464s) [311.200366] Starting dynamic subtest: pipe-B [316.665231] Dynamic subtest pipe-B: SUCCESS (5.480s) [316.679633] Starting dynamic subtest: pipe-C [322.149473] Dynamic subtest pipe-C: SUCCESS (5.484s) [322.159909] Starting dynamic subtest: pipe-D [327.614866] Dynamic subtest pipe-D: SUCCESS (5.464s) [327.630571] Starting dynamic subtest: all-pipes [333.111531] Dynamic subtest all-pipes: SUCCESS (5.492s) [333.113908] Subtest forked-bo: SUCCESS (27.395s) [333.262938] [078/156] (688s left) kms_ccs (crc-primary-basic-y-tiled-ccs) [333.358527] Starting subtest: crc-primary-basic-y-tiled-ccs [333.360881] Starting dynamic subtest: pipe-A-eDP-1 [333.384681] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [333.396492] Starting dynamic subtest: pipe-B-eDP-1 [333.422029] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [333.432353] Starting dynamic subtest: pipe-C-eDP-1 [333.455576] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [333.465530] Starting dynamic subtest: pipe-D-eDP-1 [333.490684] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [333.495820] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.006s) [335.640309] [079/156] (686s left) kms_big_fb (y-tiled-32bpp-rotate-180) [335.741480] Starting subtest: y-tiled-32bpp-rotate-180 [335.746359] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s) [335.838675] [080/156] (686s left) gem_exec_flush (basic-wb-pro-default) [335.910755] Starting subtest: basic-wb-pro-default [341.382151] Subtest basic-wb-pro-default: SUCCESS (5.469s) [341.586479] [081/156] (680s left) gem_media_vme [341.707862] [082/156] (680s left) kms_dirtyfb (fbc-dirtyfb-ioctl) [341.799550] Starting subtest: fbc-dirtyfb-ioctl [341.801746] Starting dynamic subtest: A-eDP-1 [344.424358] Dynamic subtest A-eDP-1: SUCCESS (2.625s) [344.426757] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.626s) [345.308809] [083/156] (676s left) gem_userptr_blits (coherency-unsync) [345.373574] Subtest coherency-unsync: SKIP (0.000s) [345.485645] [084/156] (676s left) gem_exec_create (basic) [345.548942] Starting subtest: basic [345.551753] Starting dynamic subtest: smem [349.331240] Dynamic subtest smem: SUCCESS (3.780s) [349.334311] Subtest basic: SUCCESS (3.782s) [349.535334] [085/156] (672s left) kms_psr (fbc-psr2-cursor-plane-onoff) [349.636792] Starting subtest: fbc-psr2-cursor-plane-onoff [349.639672] Starting dynamic subtest: eDP-1 [349.644899] Dynamic subtest eDP-1: SKIP (0.000s) [349.650318] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s) [349.715162] [086/156] (672s left) gem_ctx_persistence (legacy-engines-queued) [349.773764] Starting subtest: legacy-engines-queued [349.776686] Starting dynamic subtest: render [349.860499] Dynamic subtest render: SUCCESS (0.083s) [349.871556] Starting dynamic subtest: bsd1 [351.281450] Dynamic subtest bsd1: SUCCESS (1.419s) [351.298884] Starting dynamic subtest: bsd2 [353.264407] Dynamic subtest bsd2: SUCCESS (1.978s) [353.278889] Starting dynamic subtest: blt [353.319771] Dynamic subtest blt: SUCCESS (0.054s) [353.333553] Starting dynamic subtest: vebox [355.313269] Dynamic subtest vebox: SUCCESS (1.989s) [355.316332] Subtest legacy-engines-queued: SUCCESS (5.539s) [355.401423] [087/156] (666s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render) [355.509133] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render [355.514482] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [355.588853] [088/156] (666s left) api_intel_bb (object-reloc-purge-cache) [355.635084] Starting subtest: object-reloc-purge-cache [355.640452] Subtest object-reloc-purge-cache: SKIP (0.003s) [355.755975] [089/156] (666s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu) [355.855954] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu [355.861470] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [355.945271] [090/156] (666s left) kms_draw_crc (draw-method-mmap-cpu) [356.042603] Starting subtest: draw-method-mmap-cpu [356.045008] Starting dynamic subtest: xrgb8888-untiled [356.295359] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s) [356.307874] Starting dynamic subtest: rgb565-untiled [356.539219] Dynamic subtest rgb565-untiled: SUCCESS (0.242s) [356.550407] Starting dynamic subtest: xrgb2101010-untiled [356.762458] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s) [356.775741] Starting dynamic subtest: xbgr16161616f-untiled [356.995482] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s) [357.006367] Starting dynamic subtest: xrgb8888-xtiled [357.139382] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.142s) [357.150498] Starting dynamic subtest: rgb565-xtiled [357.273576] Dynamic subtest rgb565-xtiled: SUCCESS (0.132s) [357.284864] Starting dynamic subtest: xrgb2101010-xtiled [357.394476] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s) [357.405735] Starting dynamic subtest: xbgr16161616f-xtiled [357.527905] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s) [357.538641] Starting dynamic subtest: xrgb8888-4tiled [357.672712] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s) [357.683628] Starting dynamic subtest: rgb565-4tiled [357.817875] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s) [357.828489] Starting dynamic subtest: xrgb2101010-4tiled [357.950657] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s) [357.961501] Starting dynamic subtest: xbgr16161616f-4tiled [358.105886] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.155s) [358.107904] Subtest draw-method-mmap-cpu: SUCCESS (2.067s) [358.192225] [091/156] (664s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs) [358.282347] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs [358.284305] Starting dynamic subtest: pipe-A-eDP-1 [358.292386] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [358.303264] Starting dynamic subtest: pipe-B-eDP-1 [358.311117] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [358.322535] Starting dynamic subtest: pipe-C-eDP-1 [358.331216] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [358.341991] Starting dynamic subtest: pipe-D-eDP-1 [358.350515] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [358.354562] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SKIP (0.005s) [360.574703] [092/156] (661s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip) [360.675280] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip [361.310616] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.637s) [361.475309] [093/156] (661s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [361.576377] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [362.469306] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.894s) [362.633364] [094/156] (660s left) device_reset (cold-reset-bound) [362.684150] Starting subtest: cold-reset-bound [362.689603] Subtest cold-reset-bound: SKIP (0.000s) [362.773667] [095/156] (659s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt) [362.879719] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt [362.885214] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [363.001088] [096/156] (659s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [363.087567] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [363.092854] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s) [363.167266] [097/156] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt) [363.267676] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt [363.272779] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s) [363.364960] [098/156] (659s left) gem_mmap_gtt (zero-extend) [363.418627] Subtest zero-extend: SKIP (0.000s) [363.477395] [099/156] (659s left) gem_exec_schedule (independent) [363.543140] Starting subtest: independent [363.545074] Starting dynamic subtest: rcs0 [365.331303] Dynamic subtest rcs0: SUCCESS (1.794s) [365.345696] Starting dynamic subtest: vcs0 [367.315642] Dynamic subtest vcs0: SUCCESS (1.981s) [367.328796] Starting dynamic subtest: vcs1 [369.306707] Dynamic subtest vcs1: SUCCESS (1.989s) [369.320904] Starting dynamic subtest: vecs0 [371.358909] Dynamic subtest vecs0: SUCCESS (2.051s) [371.373126] Starting dynamic subtest: ccs0 [373.331465] Dynamic subtest ccs0: SUCCESS (1.970s) [373.334498] Subtest independent: SUCCESS (9.796s) [373.635543] [100/156] (649s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite) [373.738852] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite [373.743694] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [373.819461] [101/156] (649s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling) [373.907725] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling [373.913363] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s) [373.994013] [102/156] (649s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [374.111746] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt [374.116909] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [374.213790] [103/156] (648s left) gem_render_copy (linear-to-vebox-y-tiled) [374.271694] Starting subtest: linear-to-vebox-y-tiled [374.276541] Subtest linear-to-vebox-y-tiled: SKIP (0.000s) [374.462155] [104/156] (648s left) kms_pm_rpm (fences-dpms) [376.501136] Starting subtest: fences-dpms [376.506859] Subtest fences-dpms: SKIP (0.000s) [376.899413] [105/156] (646s left) kms_sequence (queue-idle) [376.998609] Starting subtest: queue-idle [377.001523] Starting dynamic subtest: pipe-A-eDP-1 [378.804669] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.805s) [378.816873] Starting dynamic subtest: pipe-B-eDP-1 [381.270681] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.468s) [381.282903] Starting dynamic subtest: pipe-C-eDP-1 [383.780538] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.506s) [383.792916] Starting dynamic subtest: pipe-D-eDP-1 [386.206767] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.427s) [386.209703] Subtest queue-idle: SUCCESS (9.212s) [387.020038] [106/156] (636s left) kms_big_fb (linear-8bpp-rotate-270) [387.127139] Starting subtest: linear-8bpp-rotate-270 [387.149768] Subtest linear-8bpp-rotate-270: SKIP (0.021s) [387.254319] [107/156] (635s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-wc) [387.358741] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-wc [387.364283] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [387.446579] [108/156] (635s left) gem_exec_suspend (basic) [387.510306] Starting subtest: basic [387.512857] Starting dynamic subtest: smem [389.330278] Dynamic subtest smem: SUCCESS (1.819s) [389.333198] Subtest basic: SUCCESS (1.821s) [389.519387] [109/156] (633s left) gem_ctx_bad_destroy (invalid-default-ctx) [389.576348] Starting subtest: invalid-default-ctx [389.578747] Subtest invalid-default-ctx: SUCCESS (0.000s) [389.654215] [110/156] (633s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [389.765762] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [389.771368] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [389.864583] [111/156] (633s left) syncobj_eventfd (binary-wait-signaled) [389.918690] Starting subtest: binary-wait-signaled [389.920902] Subtest binary-wait-signaled: SUCCESS (0.000s) [390.033396] [112/156] (633s left) sysfs_timeslice_duration (timeout) [390.088746] Starting subtest: timeout [390.091678] Starting dynamic subtest: vecs0 [399.307273] Dynamic subtest vecs0: SUCCESS (9.216s) [399.320608] Starting dynamic subtest: ccs0 [400.563519] Dynamic subtest ccs0: SUCCESS (1.255s) [400.579543] Starting dynamic subtest: vcs0 [410.316599] Dynamic subtest vcs0: SUCCESS (9.753s) [410.329869] Starting dynamic subtest: bcs0 [410.924387] Dynamic subtest bcs0: SUCCESS (0.607s) [410.935928] Starting dynamic subtest: rcs0 [412.191767] Dynamic subtest rcs0: SUCCESS (1.266s) [412.206528] Starting dynamic subtest: vcs1 [421.329795] Dynamic subtest vcs1: SUCCESS (9.138s) [421.332641] Subtest timeout: SUCCESS (31.245s) [421.410128] [113/156] (602s left) kms_psr (psr-basic) [421.511392] Starting subtest: psr-basic [421.513793] Starting dynamic subtest: eDP-1 [423.182046] Dynamic subtest eDP-1: SUCCESS (1.671s) [423.183880] Subtest psr-basic: SUCCESS (1.672s) [424.063711] [114/156] (599s left) kms_chamelium_color (ctm-0-75) [424.162719] Subtest ctm-0-75: SKIP (0.000s) [424.211960] [115/156] (599s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-render) [424.321893] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-render [424.327491] Subtest hdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [424.412301] [116/156] (599s left) gen7_exec_parse (basic-allocation) [424.472537] Subtest basic-allocation: SKIP (0.000s) [424.533900] [117/156] (599s left) kms_psr2_sf (pr-plane-move-sf-dmg-area) [424.629180] Starting subtest: pr-plane-move-sf-dmg-area [424.634408] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s) [424.701545] [118/156] (599s left) kms_flip (plain-flip-fb-recreate) [424.790614] Starting subtest: plain-flip-fb-recreate [424.793494] Starting dynamic subtest: A-eDP1 [433.654799] Dynamic subtest A-eDP1: SUCCESS (8.865s) [433.666169] Starting dynamic subtest: B-eDP1 [442.584433] Dynamic subtest B-eDP1: SUCCESS (8.930s) [442.600493] Starting dynamic subtest: C-eDP1 [451.512059] Dynamic subtest C-eDP1: SUCCESS (8.928s) [451.527347] Starting dynamic subtest: D-eDP1 [460.330733] Dynamic subtest D-eDP1: SUCCESS (8.817s) [460.335651] Subtest plain-flip-fb-recreate: SUCCESS (35.551s) [461.509282] [119/156] (562s left) gem_mmap (basic) [461.568690] Subtest basic: SKIP (0.000s) [461.636005] [120/156] (562s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes) [462.199882] Starting subtest: syncobj-timeline-multiple-ext-nodes [462.202802] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.003s) [463.402410] [121/156] (560s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc) [463.500560] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc [463.505906] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [463.617513] [122/156] (560s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-pwrite) [463.725726] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-pwrite [463.730788] Subtest hdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [463.815801] [123/156] (560s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-onoff) [463.911663] Starting subtest: fbchdr-2p-primscrn-spr-indfb-onoff [463.916861] Subtest fbchdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s) [464.014445] [124/156] (560s left) kms_hdr (static-swap) [464.115360] Starting subtest: static-swap [464.117869] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010 [464.122632] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s) [464.133875] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F [464.139206] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s) [464.144425] Subtest static-swap: SKIP (0.003s) [464.193524] [125/156] (559s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move) [464.297116] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move [464.301898] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s) [464.404519] [126/156] (559s left) core_setmaster_vs_auth [464.569703] [127/156] (559s left) kms_big_fb (yf-tiled-8bpp-rotate-180) [464.673543] Starting subtest: yf-tiled-8bpp-rotate-180 [464.681446] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s) [464.786498] [128/156] (559s left) kms_addfb_basic (bad-pitch-256) [464.843033] Starting subtest: bad-pitch-256 [464.845521] Subtest bad-pitch-256: SUCCESS (0.000s) [464.971747] [129/156] (559s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted) [465.024876] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted [465.125590] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.101s) [465.208159] [130/156] (559s left) perf (gen12-group-exclusive-stream-ctx-handle) [465.334077] Starting subtest: gen12-group-exclusive-stream-ctx-handle [465.369736] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.035s) [465.420801] [131/156] (558s left) gem_mmap_wc (coherency) [467.320461] Subtest coherency: SKIP (0.000s) [467.409143] [132/156] (556s left) kms_plane (pixel-format-linear-modifier) [467.513627] Starting subtest: pixel-format-linear-modifier [467.540014] Starting dynamic subtest: pipe-A-plane-0 [468.174860] Dynamic subtest pipe-A-plane-0: SUCCESS (0.633s) [468.186406] Starting dynamic subtest: pipe-A-plane-3 [468.617609] Dynamic subtest pipe-A-plane-3: SUCCESS (0.443s) [468.628507] Starting dynamic subtest: pipe-A-plane-5 [468.840156] Dynamic subtest pipe-A-plane-5: SUCCESS (0.220s) [469.998205] Starting dynamic subtest: pipe-B-plane-0 [470.611616] Dynamic subtest pipe-B-plane-0: SUCCESS (0.622s) [470.635025] Starting dynamic subtest: pipe-B-plane-3 [471.076796] Dynamic subtest pipe-B-plane-3: SUCCESS (0.466s) [471.091405] Starting dynamic subtest: pipe-B-plane-5 [471.288056] Dynamic subtest pipe-B-plane-5: SUCCESS (0.209s) [471.612784] Subtest pixel-format-linear-modifier: SUCCESS (4.100s) [472.504908] [133/156] (551s left) kms_big_fb (x-tiled-32bpp-rotate-90) [472.606644] Starting subtest: x-tiled-32bpp-rotate-90 [472.626545] Subtest x-tiled-32bpp-rotate-90: SKIP (0.019s) [472.761003] [134/156] (551s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [472.877321] Starting subtest: basic-flip-before-cursor-atomic [473.793577] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.917s) [474.616637] [135/156] (549s left) i915_hangman (detector) [474.675664] Starting subtest: detector [474.677894] Starting dynamic subtest: rcs0 [486.321575] Dynamic subtest rcs0: SUCCESS (11.644s) [486.336142] Starting dynamic subtest: bcs0 [498.288891] Dynamic subtest bcs0: SUCCESS (11.964s) [498.302445] Starting dynamic subtest: vcs0 [510.259054] Dynamic subtest vcs0: SUCCESS (11.966s) [510.277114] Starting dynamic subtest: vcs1 [522.226583] Dynamic subtest vcs1: SUCCESS (11.965s) [522.241921] Starting dynamic subtest: vecs0 [534.321419] Dynamic subtest vecs0: SUCCESS (12.094s) [534.335633] Starting dynamic subtest: ccs0 [546.289436] Dynamic subtest ccs0: SUCCESS (11.965s) [546.292438] Subtest detector: SUCCESS (71.614s) [546.381638] [136/156] (478s left) gem_madvise (dontneed-before-mmap) [546.413613] Starting subtest: dontneed-before-mmap [546.429117] Subtest dontneed-before-mmap: SUCCESS (0.016s) [546.515622] [137/156] (478s left) gem_exec_reloc (basic-gtt-cpu) [546.573904] Subtest basic-gtt-cpu: SKIP (0.000s) [546.651413] [138/156] (477s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area) [546.749398] Starting subtest: psr2-primary-plane-update-sf-dmg-area [546.751623] Starting dynamic subtest: pipe-A-eDP-1 [552.708338] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.961s) [552.719885] Starting dynamic subtest: pipe-B-eDP-1 [559.328243] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.619s) [559.330732] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.583s) [560.200585] [139/156] (464s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-pwrite) [560.311485] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-pwrite [560.316625] Subtest hdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [560.402037] [140/156] (464s left) kms_vblank (ts-continuation-dpms-rpm) [560.499949] Starting subtest: ts-continuation-dpms-rpm [560.502372] Starting dynamic subtest: pipe-A-eDP-1 [562.994583] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.494s) [563.011874] Starting dynamic subtest: pipe-D-eDP-1 [565.233812] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.239s) [565.236720] Subtest ts-continuation-dpms-rpm: SUCCESS (4.737s) [566.121718] [141/156] (458s left) gem_create (busy-create) [566.176482] Starting subtest: busy-create [566.178853] Starting dynamic subtest: smem0 [599.302627] Dynamic subtest smem0: SUCCESS (33.131s) [599.305722] Subtest busy-create: SUCCESS (33.133s) [599.505914] [142/156] (425s left) gem_exec_fence (concurrent) [599.680881] Starting subtest: concurrent [599.686901] Subtest concurrent: SKIP (0.000s) [601.388175] [143/156] (423s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc) [601.487158] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc [601.492403] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [601.587470] [144/156] (423s left) gem_flink_basic (bad-open) [601.640678] Starting subtest: bad-open [601.643341] Subtest bad-open: SUCCESS (0.000s) [601.741500] [145/156] (423s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-cpu) [601.840639] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-cpu [601.845677] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [601.939350] [146/156] (423s left) kms_chamelium_frames (hdmi-crc-single) [602.330669] Subtest hdmi-crc-single: SKIP (0.000s) [603.136874] [147/156] (421s left) gem_exec_reloc (basic-range) [603.200464] Subtest basic-range: SKIP (0.000s) [603.278076] [148/156] (421s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc) [603.395218] Starting subtest: fbc-rgb565-draw-mmap-wc [607.515539] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.126s) [608.671115] [149/156] (416s left) api_intel_allocator (execbuf-with-allocator) [608.721352] Starting subtest: execbuf-with-allocator [608.726217] Subtest execbuf-with-allocator: SUCCESS (0.008s) [608.841165] [150/156] (416s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-gtt) [608.940529] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-gtt [608.945699] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [609.043621] [151/156] (416s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling) [609.128635] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling [609.131110] Starting dynamic subtest: pipe-A-default-mode [610.319744] Dynamic subtest pipe-A-default-mode: SKIP (1.187s) [610.324747] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.188s) [610.464017] [152/156] (414s left) i915_pm_rpm (gem-execbuf) [612.504790] Starting subtest: gem-execbuf [612.508471] Starting dynamic subtest: smem0 [614.291048] Dynamic subtest smem0: SUCCESS (1.788s) [614.293590] Subtest gem-execbuf: SUCCESS (1.790s) [614.390589] [153/156] (410s left) kms_async_flips (invalid-async-flip) [614.486638] Starting subtest: invalid-async-flip [614.864531] Starting dynamic subtest: pipe-A-eDP-1 [615.670664] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.807s) [616.034289] Starting dynamic subtest: pipe-B-eDP-1 [616.919394] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.897s) [617.291483] Starting dynamic subtest: pipe-C-eDP-1 [618.138680] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.859s) [618.498857] Starting dynamic subtest: pipe-D-eDP-1 [619.275355] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.786s) [619.278091] Subtest invalid-async-flip: SUCCESS (4.792s) [620.437531] [154/156] (404s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary) [620.537872] Starting subtest: fbcpsr-shrfb-scaledprimary [626.315458] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.784s) [626.442419] [155/156] (398s left) i915_suspend (debugfs-reader) [626.496019] Starting subtest: debugfs-reader [645.190196] Subtest debugfs-reader: SUCCESS (3.157s) [645.377767] [156/156] (380s left) gem_caching (writes) [645.446156] Subtest writes: SKIP (0.000s) [645.514126] Closing watchdogs results: parsing output: 0/ for test: syncobj_timeline results: parsing output: 1/ for test: gem_exec_schedule results: parsing output: 2/ for test: gem_ctx_exec results: parsing output: 3/ for test: kms_frontbuffer_tracking results: parsing output: 4/ for test: kms_frontbuffer_tracking results: parsing output: 5/ for test: gem_exec_nop results: parsing output: 6/ for test: kms_getfb results: parsing output: 7/ for test: gem_exec_params results: parsing output: 8/ for test: gem_pxp results: parsing output: 9/ for test: gem_eio results: parsing output: 10/ for test: kms_frontbuffer_tracking results: parsing output: 11/ for test: kms_ccs results: parsing output: 12/ for test: kms_big_fb results: parsing output: 13/ for test: kms_plane results: parsing output: 14/ for test: gem_exec_async results: parsing output: 15/ for test: kms_cursor_crc results: parsing output: 16/ for test: kms_properties results: parsing output: 17/ for test: kms_frontbuffer_tracking results: parsing output: 18/ for test: kms_frontbuffer_tracking results: parsing output: 19/ for test: kms_frontbuffer_tracking results: parsing output: 20/ for test: kms_frontbuffer_tracking results: parsing output: 21/ for test: gem_bad_reloc results: parsing output: 22/ for test: gem_ctx_exec results: parsing output: 23/ for test: kms_psr results: parsing output: 24/ for test: gem_exec_balancer results: parsing output: 25/ for test: kms_plane_alpha_blend results: parsing output: 26/ for test: gem_reg_read results: parsing output: 27/ for test: kms_frontbuffer_tracking results: parsing output: 28/ for test: kms_ccs results: parsing output: 29/ for test: gem_render_copy results: parsing output: 30/ for test: kms_plane results: parsing output: 31/ for test: gem_exec_fence results: parsing output: 32/ for test: kms_frontbuffer_tracking results: parsing output: 33/ for test: sw_sync results: parsing output: 34/ for test: kms_frontbuffer_tracking results: parsing output: 35/ for test: gem_mmap_gtt results: parsing output: 36/ for test: kms_addfb_basic results: parsing output: 37/ for test: kms_frontbuffer_tracking results: parsing output: 38/ for test: syncobj_wait results: parsing output: 39/ for test: syncobj_timeline results: parsing output: 40/ for test: kms_invalid_mode results: parsing output: 41/ for test: intel_hwmon results: parsing output: 42/ for test: gem_exec_whisper results: parsing output: 43/ for test: syncobj_timeline results: parsing output: 44/ for test: i915_drm_fdinfo results: parsing output: 45/ for test: prime_self_import results: parsing output: 46/ for test: kms_frontbuffer_tracking results: parsing output: 47/ for test: gem_render_copy_redux results: parsing output: 48/ for test: gen9_exec_parse results: parsing output: 49/ for test: kms_cursor_legacy results: parsing output: 50/ for test: kms_flip results: parsing output: 51/ for test: kms_psr results: parsing output: 52/ for test: kms_pm_rpm results: parsing output: 53/ for test: kms_psr results: parsing output: 54/ for test: kms_cursor_crc results: parsing output: 55/ for test: gem_spin_batch results: parsing output: 56/ for test: kms_frontbuffer_tracking results: parsing output: 57/ for test: kms_plane_scaling results: parsing output: 58/ for test: gem_softpin results: parsing output: 59/ for test: kms_frontbuffer_tracking results: parsing output: 60/ for test: kms_lease results: parsing output: 61/ for test: syncobj_timeline results: parsing output: 62/ for test: kms_content_protection results: parsing output: 63/ for test: kms_color results: parsing output: 64/ for test: kms_frontbuffer_tracking results: parsing output: 65/ for test: kms_frontbuffer_tracking results: parsing output: 66/ for test: gem_lmem_swapping results: parsing output: 67/ for test: prime_vgem results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: i915_query results: parsing output: 70/ for test: syncobj_wait results: parsing output: 71/ for test: gem_exec_flush results: parsing output: 72/ for test: gem_fenced_exec_thrash results: parsing output: 73/ for test: kms_chamelium_hpd results: parsing output: 74/ for test: perf_pmu results: parsing output: 75/ for test: kms_flip results: parsing output: 76/ for test: kms_cursor_legacy results: parsing output: 77/ for test: kms_ccs results: parsing output: 78/ for test: kms_big_fb results: parsing output: 79/ for test: gem_exec_flush results: parsing output: 80/ for test: gem_media_vme results: parsing output: 81/ for test: kms_dirtyfb results: parsing output: 82/ for test: gem_userptr_blits results: parsing output: 83/ for test: gem_exec_create results: parsing output: 84/ for test: kms_psr results: parsing output: 85/ for test: gem_ctx_persistence results: parsing output: 86/ for test: kms_frontbuffer_tracking results: parsing output: 87/ for test: api_intel_bb results: parsing output: 88/ for test: kms_frontbuffer_tracking results: parsing output: 89/ for test: kms_draw_crc results: parsing output: 90/ for test: kms_ccs results: parsing output: 91/ for test: kms_big_fb results: parsing output: 92/ for test: kms_big_fb results: parsing output: 93/ for test: device_reset results: parsing output: 94/ for test: kms_frontbuffer_tracking results: parsing output: 95/ for test: kms_flip_scaled_crc results: parsing output: 96/ for test: kms_frontbuffer_tracking results: parsing output: 97/ for test: gem_mmap_gtt results: parsing output: 98/ for test: gem_exec_schedule results: parsing output: 99/ for test: kms_frontbuffer_tracking results: parsing output: 100/ for test: kms_flip_scaled_crc results: parsing output: 101/ for test: kms_frontbuffer_tracking results: parsing output: 102/ for test: gem_render_copy results: parsing output: 103/ for test: kms_pm_rpm results: parsing output: 104/ for test: kms_sequence results: parsing output: 105/ for test: kms_big_fb results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: gem_exec_suspend results: parsing output: 108/ for test: gem_ctx_bad_destroy results: parsing output: 109/ for test: kms_frontbuffer_tracking results: parsing output: 110/ for test: syncobj_eventfd results: parsing output: 111/ for test: sysfs_timeslice_duration results: parsing output: 112/ for test: kms_psr results: parsing output: 113/ for test: kms_chamelium_color results: parsing output: 114/ for test: kms_frontbuffer_tracking results: parsing output: 115/ for test: gen7_exec_parse results: parsing output: 116/ for test: kms_psr2_sf results: parsing output: 117/ for test: kms_flip results: parsing output: 118/ for test: gem_mmap results: parsing output: 119/ for test: gem_exec_fence results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: kms_frontbuffer_tracking results: parsing output: 122/ for test: kms_frontbuffer_tracking results: parsing output: 123/ for test: kms_hdr results: parsing output: 124/ for test: kms_frontbuffer_tracking results: parsing output: 125/ for test: core_setmaster_vs_auth results: parsing output: 126/ for test: kms_big_fb results: parsing output: 127/ for test: kms_addfb_basic results: parsing output: 128/ for test: syncobj_timeline results: parsing output: 129/ for test: perf results: parsing output: 130/ for test: gem_mmap_wc results: parsing output: 131/ for test: kms_plane results: parsing output: 132/ for test: kms_big_fb results: parsing output: 133/ for test: kms_cursor_legacy results: parsing output: 134/ for test: i915_hangman results: parsing output: 135/ for test: gem_madvise results: parsing output: 136/ for test: gem_exec_reloc results: parsing output: 137/ for test: kms_psr2_sf results: parsing output: 138/ for test: kms_frontbuffer_tracking results: parsing output: 139/ for test: kms_vblank results: parsing output: 140/ for test: gem_create results: parsing output: 141/ for test: gem_exec_fence results: parsing output: 142/ for test: kms_frontbuffer_tracking results: parsing output: 143/ for test: gem_flink_basic results: parsing output: 144/ for test: kms_frontbuffer_tracking results: parsing output: 145/ for test: kms_chamelium_frames results: parsing output: 146/ for test: gem_exec_reloc results: parsing output: 147/ for test: kms_frontbuffer_tracking results: parsing output: 148/ for test: api_intel_allocator results: parsing output: 149/ for test: kms_frontbuffer_tracking results: parsing output: 150/ for test: kms_flip_scaled_crc results: parsing output: 151/ for test: i915_pm_rpm results: parsing output: 152/ for test: kms_async_flips results: parsing output: 153/ for test: kms_frontbuffer_tracking results: parsing output: 154/ for test: i915_suspend results: parsing output: 155/ for test: gem_caching Done.