[58.645985] Initializing watchdogs [58.646053] /dev/watchdog0 [58.659708] [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.663780] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [58.665879] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [58.669312] [001/130] (960s left) kms_psr (fbc-psr-primary-page-flip) [58.830616] Starting subtest: fbc-psr-primary-page-flip [58.833067] Starting dynamic subtest: eDP-1 [58.837718] Dynamic subtest eDP-1: SKIP (0.000s) [58.842943] Subtest fbc-psr-primary-page-flip: SKIP (0.001s) [58.893765] [002/130] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move) [59.004807] Starting subtest: psr-2p-scndscrn-spr-indfb-move [59.009329] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [59.091128] [003/130] (960s left) gem_mmap_gtt (cpuset-basic-small-copy) [59.147639] Subtest cpuset-basic-small-copy: SKIP (0.000s) [59.207064] [004/130] (960s left) sw_sync (alloc_merge_fence) [59.247452] Starting subtest: alloc_merge_fence [59.249757] Subtest alloc_merge_fence: SUCCESS (0.000s) [59.295930] [005/130] (959s left) sw_sync (sync_merge) [59.328060] Starting subtest: sync_merge [59.330116] Subtest sync_merge: SUCCESS (0.000s) [59.375656] [006/130] (959s left) gem_eio (in-flight-10ms) [59.521718] Starting subtest: in-flight-10ms [66.872141] Subtest in-flight-10ms: SUCCESS (7.381s) [67.044692] [FACT gem_eio (in-flight-10ms)] new: kernel.kmod_is_loaded.vgem: true [67.047730] [007/130] (952s left) gem_exec_whisper (basic-contexts-priority) [67.117826] Starting subtest: basic-contexts-priority [90.287928] Subtest basic-contexts-priority: SUCCESS (23.182s) [90.464990] [008/130] (928s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite) [90.571180] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite [97.236520] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.668s) [97.374190] [009/130] (922s left) gem_mmap_wc (read-write-distinct) [97.426990] Subtest read-write-distinct: SKIP (0.000s) [97.490235] [010/130] (921s left) gem_exec_flush (basic-uc-ro-default) [97.556088] Starting subtest: basic-uc-ro-default [103.034108] Subtest basic-uc-ro-default: SUCCESS (5.479s) [103.225726] [011/130] (916s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs) [103.331933] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs [103.334256] Starting dynamic subtest: pipe-A-eDP-1 [103.393609] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [103.402703] Starting dynamic subtest: pipe-B-eDP-1 [103.463883] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [103.472791] Starting dynamic subtest: pipe-C-eDP-1 [103.530356] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [103.539194] Starting dynamic subtest: pipe-D-eDP-1 [103.595618] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [103.599718] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.007s) [105.502857] [012/130] (913s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible) [105.602646] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible [105.605052] Starting dynamic subtest: A-eDP1 [109.210370] Dynamic subtest A-eDP1: SUCCESS (3.603s) [109.219514] Starting dynamic subtest: B-eDP1 [112.386176] Dynamic subtest B-eDP1: SUCCESS (3.176s) [112.393667] Starting dynamic subtest: C-eDP1 [115.457439] Dynamic subtest C-eDP1: SUCCESS (3.070s) [115.466283] Starting dynamic subtest: D-eDP1 [118.527413] Dynamic subtest D-eDP1: SUCCESS (3.072s) [118.530059] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (12.926s) [119.325013] [013/130] (900s left) prime_mmap_coherency (write) [119.387145] Starting subtest: write [124.772659] Subtest write: SUCCESS (5.383s) [125.171461] [014/130] (894s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite) [125.280939] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite [125.285118] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [125.389420] [015/130] (894s left) kms_cursor_crc (cursor-onscreen-256x85) [125.625419] Starting subtest: cursor-onscreen-256x85 [125.668654] Subtest cursor-onscreen-256x85: SKIP (0.037s) [125.850447] [016/130] (893s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic) [125.974381] Starting subtest: 2x-cursor-vs-flip-atomic [125.978838] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s) [126.069176] [017/130] (893s left) syncobj_timeline (invalid-query-illegal-handle) [126.128417] Starting subtest: invalid-query-illegal-handle [126.130822] Subtest invalid-query-illegal-handle: SUCCESS (0.000s) [126.247670] [018/130] (893s left) gem_caching (writes) [126.314589] Subtest writes: SKIP (0.000s) [126.393804] [019/130] (893s left) gem_ctx_param (set-priority-range) [126.450297] Starting subtest: set-priority-range [126.468414] Subtest set-priority-range: SUCCESS (0.018s) [126.575889] [020/130] (893s left) kms_psr (psr2-cursor-plane-onoff) [126.677837] Starting subtest: psr2-cursor-plane-onoff [126.680043] Starting dynamic subtest: eDP-1 [128.405999] Dynamic subtest eDP-1: SUCCESS (1.726s) [128.408383] Subtest psr2-cursor-plane-onoff: SUCCESS (1.726s) [129.185037] [021/130] (890s left) sw_sync (timeline_closed_signaled) [129.222396] Starting subtest: timeline_closed_signaled [129.224244] Subtest timeline_closed_signaled: SUCCESS (0.000s) [129.266750] [022/130] (890s left) kms_draw_crc (draw-method-mmap-gtt) [129.360706] Starting subtest: draw-method-mmap-gtt [129.364960] Subtest draw-method-mmap-gtt: SKIP (0.000s) [129.457252] [023/130] (890s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render) [129.564485] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render [129.568613] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [129.668513] [024/130] (890s left) gem_ctx_param (invalid-set-no-zeromap) [129.725561] Starting subtest: invalid-set-no-zeromap [129.727815] Subtest invalid-set-no-zeromap: SUCCESS (0.000s) [129.790180] [025/130] (890s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render) [129.892566] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render [132.677072] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (2.789s) [132.831070] [026/130] (887s left) kms_writeback (writeback-fb-id-xrgb2101010) [132.933677] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s) [132.975552] [027/130] (887s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [133.095119] Starting subtest: cursorA-vs-flipA-atomic-transitions [144.299345] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.204s) [145.144452] [028/130] (874s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs) [145.249852] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs [145.252114] Starting dynamic subtest: pipe-A-eDP-1 [145.277232] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [145.286606] Starting dynamic subtest: pipe-B-eDP-1 [145.310525] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [145.318770] Starting dynamic subtest: pipe-C-eDP-1 [145.342949] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [145.350656] Starting dynamic subtest: pipe-D-eDP-1 [145.375223] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [145.379304] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.003s) [147.397267] [029/130] (872s left) syncobj_wait (invalid-signal-one-illegal-handle) [147.450116] Starting subtest: invalid-signal-one-illegal-handle [147.452444] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s) [147.552462] [030/130] (872s left) perf_pmu (most-busy-idle-check-all) [147.592862] Starting subtest: most-busy-idle-check-all [147.595114] Starting dynamic subtest: rcs0 [148.913559] Dynamic subtest rcs0: SUCCESS (1.318s) [148.927960] Starting dynamic subtest: bcs0 [150.896265] Dynamic subtest bcs0: SUCCESS (1.982s) [150.907674] Starting dynamic subtest: vcs0 [152.944375] Dynamic subtest vcs0: SUCCESS (2.047s) [152.955467] Starting dynamic subtest: vcs1 [154.928933] Dynamic subtest vcs1: SUCCESS (1.983s) [154.939438] Starting dynamic subtest: vecs0 [156.912512] Dynamic subtest vecs0: SUCCESS (1.982s) [156.923541] Starting dynamic subtest: ccs0 [158.960887] Dynamic subtest ccs0: SUCCESS (2.047s) [158.963677] Subtest most-busy-idle-check-all: SUCCESS (11.365s) [159.063892] [031/130] (861s left) gem_watchdog (default-virtual) [159.131780] Starting subtest: default-virtual [165.417525] Subtest default-virtual: SUCCESS (6.291s) [165.496900] [032/130] (854s left) kms_lease (lease-again) [165.595046] Starting subtest: lease-again [165.597634] Starting dynamic subtest: pipe-A-eDP-1 [165.600157] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s) [165.611358] Starting dynamic subtest: pipe-B-eDP-1 [165.613524] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s) [165.621326] Starting dynamic subtest: pipe-C-eDP-1 [165.623547] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s) [165.632574] Starting dynamic subtest: pipe-D-eDP-1 [165.634685] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s) [165.636813] Subtest lease-again: SUCCESS (0.011s) [165.702545] [033/130] (854s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [165.799531] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [165.802095] Starting dynamic subtest: pipe-A-default-mode [166.087763] Dynamic subtest pipe-A-default-mode: SKIP (0.282s) [166.093093] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.283s) [166.918304] [034/130] (853s left) gem_exec_flush (basic-wb-rw-before-default) [166.993655] Starting subtest: basic-wb-rw-before-default [172.471657] Subtest basic-wb-rw-before-default: SUCCESS (5.478s) [172.649216] [035/130] (847s left) gem_render_copy (y-tiled-ccs-to-x-tiled) [172.700839] Starting subtest: y-tiled-ccs-to-x-tiled [172.705205] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s) [172.877585] [036/130] (847s left) gem_pxp (hw-rejects-pxp-context) [173.001808] Starting subtest: hw-rejects-pxp-context [173.007059] Subtest hw-rejects-pxp-context: SKIP (0.000s) [173.105392] [037/130] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc) [173.227237] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc [173.231632] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [173.317279] [038/130] (847s left) gem_exec_reloc (basic-gtt-active) [173.383151] Subtest basic-gtt-active: SKIP (0.000s) [173.453523] [039/130] (846s left) kms_hdr (invalid-hdr) [173.551997] Starting subtest: invalid-hdr [174.591252] Starting dynamic subtest: pipe-A-eDP-1 [177.773870] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.183s) [177.776337] Subtest invalid-hdr: SUCCESS (4.221s) [177.896072] [040/130] (842s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs) [178.004977] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs [178.007202] Starting dynamic subtest: pipe-A-eDP-1 [178.029139] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [178.039749] Starting dynamic subtest: pipe-B-eDP-1 [178.060593] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [178.068192] Starting dynamic subtest: pipe-C-eDP-1 [178.089266] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [178.096888] Starting dynamic subtest: pipe-D-eDP-1 [178.117693] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [178.121644] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s) [180.146756] [041/130] (840s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted) [180.204705] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted [180.307098] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.103s) [180.377297] [042/130] (840s left) kms_psr (fbc-psr2-sprite-blt) [180.487406] Starting subtest: fbc-psr2-sprite-blt [180.489799] Starting dynamic subtest: eDP-1 [180.494111] Dynamic subtest eDP-1: SKIP (0.000s) [180.498335] Subtest fbc-psr2-sprite-blt: SKIP (0.000s) [180.557347] [043/130] (840s left) gem_exec_balancer (semaphore) [180.625828] Starting subtest: semaphore [181.930462] Subtest semaphore: SUCCESS (1.303s) [182.252243] [044/130] (838s left) kms_big_fb (linear-16bpp-rotate-0) [182.361428] Starting subtest: linear-16bpp-rotate-0 [183.916221] Subtest linear-16bpp-rotate-0: SUCCESS (1.556s) [184.065331] [045/130] (836s left) kms_chamelium_hpd (hdmi-hpd-after-suspend) [184.456628] Subtest hdmi-hpd-after-suspend: SKIP (0.000s) [185.264163] [046/130] (835s left) gem_exec_schedule (fairslice) [185.338547] Starting subtest: fairslice [185.340625] Starting dynamic subtest: rcs0 [187.350999] Dynamic subtest rcs0: SUCCESS (2.020s) [187.361070] Starting dynamic subtest: bcs0 [189.371383] Dynamic subtest bcs0: SUCCESS (2.019s) [189.379468] Starting dynamic subtest: vcs0 [191.394539] Dynamic subtest vcs0: SUCCESS (2.022s) [191.403780] Starting dynamic subtest: vcs1 [193.415666] Dynamic subtest vcs1: SUCCESS (2.022s) [193.423553] Starting dynamic subtest: vecs0 [195.436798] Dynamic subtest vecs0: SUCCESS (2.020s) [195.447146] Starting dynamic subtest: ccs0 [197.457413] Dynamic subtest ccs0: SUCCESS (2.021s) [197.459460] Subtest fairslice: SUCCESS (12.126s) [197.753453] [047/130] (822s left) gem_exec_balancer (bonded-true-hang) [198.000690] Starting subtest: bonded-true-hang [198.006091] Subtest bonded-true-hang: SKIP (0.000s) [198.108750] [048/130] (822s left) drm_fdinfo (most-busy-idle-check-all) [198.148251] Starting subtest: most-busy-idle-check-all [198.150614] Starting dynamic subtest: rcs0 [198.155537] Dynamic subtest rcs0: SKIP (0.005s) [198.164685] Starting dynamic subtest: bcs0 [198.169489] Dynamic subtest bcs0: SKIP (0.000s) [198.176991] Starting dynamic subtest: vcs0 [198.181718] Dynamic subtest vcs0: SKIP (0.000s) [198.189362] Starting dynamic subtest: vcs1 [198.193953] Dynamic subtest vcs1: SKIP (0.000s) [198.201585] Starting dynamic subtest: vecs0 [198.206133] Dynamic subtest vecs0: SKIP (0.000s) [198.213648] Starting dynamic subtest: ccs0 [198.217911] Dynamic subtest ccs0: SKIP (0.000s) [198.222197] Subtest most-busy-idle-check-all: SKIP (0.007s) [198.255109] [049/130] (822s left) kms_flip (absolute-wf_vblank) [198.416298] Starting subtest: absolute-wf_vblank [198.418601] Starting dynamic subtest: A-eDP1 [207.131095] Dynamic subtest A-eDP1: SUCCESS (8.714s) [207.138634] Starting dynamic subtest: B-eDP1 [215.853546] Dynamic subtest B-eDP1: SUCCESS (8.721s) [215.864368] Starting dynamic subtest: C-eDP1 [224.567794] Dynamic subtest C-eDP1: SUCCESS (8.713s) [224.578082] Starting dynamic subtest: D-eDP1 [233.293507] Dynamic subtest D-eDP1: SUCCESS (8.725s) [233.298826] Subtest absolute-wf_vblank: SUCCESS (34.882s) [234.495828] [050/130] (786s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled) [234.559501] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled [234.561506] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s) [234.655758] [051/130] (786s left) kms_async_flips (crc-atomic) [234.753435] Starting subtest: crc-atomic [235.125675] Starting dynamic subtest: pipe-A-eDP-1 [238.001059] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.874s) [238.377448] Starting dynamic subtest: pipe-B-eDP-1 [241.211358] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.845s) [241.599310] Starting dynamic subtest: pipe-C-eDP-1 [244.435085] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.844s) [244.793643] Starting dynamic subtest: pipe-D-eDP-1 [247.657803] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.871s) [247.660204] Subtest crc-atomic: SUCCESS (12.903s) [248.822691] [052/130] (772s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling) [248.925121] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling [248.927545] Starting dynamic subtest: pipe-A-default-mode [249.226833] Dynamic subtest pipe-A-default-mode: SKIP (0.294s) [249.231723] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.295s) [250.051592] [053/130] (770s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip) [250.156363] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip [250.160579] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s) [250.256063] [054/130] (770s left) kms_rotation_crc (sprite-rotation-270) [250.354984] Starting subtest: sprite-rotation-270 [250.359465] Subtest sprite-rotation-270: SKIP (0.001s) [250.424550] [055/130] (770s left) kms_content_protection (lic-type-0) [250.602087] Starting subtest: lic-type-0 [253.971285] Subtest lic-type-0: SKIP (3.369s) [255.135565] [056/130] (765s left) sysfs_preempt_timeout (idempotent) [255.201521] Starting subtest: idempotent [255.203944] Starting dynamic subtest: vecs0 [255.206368] Dynamic subtest vecs0: SUCCESS (0.000s) [255.215840] Starting dynamic subtest: ccs0 [255.217831] Dynamic subtest ccs0: SUCCESS (0.000s) [255.225418] Starting dynamic subtest: vcs0 [255.227697] Dynamic subtest vcs0: SUCCESS (0.000s) [255.235259] Starting dynamic subtest: bcs0 [255.237158] Dynamic subtest bcs0: SUCCESS (0.000s) [255.244267] Starting dynamic subtest: rcs0 [255.246143] Dynamic subtest rcs0: SUCCESS (0.000s) [255.253314] Starting dynamic subtest: vcs1 [255.255252] Dynamic subtest vcs1: SUCCESS (0.000s) [255.257128] Subtest idempotent: SUCCESS (0.005s) [255.286937] [057/130] (765s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc) [255.390468] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc [260.250268] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.864s) [260.360147] [058/130] (760s left) i915_pm_rps (thresholds-park) [260.417102] Starting subtest: thresholds-park [260.422065] Subtest thresholds-park: SKIP (0.000s) [260.488433] [059/130] (760s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip) [260.595482] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip [261.550256] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (0.955s) [261.688330] [060/130] (759s left) gem_exec_schedule (u-submit-late-slice) [261.764480] Starting subtest: u-submit-late-slice [261.766624] Starting dynamic subtest: rcs0 [261.799166] Dynamic subtest rcs0: SUCCESS (0.036s) [261.807699] Starting dynamic subtest: bcs0 [261.834128] Dynamic subtest bcs0: SUCCESS (0.034s) [261.844395] Starting dynamic subtest: vcs0 [261.862891] Dynamic subtest vcs0: SUCCESS (0.028s) [261.870991] Starting dynamic subtest: vcs1 [261.889957] Dynamic subtest vcs1: SUCCESS (0.027s) [261.897446] Starting dynamic subtest: vecs0 [261.916064] Dynamic subtest vecs0: SUCCESS (0.026s) [261.923461] Starting dynamic subtest: ccs0 [261.954376] Dynamic subtest ccs0: SUCCESS (0.038s) [261.956379] Subtest u-submit-late-slice: SUCCESS (0.192s) [263.054404] [061/130] (758s left) prime_busy (hang) [263.116618] Starting subtest: hang [263.119040] Starting dynamic subtest: rcs0 [289.414943] Dynamic subtest rcs0: SUCCESS (26.295s) [289.422237] Starting dynamic subtest: bcs0 [296.576102] Dynamic subtest bcs0: SUCCESS (7.158s) [296.586090] Starting dynamic subtest: vcs0 [303.609952] Dynamic subtest vcs0: SUCCESS (7.033s) [303.619818] Starting dynamic subtest: vcs1 [310.586996] Dynamic subtest vcs1: SUCCESS (6.977s) [310.595049] Starting dynamic subtest: vecs0 [317.555621] Dynamic subtest vecs0: SUCCESS (6.966s) [317.562985] Starting dynamic subtest: ccs0 [331.462328] Dynamic subtest ccs0: SUCCESS (13.905s) [331.464797] Subtest hang: SUCCESS (68.340s) [331.616158] [062/130] (689s left) gem_mmap_gtt (hang) [331.687513] Subtest hang: SKIP (0.000s) [331.741665] [063/130] (689s left) syncobj_basic (bad-handle-to-fd) [331.806913] Starting subtest: bad-handle-to-fd [331.809314] Subtest bad-handle-to-fd: SUCCESS (0.000s) [331.913811] [064/130] (689s left) kms_prop_blob (invalid-set-prop-any) [331.972901] Starting subtest: invalid-set-prop-any [331.975131] Subtest invalid-set-prop-any: SUCCESS (0.000s) [332.082262] [065/130] (689s left) kms_plane (plane-panning-bottom-right) [332.180068] Starting subtest: plane-panning-bottom-right [332.182310] Starting dynamic subtest: pipe-A [333.825245] Dynamic subtest pipe-A: SUCCESS (1.644s) [333.834635] Starting dynamic subtest: pipe-B [336.072419] Dynamic subtest pipe-B: SUCCESS (2.245s) [336.074840] Subtest plane-panning-bottom-right: SUCCESS (3.891s) [336.888156] [066/130] (684s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite) [337.003894] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite [341.670249] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.669s) [341.755742] [067/130] (679s left) gem_exec_balancer (smoke) [341.812583] Starting subtest: smoke [364.244079] Subtest smoke: SUCCESS (22.428s) [365.266719] [068/130] (656s left) kms_vblank (ts-continuation-idle-hang) [365.380267] Starting subtest: ts-continuation-idle-hang [365.382541] Starting dynamic subtest: pipe-A-eDP-1 [391.659803] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.276s) [391.676737] Starting dynamic subtest: pipe-D-eDP-1 [416.689952] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.027s) [416.692366] Subtest ts-continuation-idle-hang: SUCCESS (51.307s) [417.514190] [069/130] (603s left) gen9_exec_parse (bb-large) [417.577710] Subtest bb-large: SKIP (0.000s) [417.637062] [070/130] (603s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [417.740463] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip [417.744931] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s) [417.828967] [071/130] (603s left) kms_psr (psr-sprite-plane-onoff) [417.921621] Starting subtest: psr-sprite-plane-onoff [417.924011] Starting dynamic subtest: eDP-1 [419.642867] Dynamic subtest eDP-1: SUCCESS (1.723s) [419.645213] Subtest psr-sprite-plane-onoff: SUCCESS (1.724s) [420.469422] [072/130] (601s left) gem_busy (extended) [420.653239] Starting subtest: extended [420.655481] Starting dynamic subtest: rcs0 [420.754230] Dynamic subtest rcs0: SUCCESS (0.102s) [420.765831] Starting dynamic subtest: bcs0 [420.800372] Dynamic subtest bcs0: SUCCESS (0.044s) [420.810542] Starting dynamic subtest: vcs0 [421.936219] Dynamic subtest vcs0: SUCCESS (1.132s) [421.951205] Starting dynamic subtest: vcs1 [423.920256] Dynamic subtest vcs1: SUCCESS (1.981s) [423.932896] Starting dynamic subtest: vecs0 [425.967389] Dynamic subtest vecs0: SUCCESS (2.045s) [425.978532] Starting dynamic subtest: ccs0 [426.012413] Dynamic subtest ccs0: SUCCESS (0.044s) [426.014854] Subtest extended: SUCCESS (5.361s) [426.265437] [073/130] (595s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite) [426.374311] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite [426.378592] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [426.483548] [074/130] (595s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers) [426.583141] Starting subtest: plane-downscale-factor-0-5-with-modifiers [426.585387] Starting dynamic subtest: pipe-A [426.594552] Dynamic subtest pipe-A: SKIP (0.004s) [426.604185] Starting dynamic subtest: pipe-B [426.613172] Dynamic subtest pipe-B: SKIP (0.020s) [426.623707] Starting dynamic subtest: pipe-C [426.633941] Dynamic subtest pipe-C: SKIP (0.016s) [426.643403] Starting dynamic subtest: pipe-D [426.652428] Dynamic subtest pipe-D: SKIP (0.016s) [426.656936] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.058s) [426.751780] [075/130] (594s left) kms_fbcon_fbt (psr) [426.809092] Starting subtest: psr [432.107878] Subtest psr: SUCCESS (5.299s) [432.175037] [076/130] (589s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite) [432.275810] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite [436.623530] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (4.351s) [436.768062] [077/130] (584s left) kms_big_fb (y-tiled-8bpp-rotate-180) [436.883848] Starting subtest: y-tiled-8bpp-rotate-180 [436.888236] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s) [436.991464] [078/130] (584s left) kms_pm_dc (dc5-dpms-negative) [438.107476] Starting subtest: dc5-dpms-negative [438.112117] Subtest dc5-dpms-negative: SKIP (0.000s) [438.171823] [079/130] (583s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt) [438.276403] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt [438.280364] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [438.359555] [080/130] (583s left) gem_ctx_isolation (dirty-create) [438.426504] Starting subtest: dirty-create [438.428959] Starting dynamic subtest: rcs0 [438.867832] Dynamic subtest rcs0: SUCCESS (0.441s) [438.876204] Starting dynamic subtest: bcs0 [439.311790] Dynamic subtest bcs0: SUCCESS (0.443s) [439.321247] Starting dynamic subtest: vcs0 [439.798182] Dynamic subtest vcs0: SUCCESS (0.486s) [439.808961] Starting dynamic subtest: vcs1 [441.404112] Dynamic subtest vcs1: SUCCESS (1.605s) [441.414565] Starting dynamic subtest: vecs0 [443.391728] Dynamic subtest vecs0: SUCCESS (1.987s) [443.402583] Starting dynamic subtest: ccs0 [445.362153] Dynamic subtest ccs0: SUCCESS (1.970s) [445.364573] Subtest dirty-create: SUCCESS (6.936s) [445.615283] [081/130] (576s left) kms_psr (fbc-pr-no-drrs) [445.730066] Starting subtest: fbc-pr-no-drrs [445.734100] Subtest fbc-pr-no-drrs: SKIP (0.000s) [445.799113] [082/130] (576s left) kms_addfb_basic (unused-modifier) [445.858255] Starting subtest: unused-modifier [445.860523] Subtest unused-modifier: SUCCESS (0.000s) [445.994417] [083/130] (575s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode) [446.385062] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s) [447.143549] [084/130] (574s left) gem_mmap (big-bo) [447.203793] Subtest big-bo: SKIP (0.000s) [447.266163] [085/130] (574s left) syncobj_eventfd (timeline-wait-signaled) [447.330704] Starting subtest: timeline-wait-signaled [447.332954] Subtest timeline-wait-signaled: SUCCESS (0.000s) [447.427203] [086/130] (574s left) gem_mmap (short-mmap) [447.485110] Subtest short-mmap: SKIP (0.000s) [447.545091] [087/130] (574s left) kms_big_fb (x-tiled-16bpp-rotate-180) [447.650539] Starting subtest: x-tiled-16bpp-rotate-180 [448.919127] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.267s) [449.068595] [088/130] (573s left) kms_cursor_crc (cursor-size-hints) [449.181910] Starting subtest: cursor-size-hints [449.184770] Starting dynamic subtest: pipe-A-eDP-1 [449.799648] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.617s) [449.810081] Starting dynamic subtest: pipe-D-eDP-1 [451.035721] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.233s) [451.038459] Subtest cursor-size-hints: SUCCESS (1.852s) [451.815559] [089/130] (570s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [451.952827] Starting subtest: basic-flip-after-cursor-atomic [452.856562] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.905s) [453.651753] [090/130] (568s left) kms_addfb_basic (framebuffer-vs-set-tiling) [453.713275] Starting subtest: framebuffer-vs-set-tiling [453.718093] Subtest framebuffer-vs-set-tiling: SKIP (0.000s) [453.845493] [091/130] (568s left) kms_cursor_edge_walk (128x128-top-bottom) [453.949152] Starting subtest: 128x128-top-bottom [453.951491] Starting dynamic subtest: pipe-A-eDP-1 [457.402638] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.452s) [457.411417] Starting dynamic subtest: pipe-D-eDP-1 [460.534070] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.129s) [460.536441] Subtest 128x128-top-bottom: SUCCESS (6.584s) [461.326534] [092/130] (560s left) syncobj_timeline (wait-all-for-submit-delayed-submit) [461.377897] Starting subtest: wait-all-for-submit-delayed-submit [461.480363] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s) [461.551082] [093/130] (560s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [461.649295] Starting subtest: compare-crc-sanitycheck-xr24 [461.651632] Starting dynamic subtest: pipe-A-eDP-1 [462.266886] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.615s) [462.275901] Starting dynamic subtest: pipe-B-eDP-1 [463.478781] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.210s) [463.487290] Starting dynamic subtest: pipe-C-eDP-1 [464.649946] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.172s) [464.659128] Starting dynamic subtest: pipe-D-eDP-1 [465.876928] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.225s) [465.879596] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.227s) [466.722924] [094/130] (555s left) kms_atomic (plane-primary-legacy) [466.832167] Starting subtest: plane-primary-legacy [466.834509] Starting dynamic subtest: pipe-A-eDP-1 [467.292249] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.458s) [467.294730] Subtest plane-primary-legacy: SUCCESS (0.460s) [468.146661] [095/130] (554s left) prime_self_import (basic-llseek-bad) [468.182750] Starting subtest: basic-llseek-bad [468.212884] Subtest basic-llseek-bad: SUCCESS (0.030s) [468.309661] [096/130] (554s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs) [468.416246] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs [468.420892] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s) [469.570407] [097/130] (552s left) syncobj_timeline (signal-point-0) [469.630429] Starting subtest: signal-point-0 [469.632385] Subtest signal-point-0: SUCCESS (0.000s) [469.706896] [098/130] (552s left) kms_psr (psr2-suspend) [469.800318] Starting subtest: psr2-suspend [469.802489] Starting dynamic subtest: eDP-1 [487.374914] Dynamic subtest eDP-1: SUCCESS (2.809s) [487.377547] Subtest psr2-suspend: SUCCESS (2.810s) [488.148813] [099/130] (549s left) gem_ctx_param (root-set) [488.206010] Starting subtest: root-set [488.208439] Subtest root-set: SUCCESS (0.001s) [488.313861] [100/130] (548s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs) [488.367037] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs [488.371629] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s) [488.547558] [101/130] (548s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs) [488.645529] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs [488.647678] Starting dynamic subtest: pipe-A-eDP-1 [488.669284] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [488.678483] Starting dynamic subtest: pipe-B-eDP-1 [488.699628] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [488.708120] Starting dynamic subtest: pipe-C-eDP-1 [488.728806] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [488.737133] Starting dynamic subtest: pipe-D-eDP-1 [488.758521] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [488.762494] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.004s) [490.768950] [102/130] (546s left) kms_addfb_basic (addfb25-y-tiled-legacy) [490.869321] Starting subtest: addfb25-y-tiled-legacy [490.871494] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [490.965889] [103/130] (546s left) kms_vrr (negative-basic) [491.056376] Starting subtest: negative-basic [491.063176] Starting dynamic subtest: pipe-A-eDP-1 [496.512900] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.489s) [496.805826] Subtest negative-basic: SUCCESS (5.794s) [497.645286] [104/130] (539s left) gem_ctx_persistence (legacy-engines-hostile) [497.698640] Starting subtest: legacy-engines-hostile [497.700947] Starting dynamic subtest: render [497.806080] Dynamic subtest render: SUCCESS (0.107s) [497.817811] Starting dynamic subtest: bsd1 [497.925799] Dynamic subtest bsd1: SUCCESS (0.118s) [497.937264] Starting dynamic subtest: bsd2 [497.998007] Dynamic subtest bsd2: SUCCESS (0.070s) [498.008629] Starting dynamic subtest: blt [498.147267] Dynamic subtest blt: SUCCESS (0.147s) [498.158843] Starting dynamic subtest: vebox [498.255824] Dynamic subtest vebox: SUCCESS (0.107s) [498.258269] Subtest legacy-engines-hostile: SUCCESS (0.561s) [498.331397] [105/130] (539s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc) [498.431693] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc [498.435860] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [498.527359] [106/130] (538s left) kms_fb_coherency (memset-crc) [498.617848] Starting subtest: memset-crc [498.619668] Starting dynamic subtest: mmap-offset-wc [499.001369] Dynamic subtest mmap-offset-wc: SUCCESS (0.387s) [499.287878] Starting dynamic subtest: mmap-offset-uc [505.252900] Dynamic subtest mmap-offset-uc: SUCCESS (5.991s) [505.544480] Subtest memset-crc: SUCCESS (6.948s) [506.384338] [107/130] (531s left) api_intel_bb (add-remove-objects) [506.450099] Starting subtest: add-remove-objects [506.455167] Subtest add-remove-objects: SUCCESS (0.008s) [506.588584] [108/130] (530s left) kms_setmode (basic-clone-single-crtc) [506.658058] Starting subtest: basic-clone-single-crtc [506.665162] Subtest basic-clone-single-crtc: SKIP (0.000s) [506.767060] [109/130] (530s left) gem_softpin (full) [506.835658] Starting subtest: full [506.842013] Subtest full: SUCCESS (0.006s) [506.986154] [110/130] (530s left) gem_lmem_swapping (heavy-verify-random-ccs) [507.026938] Subtest heavy-verify-random-ccs: SKIP (0.000s) [507.071967] [111/130] (530s left) kms_chamelium_color (ctm-blue-to-red) [507.177526] Subtest ctm-blue-to-red: SKIP (0.000s) [507.219020] [112/130] (530s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff) [507.330566] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff [507.334064] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s) [507.434552] [113/130] (530s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw) [507.537883] Starting subtest: psr-1p-pri-indfb-multidraw [527.660743] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (20.128s) [527.781975] [114/130] (509s left) kms_pm_backlight (basic-brightness) [527.888763] Starting subtest: basic-brightness [527.949874] Starting dynamic subtest: eDP-1 [528.457718] Dynamic subtest eDP-1: SUCCESS (0.504s) [528.459823] Subtest basic-brightness: SUCCESS (0.568s) [529.244673] [115/130] (508s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf) [534.203368] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf [534.205954] Starting dynamic subtest: pipe-A-eDP-1 [534.991708] Dynamic subtest pipe-A-eDP-1: SKIP (0.787s) [535.004114] Starting dynamic subtest: pipe-B-eDP-1 [536.114642] Dynamic subtest pipe-B-eDP-1: SKIP (1.119s) [536.119286] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.910s) [537.289780] [116/130] (500s left) gem_exec_schedule (preempt-queue-contexts) [537.370808] Subtest preempt-queue-contexts: SKIP (0.000s) [537.656919] [117/130] (500s left) gem_exec_fence (submit-chain) [537.831002] Starting subtest: submit-chain [537.846104] Subtest submit-chain: SUCCESS (0.016s) [539.785927] [118/130] (498s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move) [539.895820] Starting subtest: fbc-2p-scndscrn-spr-indfb-move [539.900252] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [539.984201] [119/130] (497s left) gem_mmap (pf-nonblock) [540.049067] Subtest pf-nonblock: SKIP (0.000s) [540.110418] [120/130] (497s left) api_intel_bb (reset-bb) [540.162686] Starting subtest: reset-bb [540.164918] Subtest reset-bb: SUCCESS (0.003s) [540.276035] [121/130] (497s left) i915_module_load (reload-no-display) [540.315255] Starting subtest: reload-no-display [546.525238] Subtest reload-no-display: SUCCESS (6.211s) [546.550846] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [546.551613] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true [546.554634] [122/130] (491s left) kms_vblank (crtc-id) [550.240400] Starting subtest: crtc-id [550.242550] Starting dynamic subtest: pipe-A-eDP-1 [550.729644] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.487s) [550.742282] Starting dynamic subtest: pipe-D-eDP-1 [551.865640] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s) [551.867970] Subtest crtc-id: SUCCESS (1.625s) [552.697987] [FACT kms_vblank (crtc-id)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [552.700151] [FACT kms_vblank (crtc-id)] new: kernel.kmod_is_loaded.amdgpu: true [552.700264] [FACT kms_vblank (crtc-id)] new: kernel.kmod_is_loaded.i915: true [552.700861] [FACT kms_vblank (crtc-id)] new: kernel.kmod_is_loaded.xe: true [552.704205] [123/130] (485s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt) [552.808917] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt [552.813083] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [552.914198] [124/130] (485s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf) [557.940687] Starting subtest: pr-overlay-plane-update-continuous-sf [557.945800] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.002s) [558.797833] [125/130] (479s left) kms_hdr (static-toggle) [558.890410] Starting subtest: static-toggle [558.897283] Subtest static-toggle: SKIP (0.000s) [558.960645] [126/130] (479s left) gem_userptr_blits (access-control) [559.040746] Starting subtest: access-control [559.045479] Subtest access-control: SKIP (0.000s) [559.151565] [127/130] (479s left) dmabuf_sync_file (import-multiple-read-only) [559.198280] Starting subtest: import-multiple-read-only [559.200534] Subtest import-multiple-read-only: SUCCESS (0.003s) [559.248148] [128/130] (478s left) gem_render_copy (yf-tiled) [559.312468] Starting subtest: yf-tiled [559.317106] Subtest yf-tiled: SKIP (0.000s) [559.490747] [129/130] (478s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c) [559.541437] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s) [559.601180] [130/130] (478s left) kms_flip (plain-flip-ts-check) [559.703315] Starting subtest: plain-flip-ts-check [559.705599] Starting dynamic subtest: A-eDP1 [568.595745] Dynamic subtest A-eDP1: SUCCESS (8.894s) [568.604917] Starting dynamic subtest: B-eDP1 [577.519905] Dynamic subtest B-eDP1: SUCCESS (8.925s) [577.527851] Starting dynamic subtest: C-eDP1 [586.418794] Dynamic subtest C-eDP1: SUCCESS (8.898s) [586.427915] Starting dynamic subtest: D-eDP1 [595.245117] Dynamic subtest D-eDP1: SUCCESS (8.823s) [595.247500] Subtest plain-flip-ts-check: SUCCESS (35.547s) [596.415081] Closing watchdogs Done.