[56.106934] Initializing watchdogs
[56.106986]   /dev/watchdog0
[56.121788] [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]
[56.126645] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.129385] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.132877] [001/155] (960s left) kms_pipe_crc_basic (read-crc)
[56.248924] Starting subtest: read-crc
[56.251318] Starting dynamic subtest: pipe-A-eDP-1
[56.982354] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.742s)
[56.991822] Starting dynamic subtest: pipe-B-eDP-1
[58.365683] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.398s)
[58.374184] Starting dynamic subtest: pipe-C-eDP-1
[59.748301] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.394s)
[59.756152] Starting dynamic subtest: pipe-D-eDP-1
[61.064117] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.326s)
[61.066396] Subtest read-crc: SUCCESS (4.864s)
[61.892257] [002/155] (954s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-pwrite)
[62.002760] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-pwrite
[62.007204] Subtest fbchdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[62.101221] [003/155] (954s left) gem_exec_reloc (basic-gtt)
[62.177785] Subtest basic-gtt: SKIP (0.000s)
[62.236978] [004/155] (954s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-shrfb-plflip-blt)
[62.335125] Starting subtest: fbchdr-1p-primscrn-shrfb-plflip-blt
[62.339240] Subtest fbchdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[62.435720] [005/155] (954s left) gem_render_copy (linear-to-vebox-y-tiled)
[62.496465] Starting subtest: linear-to-vebox-y-tiled
[62.500278] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[62.672197] [006/155] (954s left) gem_ctx_isolation (nonpriv)
[62.734299] Starting subtest: nonpriv
[62.736623] Starting dynamic subtest: rcs0
[62.986940] Dynamic subtest rcs0: SUCCESS (0.254s)
[62.996813] Starting dynamic subtest: bcs0
[63.239434] Dynamic subtest bcs0: SUCCESS (0.253s)
[63.248105] Starting dynamic subtest: vcs0
[63.515669] Dynamic subtest vcs0: SUCCESS (0.278s)
[63.523765] Starting dynamic subtest: vcs1
[65.551593] Dynamic subtest vcs1: SUCCESS (2.045s)
[65.559526] Starting dynamic subtest: vecs0
[67.512810] Dynamic subtest vecs0: SUCCESS (1.967s)
[67.520573] Starting dynamic subtest: ccs0
[69.589617] Dynamic subtest ccs0: SUCCESS (2.082s)
[69.591979] Subtest nonpriv: SUCCESS (6.882s)
[69.841900] [007/155] (947s left) gem_exec_fence (syncobj-channel)
[70.304115] Starting subtest: syncobj-channel
[71.326062] Subtest syncobj-channel: SUCCESS (1.020s)
[71.632951] [FACT gem_exec_fence (syncobj-channel)] new: kernel.kmod_is_loaded.vgem: true
[71.636541] [008/155] (945s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[71.739817] Starting subtest: fbc-shrfb-scaledprimary
[74.644237] Subtest fbc-shrfb-scaledprimary: SUCCESS (2.913s)
[75.849176] [009/155] (941s left) gem_ccs (block-copy-uncompressed)
[75.914948] Starting subtest: block-copy-uncompressed
[75.917865] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[75.937076] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s)
[75.947407] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[75.953835] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[75.961453] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[75.970240] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[75.978296] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[75.987871] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[75.990137] Subtest block-copy-uncompressed: SUCCESS (0.074s)
[76.121411] [010/155] (940s left) gem_ctx_persistence (legacy-engines-hang)
[76.172889] Starting subtest: legacy-engines-hang
[76.175168] Starting dynamic subtest: render
[76.255332] Dynamic subtest render: SUCCESS (0.082s)
[76.265043] Starting dynamic subtest: bsd1
[76.352571] Dynamic subtest bsd1: SUCCESS (0.095s)
[76.362716] Starting dynamic subtest: bsd2
[76.428368] Dynamic subtest bsd2: SUCCESS (0.075s)
[76.438327] Starting dynamic subtest: blt
[76.543529] Dynamic subtest blt: SUCCESS (0.114s)
[76.555264] Starting dynamic subtest: vebox
[76.670234] Dynamic subtest vebox: SUCCESS (0.125s)
[76.672715] Subtest legacy-engines-hang: SUCCESS (0.498s)
[76.761631] [011/155] (940s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt)
[76.864327] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt
[76.868034] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[76.962506] [012/155] (940s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[77.059447] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[77.063313] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[78.174757] [013/155] (938s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[78.272975] Starting subtest: psr-1p-primscrn-cur-indfb-move
[85.647162] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.383s)
[85.784185] [014/155] (931s left) kms_addfb_basic (unused-pitches)
[85.848784] Starting subtest: unused-pitches
[85.851252] Subtest unused-pitches: SUCCESS (0.000s)
[85.985867] [015/155] (931s left) gem_flink_basic (double-flink)
[86.038813] Starting subtest: double-flink
[86.041189] Subtest double-flink: SUCCESS (0.000s)
[86.149411] [016/155] (931s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[86.244475] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[86.248731] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[86.335484] [017/155] (930s left) kms_getfb (getfb-handle-protection)
[86.436701] Starting subtest: getfb-handle-protection
[86.462986] Subtest getfb-handle-protection: SUCCESS (0.027s)
[86.583626] [018/155] (930s left) i915_suspend (debugfs-reader)
[86.638073] Starting subtest: debugfs-reader
[104.918220] Subtest debugfs-reader: SUCCESS (3.283s)
[105.115299] [019/155] (912s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[105.228738] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[105.232725] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[105.337762] [020/155] (912s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-cpu)
[105.433780] Starting subtest: fbchdr-rgb565-draw-mmap-cpu
[105.438709] Subtest fbchdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[105.543046] [021/155] (912s left) gem_exec_schedule (fairslice)
[105.612787] Starting subtest: fairslice
[105.614947] Starting dynamic subtest: rcs0
[107.630238] Dynamic subtest rcs0: SUCCESS (2.060s)
[107.638897] Starting dynamic subtest: bcs0
[109.653526] Dynamic subtest bcs0: SUCCESS (2.050s)
[109.664040] Starting dynamic subtest: vcs0
[111.675893] Dynamic subtest vcs0: SUCCESS (2.045s)
[111.689082] Starting dynamic subtest: vcs1
[113.700573] Dynamic subtest vcs1: SUCCESS (2.039s)
[113.711669] Starting dynamic subtest: vecs0
[115.721294] Dynamic subtest vecs0: SUCCESS (2.034s)
[115.734671] Starting dynamic subtest: ccs0
[117.741908] Dynamic subtest ccs0: SUCCESS (2.031s)
[117.744175] Subtest fairslice: SUCCESS (12.265s)
[118.043320] [022/155] (899s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[118.148813] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[125.662261] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.541s)
[125.786925] [023/155] (891s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[125.894446] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[125.898620] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[125.994505] [024/155] (891s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[126.042944] Starting subtest: etime-multi-wait-all-submitted-signaled
[126.148558] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.105s)
[126.236243] [025/155] (891s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[126.339936] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[126.344449] Subtest fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[126.441476] [026/155] (891s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-indfb-plflip-blt)
[126.537076] Starting subtest: hdr-2p-scndscrn-indfb-plflip-blt
[126.541517] Subtest hdr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[126.653402] [027/155] (891s left) kms_addfb_basic (addfb25-4-tiled)
[126.749662] Starting subtest: addfb25-4-tiled
[126.751615] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[126.852832] [028/155] (891s left) kms_pipe_crc_basic (hang-read-crc)
[126.950092] Starting subtest: hang-read-crc
[126.952536] Starting dynamic subtest: pipe-A-eDP-1
[129.033755] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.087s)
[129.044227] Starting dynamic subtest: pipe-B-eDP-1
[131.888058] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.857s)
[131.899234] Starting dynamic subtest: pipe-C-eDP-1
[134.626276] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.738s)
[134.636839] Starting dynamic subtest: pipe-D-eDP-1
[137.304116] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.676s)
[137.306393] Subtest hang-read-crc: SUCCESS (10.363s)
[138.125326] [029/155] (879s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[138.238963] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[138.243537] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[138.336286] [030/155] (879s left) gem_create (hog-create)
[138.394026] Starting subtest: hog-create
[138.396280] Starting dynamic subtest: smem0
[172.263429] Dynamic subtest smem0: SUCCESS (33.874s)
[172.266062] Subtest hog-create: SUCCESS (33.875s)
[172.447489] [031/155] (845s left) kms_rotation_crc (sprite-rotation-270)
[172.540131] Starting subtest: sprite-rotation-270
[172.544710] Subtest sprite-rotation-270: SKIP (0.001s)
[172.615870] [032/155] (845s left) gem_request_retire (retire-vma-not-inactive)
[172.680575] Starting subtest: retire-vma-not-inactive
[172.740981] Subtest retire-vma-not-inactive: SUCCESS (0.057s)
[172.844466] [033/155] (845s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[172.931574] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[172.933908] Starting dynamic subtest: pipe-A-eDP-1
[172.957239] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[172.969212] Starting dynamic subtest: pipe-B-eDP-1
[172.991406] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[173.000624] Starting dynamic subtest: pipe-C-eDP-1
[173.022701] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[173.031411] Starting dynamic subtest: pipe-D-eDP-1
[173.052966] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[173.056797] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[175.198614] [034/155] (842s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[175.298963] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[175.302968] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[175.388677] [035/155] (842s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[175.489967] Starting subtest: fbc-rgb565-draw-mmap-gtt
[175.494374] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[175.601915] [036/155] (842s left) api_intel_allocator (standalone)
[175.659204] Starting subtest: standalone
[175.674695] Subtest standalone: SUCCESS (0.017s)
[175.824760] [037/155] (842s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[175.932445] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[182.770532] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.846s)
[182.943353] [038/155] (835s left) syncobj_wait (wait-any-complex)
[182.992089] Starting subtest: wait-any-complex
[183.052722] Subtest wait-any-complex: SUCCESS (0.062s)
[183.131988] [039/155] (835s left) kms_chamelium_edid (hdmi-mode-timings)
[183.538029] Subtest hdmi-mode-timings: SKIP (0.000s)
[184.331086] [040/155] (834s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[184.432059] Starting subtest: pr-cursor-plane-update-sf
[184.436561] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[184.512442] [041/155] (833s left) kms_selftest (drm_format_helper)
[184.554412] Starting subtest: drm_format_helper
[184.603771] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[184.690523] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.088s)
[184.700458] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[184.796991] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.107s)
[184.808280] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[184.888849] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.090s)
[184.899822] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[184.984317] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.092s)
[184.994254] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[185.111291] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.127s)
[185.122106] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[185.205237] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.094s)
[185.216212] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[185.300158] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.094s)
[185.311300] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[185.399573] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.096s)
[185.409038] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[185.500956] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.099s)
[185.510145] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[185.606070] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.106s)
[185.615357] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[185.686860] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.082s)
[185.697747] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[185.787327] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.097s)
[185.797636] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[185.893218] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.105s)
[185.903235] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[186.000552] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.106s)
[186.010063] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[186.102672] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.103s)
[186.111915] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[186.182969] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.078s)
[186.192292] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[186.288723] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.107s)
[186.290642] Subtest drm_format_helper: SUCCESS (1.738s)
[186.462908] [042/155] (832s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[186.563003] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[186.565213] Starting dynamic subtest: pipe-A-eDP-1
[186.587979] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[186.600170] Starting dynamic subtest: pipe-B-eDP-1
[186.622003] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[186.631269] Starting dynamic subtest: pipe-C-eDP-1
[186.653068] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[186.662229] Starting dynamic subtest: pipe-D-eDP-1
[186.685244] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[186.689341] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[188.840920] [043/155] (829s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[188.924855] Starting subtest: random-ccs-data-y-tiled-ccs
[188.927165] Starting dynamic subtest: pipe-A-eDP-1
[188.952484] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[188.964353] Starting dynamic subtest: pipe-B-eDP-1
[188.988301] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[188.997347] Starting dynamic subtest: pipe-C-eDP-1
[189.021153] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[189.029752] Starting dynamic subtest: pipe-D-eDP-1
[189.053182] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[189.057824] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[191.195839] [044/155] (827s left) gem_lmem_swapping (massive)
[191.242236] Subtest massive: SKIP (0.000s)
[191.292908] [045/155] (827s left) core_getclient (basic)
[191.335188] Starting subtest: basic
[191.337168] Subtest basic: SUCCESS (0.000s)
[191.430150] [046/155] (827s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[191.479451] Starting subtest: single-wait-for-submit-available-signaled
[191.481374] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[191.589413] [047/155] (827s left) gem_exec_create (legacy)
[191.638577] Starting subtest: legacy
[191.641048] Starting dynamic subtest: smem
[195.302732] Dynamic subtest smem: SUCCESS (3.663s)
[195.305520] Subtest legacy: SUCCESS (3.665s)
[195.488900] [048/155] (823s left) kms_plane_scaling (planes-upscale-20x20)
[195.584976] Starting subtest: planes-upscale-20x20
[195.587274] Starting dynamic subtest: pipe-A
[195.644785] Dynamic subtest pipe-A: SUCCESS (0.062s)
[195.654483] Starting dynamic subtest: pipe-B
[196.807433] Dynamic subtest pipe-B: SUCCESS (1.162s)
[196.818936] Starting dynamic subtest: pipe-C
[197.906989] Dynamic subtest pipe-C: SUCCESS (1.098s)
[197.916340] Starting dynamic subtest: pipe-D
[199.033051] Dynamic subtest pipe-D: SUCCESS (1.126s)
[199.035333] Subtest planes-upscale-20x20: SUCCESS (3.452s)
[200.198480] [049/155] (818s left) gem_softpin (evict-prime)
[200.252919] Starting subtest: evict-prime
[200.255193] Starting dynamic subtest: rcs0
[201.405878] Dynamic subtest rcs0: SUCCESS (1.152s)
[201.417845] Starting dynamic subtest: bcs0
[202.543056] Dynamic subtest bcs0: SUCCESS (1.135s)
[202.554698] Starting dynamic subtest: vcs0
[204.272094] Dynamic subtest vcs0: SUCCESS (1.724s)
[204.285384] Starting dynamic subtest: vcs1
[206.245672] Dynamic subtest vcs1: SUCCESS (1.971s)
[206.258709] Starting dynamic subtest: vecs0
[208.301649] Dynamic subtest vecs0: SUCCESS (2.053s)
[208.313899] Starting dynamic subtest: ccs0
[209.434032] Dynamic subtest ccs0: SUCCESS (1.131s)
[209.445448] Starting dynamic subtest: all
[211.300905] Dynamic subtest all: SUCCESS (1.863s)
[211.303896] Subtest evict-prime: SUCCESS (11.042s)
[211.381636] [050/155] (807s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[211.495058] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[216.520191] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (5.029s)
[217.932853] [051/155] (800s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[218.060558] Starting subtest: 2x-flip-vs-cursor-atomic
[218.065127] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[218.207224] [052/155] (800s left) kms_psr (pr-suspend)
[218.308096] Starting subtest: pr-suspend
[218.312518] Subtest pr-suspend: SKIP (0.000s)
[218.381053] [053/155] (800s left) gem_exec_reloc (basic-gtt-read-active)
[218.458621] Subtest basic-gtt-read-active: SKIP (0.000s)
[218.534682] [054/155] (800s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-move)
[218.635921] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-move
[218.640266] Subtest fbchdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[218.736319] [055/155] (800s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-onoff)
[218.835392] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-onoff
[218.839006] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[218.917019] [056/155] (800s left) i915_pm_rc6_residency (rc6-fence)
[218.971569] Starting subtest: rc6-fence
[218.989236] Starting dynamic subtest: gt0
[232.196555] Dynamic subtest gt0: SUCCESS (13.203s)
[232.207994] Starting dynamic subtest: gt1
[247.270908] Dynamic subtest gt1: SUCCESS (15.072s)
[247.273978] Subtest rc6-fence: SUCCESS (28.299s)
[247.351953] [057/155] (771s left) kms_psr (fbc-psr-no-drrs)
[247.443839] Starting subtest: fbc-psr-no-drrs
[247.446128] Starting dynamic subtest: eDP-1
[247.450352] Dynamic subtest eDP-1: SKIP (0.000s)
[247.454611] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[247.516576] [058/155] (771s left) gem_exec_reloc (basic-cpu-read)
[247.578731] Subtest basic-cpu-read: SKIP (0.000s)
[247.657817] [059/155] (771s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[247.764230] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[247.768442] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[247.871639] [060/155] (771s left) perf (invalid-remove-userspace-config)
[247.985965] Starting subtest: invalid-remove-userspace-config
[247.992792] Subtest invalid-remove-userspace-config: SUCCESS (0.008s)
[248.035221] [061/155] (771s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[248.097440] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[248.099341] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[248.203890] [062/155] (771s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[248.307782] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[249.332008] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.024s)
[249.457544] [063/155] (769s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[249.557780] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[249.561875] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[249.650701] [064/155] (769s left) gem_userptr_blits (mmap-offset-banned)
[249.714483] Starting subtest: mmap-offset-banned
[249.716278] Starting dynamic subtest: wb
[249.717874] Dynamic subtest wb: SUCCESS (0.000s)
[249.727674] Starting dynamic subtest: wc
[249.729450] Dynamic subtest wc: SUCCESS (0.000s)
[249.738689] Starting dynamic subtest: uc
[249.740487] Dynamic subtest uc: SUCCESS (0.000s)
[249.742276] Subtest mmap-offset-banned: SUCCESS (0.003s)
[249.825844] [065/155] (769s left) kms_atomic (atomic-plane-damage)
[249.907855] Starting subtest: atomic-plane-damage
[249.956590] Starting dynamic subtest: pipe-A-eDP-1
[250.489381] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.534s)
[250.491677] Subtest atomic-plane-damage: SUCCESS (0.583s)
[251.341925] [066/155] (768s left) gem_exec_schedule (preempt-queue-contexts-chain)
[251.429340] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[251.735971] [067/155] (767s left) gem_fenced_exec_thrash (2-spare-fences)
[251.793015] Subtest 2-spare-fences: SKIP (0.000s)
[251.862363] [068/155] (767s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[251.962046] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[251.964198] Starting dynamic subtest: pipe-A-eDP-1
[257.843434] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.886s)
[257.856034] Starting dynamic subtest: pipe-B-eDP-1
[264.464819] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.622s)
[264.467219] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.511s)
[265.338790] [069/155] (754s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[265.449631] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[265.453964] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[265.552206] [070/155] (754s left) sysfs_preempt_timeout (timeout)
[265.598519] Starting subtest: timeout
[265.600777] Starting dynamic subtest: vecs0
[275.295390] Dynamic subtest vecs0: SUCCESS (9.695s)
[275.305720] Starting dynamic subtest: ccs0
[276.635886] Dynamic subtest ccs0: SUCCESS (1.342s)
[276.646962] Starting dynamic subtest: vcs0
[286.304111] Dynamic subtest vcs0: SUCCESS (9.664s)
[286.316922] Starting dynamic subtest: bcs0
[287.626455] Dynamic subtest bcs0: SUCCESS (1.321s)
[287.639766] Starting dynamic subtest: rcs0
[288.900004] Dynamic subtest rcs0: SUCCESS (1.273s)
[288.915865] Starting dynamic subtest: vcs1
[298.275353] Dynamic subtest vcs1: SUCCESS (9.373s)
[298.277906] Subtest timeout: SUCCESS (32.682s)
[298.359075] [071/155] (721s left) gem_exec_params (rel-constants-invalid)
[298.408796] Starting subtest: rel-constants-invalid
[298.411112] Subtest rel-constants-invalid: SUCCESS (0.000s)
[298.527779] [072/155] (721s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[298.632845] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[298.637122] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[298.732238] [073/155] (721s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[298.840643] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[298.844954] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[298.967172] [074/155] (720s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[299.074245] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-pwrite
[299.078404] Subtest psrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[299.160054] [075/155] (720s left) kms_frontbuffer_tracking (psrhdr-farfromfence-mmap-gtt)
[299.256926] Starting subtest: psrhdr-farfromfence-mmap-gtt
[299.261274] Subtest psrhdr-farfromfence-mmap-gtt: SKIP (0.000s)
[299.383984] [076/155] (720s left) kms_cursor_legacy (forked-bo)
[299.511110] Starting subtest: forked-bo
[299.513219] Starting dynamic subtest: pipe-A
[304.982206] Dynamic subtest pipe-A: SUCCESS (5.468s)
[305.004120] Starting dynamic subtest: pipe-B
[310.462739] Dynamic subtest pipe-B: SUCCESS (5.479s)
[310.475349] Starting dynamic subtest: pipe-C
[315.930942] Dynamic subtest pipe-C: SUCCESS (5.467s)
[315.945072] Starting dynamic subtest: pipe-D
[321.409324] Dynamic subtest pipe-D: SUCCESS (5.478s)
[321.419833] Starting dynamic subtest: all-pipes
[326.926248] Dynamic subtest all-pipes: SUCCESS (5.515s)
[326.928637] Subtest forked-bo: SUCCESS (27.419s)
[327.077998] [077/155] (692s left) gem_userptr_blits (readonly-unsync)
[327.142034] Subtest readonly-unsync: SKIP (0.000s)
[327.280321] [078/155] (692s left) drm_read (short-buffer-wakeup)
[327.419364] Starting subtest: short-buffer-wakeup
[359.646183] Subtest short-buffer-wakeup: SUCCESS (32.215s)
[359.782973] [079/155] (660s left) gem_eio (wait-immediate)
[360.009074] Starting subtest: wait-immediate
[360.359211] Subtest wait-immediate: SUCCESS (0.349s)
[360.498770] [080/155] (659s left) gem_exec_whisper (basic-contexts-forked)
[360.555023] Starting subtest: basic-contexts-forked
[383.762789] Subtest basic-contexts-forked: SUCCESS (23.202s)
[383.952005] [081/155] (636s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[384.064252] Starting subtest: psr-rgb101010-draw-mmap-wc
[391.146278] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.086s)
[391.267360] [082/155] (628s left) kms_rotation_crc (sprite-rotation-180)
[391.361851] Starting subtest: sprite-rotation-180
[398.211160] Subtest sprite-rotation-180: SUCCESS (6.850s)
[399.446436] [083/155] (620s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[399.543832] Starting subtest: fb-8bpc-vs-panel-8bpc
[399.546095] Starting dynamic subtest: pipe-A-eDP-1
[400.908024] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.363s)
[400.909878] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.364s)
[401.705047] [084/155] (618s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[401.789469] Starting subtest: primary-x-tiled-reflect-x-180
[408.848865] Subtest primary-x-tiled-reflect-x-180: SUCCESS (7.061s)
[410.064326] [085/155] (610s left) kms_big_fb (linear-16bpp-rotate-0)
[410.165110] Starting subtest: linear-16bpp-rotate-0
[411.622868] Subtest linear-16bpp-rotate-0: SUCCESS (1.457s)
[411.737109] [086/155] (608s left) kms_dsc (dsc-with-formats)
[411.824546] Starting subtest: dsc-with-formats
[411.857246] Subtest dsc-with-formats: SKIP (0.001s)
[411.898585] [087/155] (608s left) gem_mmap_wc (write-cpu-read-wc)
[411.951761] Subtest write-cpu-read-wc: SKIP (0.000s)
[412.019289] [088/155] (608s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[412.073802] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[412.127539] [089/155] (608s left) gem_exec_schedule (smoketest)
[412.310557] Starting subtest: smoketest
[412.314229] Starting dynamic subtest: rcs0
[417.845102] Dynamic subtest rcs0: SUCCESS (5.480s)
[417.857476] Starting dynamic subtest: bcs0
[423.347171] Dynamic subtest bcs0: SUCCESS (5.503s)
[423.359778] Starting dynamic subtest: vcs0
[428.869121] Dynamic subtest vcs0: SUCCESS (5.519s)
[428.890474] Starting dynamic subtest: vcs1
[434.367896] Dynamic subtest vcs1: SUCCESS (5.504s)
[434.383460] Starting dynamic subtest: vecs0
[439.871815] Dynamic subtest vecs0: SUCCESS (5.497s)
[439.885751] Starting dynamic subtest: ccs0
[445.386685] Dynamic subtest ccs0: SUCCESS (5.518s)
[445.388717] Subtest smoketest: SUCCESS (33.030s)
[445.579214] [090/155] (574s left) gen9_exec_parse (bb-chained)
[445.643579] Subtest bb-chained: SKIP (0.000s)
[445.712992] [091/155] (574s left) kms_color (ctm-green-to-red)
[445.814093] Starting subtest: ctm-green-to-red
[445.816333] Starting dynamic subtest: pipe-A-eDP-1
[446.500360] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[446.511464] Starting dynamic subtest: pipe-B-eDP-1
[447.970300] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.468s)
[447.981659] Starting dynamic subtest: pipe-C-eDP-1
[449.329434] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.358s)
[449.342835] Starting dynamic subtest: pipe-D-eDP-1
[450.640667] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.313s)
[450.643019] Subtest ctm-green-to-red: SUCCESS (4.829s)
[451.475001] [092/155] (569s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[451.582922] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[458.326630] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.749s)
[458.463151] [093/155] (562s left) gem_pxp (create-regular-buffer)
[458.583814] Starting subtest: create-regular-buffer
[458.586458] Subtest create-regular-buffer: SUCCESS (0.000s)
[458.691186] [094/155] (562s left) prime_mmap (test_invalid_sync_flags)
[458.740130] Starting subtest: test_invalid_sync_flags
[458.742468] Starting dynamic subtest: test_invalid_sync_flags-smem
[458.744749] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[458.747005] Subtest test_invalid_sync_flags: SUCCESS (0.001s)
[458.866374] [095/155] (561s left) kms_plane_multiple (tiling-x)
[458.966743] Starting subtest: tiling-x
[458.968868] Starting dynamic subtest: pipe-A-eDP-1
[462.652612] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.685s)
[462.663958] Starting dynamic subtest: pipe-B-eDP-1
[467.286726] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.634s)
[467.297987] Starting dynamic subtest: pipe-C-eDP-1
[471.825659] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.538s)
[471.836775] Starting dynamic subtest: pipe-D-eDP-1
[476.361749] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.533s)
[476.364024] Subtest tiling-x: SUCCESS (17.396s)
[477.186639] [096/155] (543s left) vgem_basic (mmap)
[477.238263] Starting subtest: mmap
[477.260910] Subtest mmap: SUCCESS (0.027s)
[477.331986] [097/155] (543s left) kms_pm_dc (dc5-psr)
[478.435720] Starting subtest: dc5-psr
[481.303670] Subtest dc5-psr: SUCCESS (2.869s)
[482.130247] [098/155] (538s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[482.235265] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[482.239413] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[482.321852] [099/155] (538s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[482.382326] Subtest writes-after-reads: SKIP (0.000s)
[482.459843] [100/155] (538s left) kms_flip (2x-flip-vs-suspend-interruptible)
[482.553989] Starting subtest: 2x-flip-vs-suspend-interruptible
[482.558451] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.002s)
[482.672541] [101/155] (538s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[482.774743] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[482.778686] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[482.872940] [102/155] (538s left) kms_chamelium_hpd (dp-hpd-fast)
[483.258037] Subtest dp-hpd-fast: SKIP (0.000s)
[484.131179] [103/155] (536s left) gem_gtt_cpu_tlb
[484.261836] [104/155] (536s left) kms_frontbuffer_tracking (hdr-rgb565-draw-pwrite)
[484.365706] Starting subtest: hdr-rgb565-draw-pwrite
[484.369844] Subtest hdr-rgb565-draw-pwrite: SKIP (0.000s)
[484.461064] [105/155] (536s left) gem_eio (in-flight-contexts-immediate)
[484.684252] Starting subtest: in-flight-contexts-immediate
[496.324629] Subtest in-flight-contexts-immediate: SUCCESS (11.641s)
[496.498730] [106/155] (524s left) kms_cursor_crc (cursor-dpms)
[496.621099] Starting subtest: cursor-dpms
[496.623315] Starting dynamic subtest: pipe-A-eDP-1
[499.709146] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.087s)
[499.720196] Starting dynamic subtest: pipe-D-eDP-1
[503.426570] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.718s)
[503.428705] Subtest cursor-dpms: SUCCESS (6.808s)
[504.258126] [107/155] (517s left) perf_pmu (busy-hang)
[504.295305] Starting subtest: busy-hang
[504.298040] Starting dynamic subtest: rcs0
[505.560503] Dynamic subtest rcs0: SUCCESS (1.266s)
[505.572208] Starting dynamic subtest: bcs0
[506.809276] Dynamic subtest bcs0: SUCCESS (1.244s)
[506.831007] Starting dynamic subtest: vcs0
[508.839965] Dynamic subtest vcs0: SUCCESS (2.033s)
[508.853642] Starting dynamic subtest: vcs1
[510.823317] Dynamic subtest vcs1: SUCCESS (1.982s)
[510.835556] Starting dynamic subtest: vecs0
[512.810319] Dynamic subtest vecs0: SUCCESS (1.982s)
[512.821918] Starting dynamic subtest: ccs0
[514.043332] Dynamic subtest ccs0: SUCCESS (1.235s)
[514.046114] Subtest busy-hang: SUCCESS (9.752s)
[514.146867] [108/155] (507s left) gem_softpin (evict-snoop-interruptible)
[514.208494] Starting subtest: evict-snoop-interruptible
[514.212744] Subtest evict-snoop-interruptible: SKIP (0.000s)
[514.350579] [109/155] (507s left) gem_mmap_gtt (hang-busy)
[514.401396] Subtest hang-busy: SKIP (0.000s)
[514.457266] [110/155] (506s left) gem_exec_balancer (full-late-pulse)
[514.524225] Starting subtest: full-late-pulse
[519.196199] Subtest full-late-pulse: SUCCESS (4.673s)
[519.542515] [111/155] (501s left) perf_pmu (init-busy)
[519.582893] Starting subtest: init-busy
[519.585195] Starting dynamic subtest: rcs0
[519.587567] Dynamic subtest rcs0: SUCCESS (0.001s)
[519.598917] Starting dynamic subtest: bcs0
[519.600804] Dynamic subtest bcs0: SUCCESS (0.000s)
[519.610860] Starting dynamic subtest: vcs0
[519.612725] Dynamic subtest vcs0: SUCCESS (0.000s)
[519.623122] Starting dynamic subtest: vcs1
[519.625241] Dynamic subtest vcs1: SUCCESS (0.001s)
[519.635661] Starting dynamic subtest: vecs0
[519.637465] Dynamic subtest vecs0: SUCCESS (0.001s)
[519.647874] Starting dynamic subtest: ccs0
[519.649964] Dynamic subtest ccs0: SUCCESS (0.000s)
[519.652150] Subtest init-busy: SUCCESS (0.008s)
[519.693112] [112/155] (501s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[519.815864] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[519.820459] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[519.962333] [113/155] (501s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[520.056084] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[520.058341] Starting dynamic subtest: pipe-A-eDP-1
[520.156023] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[520.167604] Starting dynamic subtest: pipe-B-eDP-1
[521.326306] Dynamic subtest pipe-B-eDP-1: SKIP (1.168s)
[521.330909] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.271s)
[522.513214] [114/155] (499s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[522.616017] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[522.618410] Starting dynamic subtest: pipe-A-default-mode
[525.346948] Dynamic subtest pipe-A-default-mode: SUCCESS (2.732s)
[525.349437] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.734s)
[526.220548] [115/155] (495s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[526.322157] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[533.294837] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.978s)
[533.448553] [116/155] (488s left) kms_frontbuffer_tracking (fbcpsrhdr-slowdraw)
[533.556422] Starting subtest: fbcpsrhdr-slowdraw
[533.560332] Subtest fbcpsrhdr-slowdraw: SKIP (0.000s)
[533.654110] [117/155] (488s left) sw_sync (sync_multi_consumer_producer)
[533.693161] Starting subtest: sync_multi_consumer_producer
[533.977213] Subtest sync_multi_consumer_producer: SUCCESS (0.287s)
[534.053505] [118/155] (487s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[534.146780] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[534.149012] Starting dynamic subtest: pipe-A
[535.272650] Dynamic subtest pipe-A: SUCCESS (1.128s)
[535.285625] Starting dynamic subtest: pipe-B
[537.416356] Dynamic subtest pipe-B: SUCCESS (2.141s)
[537.427618] Starting dynamic subtest: pipe-C
[538.639330] Dynamic subtest pipe-C: SUCCESS (1.222s)
[538.650859] Starting dynamic subtest: pipe-D
[539.872227] Dynamic subtest pipe-D: SUCCESS (1.232s)
[539.874538] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.729s)
[541.066443] [119/155] (480s left) i915_query (query-topology-coherent-slice-mask)
[541.114325] Starting subtest: query-topology-coherent-slice-mask
[541.118722] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[541.207610] [120/155] (480s left) gem_mmap_gtt (basic-read)
[541.260286] Subtest basic-read: SKIP (0.000s)
[541.318662] [121/155] (480s left) core_auth (many-magics)
[541.364302] Starting subtest: many-magics
[542.315307] Subtest many-magics: SUCCESS (0.954s)
[542.457611] [122/155] (479s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite)
[542.560300] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite
[542.563608] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[542.648008] [123/155] (479s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[542.753838] Starting subtest: yf-tiled-16bpp-rotate-0
[542.757554] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[542.863116] [124/155] (479s left) prime_busy (before)
[542.914526] Starting subtest: before
[542.919070] Starting dynamic subtest: rcs0
[542.945246] Dynamic subtest rcs0: SUCCESS (0.027s)
[542.958071] Starting dynamic subtest: bcs0
[542.985955] Dynamic subtest bcs0: SUCCESS (0.038s)
[543.004515] Starting dynamic subtest: vcs0
[543.023880] Dynamic subtest vcs0: SUCCESS (0.039s)
[543.034676] Starting dynamic subtest: vcs1
[543.062657] Dynamic subtest vcs1: SUCCESS (0.038s)
[543.072923] Starting dynamic subtest: vecs0
[543.104018] Dynamic subtest vecs0: SUCCESS (0.040s)
[543.114589] Starting dynamic subtest: ccs0
[543.146484] Dynamic subtest ccs0: SUCCESS (0.042s)
[543.255781] Subtest before: SUCCESS (0.339s)
[543.347365] [125/155] (478s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[543.451453] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[543.455334] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[543.531628] [126/155] (478s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[543.595189] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[543.671625] [127/155] (478s left) kms_invalid_mode (int-max-clock)
[543.758035] Starting subtest: int-max-clock
[543.760298] Starting dynamic subtest: pipe-A-eDP-1
[544.041014] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[544.053651] Starting dynamic subtest: pipe-B-eDP-1
[544.055612] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[544.066176] Starting dynamic subtest: pipe-C-eDP-1
[544.068320] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[544.078729] Starting dynamic subtest: pipe-D-eDP-1
[544.083841] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[544.086659] Subtest int-max-clock: SUCCESS (0.292s)
[544.884885] [128/155] (477s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[544.972865] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[544.976844] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[545.058685] [129/155] (477s left) gem_workarounds (suspend-resume-fd)
[545.119224] Starting subtest: suspend-resume-fd
[561.916942] Subtest suspend-resume-fd: SUCCESS (1.180s)
[562.064118] [130/155] (460s left) kms_psr (psr-primary-blt)
[562.160548] Starting subtest: psr-primary-blt
[562.162799] Starting dynamic subtest: eDP-1
[563.886097] Dynamic subtest eDP-1: SUCCESS (1.755s)
[563.888375] Subtest psr-primary-blt: SUCCESS (1.756s)
[564.726345] [131/155] (457s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[564.827290] Starting subtest: x-tiled-16bpp-rotate-270
[564.845550] Subtest x-tiled-16bpp-rotate-270: SKIP (0.018s)
[564.961409] [132/155] (457s left) i915_module_load (resize-bar)
[564.996987] Starting subtest: resize-bar
[565.003234] Subtest resize-bar: SKIP (0.006s)
[565.042328] [133/155] (457s left) i915_drm_fdinfo (memory-info-active)
[565.077469] Starting subtest: memory-info-active
[565.079720] Starting dynamic subtest: smem0
[567.669853] Dynamic subtest smem0: SUCCESS (2.632s)
[567.672215] Subtest memory-info-active: SUCCESS (2.633s)
[567.744305] [134/155] (454s left) syncobj_timeline (reset-during-wait-for-submit)
[567.802949] Starting subtest: reset-during-wait-for-submit
[567.907132] Subtest reset-during-wait-for-submit: SUCCESS (0.104s)
[568.004827] [135/155] (454s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt)
[568.115944] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt
[568.119951] Subtest fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[568.225179] [136/155] (454s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[568.315485] Starting subtest: multiplane-rotation-cropping-top
[585.738366] Subtest multiplane-rotation-cropping-top: SUCCESS (17.504s)
[585.855821] [137/155] (436s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[585.955534] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[585.958924] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[586.065936] [138/155] (436s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[586.118977] Starting subtest: y-tiled-to-vebox-yf-tiled
[586.122206] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[586.303597] [139/155] (436s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[586.423074] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-wc
[586.427230] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[586.540351] [140/155] (436s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[586.592737] Starting subtest: multi-wait-all-for-submit-available-submitted
[586.595080] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[586.707325] [141/155] (436s left) gem_mmap_wc (coherency)
[586.762792] Subtest coherency: SKIP (0.000s)
[586.822068] [142/155] (435s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[586.913068] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[586.924704] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.011s)
[587.004338] [143/155] (435s left) kms_vblank (wait-forked-busy-hang)
[587.091890] Starting subtest: wait-forked-busy-hang
[587.094034] Starting dynamic subtest: pipe-A-eDP-1
[613.658463] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.568s)
[613.678789] Starting dynamic subtest: pipe-D-eDP-1
[638.703948] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.047s)
[638.706225] Subtest wait-forked-busy-hang: SUCCESS (51.623s)
[639.547277] [144/155] (383s left) gem_ctx_shared (exec-single-timeline)
[639.609408] Starting subtest: exec-single-timeline
[639.611700] Starting dynamic subtest: rcs0
[640.703383] Dynamic subtest rcs0: SUCCESS (1.092s)
[640.718896] Starting dynamic subtest: bcs0
[641.727262] Dynamic subtest bcs0: SUCCESS (1.021s)
[641.743197] Starting dynamic subtest: vcs0
[642.816149] Dynamic subtest vcs0: SUCCESS (1.086s)
[642.830922] Starting dynamic subtest: vcs1
[643.903817] Dynamic subtest vcs1: SUCCESS (1.085s)
[643.917003] Starting dynamic subtest: vecs0
[644.992051] Dynamic subtest vecs0: SUCCESS (1.085s)
[645.007510] Starting dynamic subtest: ccs0
[646.080022] Dynamic subtest ccs0: SUCCESS (1.085s)
[646.083444] Subtest exec-single-timeline: SUCCESS (6.475s)
[647.030113] [145/155] (375s left) kms_cursor_crc (cursor-random-max-size)
[647.350750] Starting subtest: cursor-random-max-size
[647.357681] Subtest cursor-random-max-size: SKIP (0.000s)
[647.468842] [146/155] (375s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[647.573724] Starting subtest: extended-modeset-hang-oldfb-with-reset
[647.576047] Starting dynamic subtest: pipe-A
[659.207162] Dynamic subtest pipe-A: SUCCESS (11.638s)
[659.219650] Starting dynamic subtest: pipe-D
[671.984804] Dynamic subtest pipe-D: SUCCESS (12.768s)
[671.987593] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.410s)
[672.873609] [147/155] (350s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[672.980118] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[672.984228] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[673.090434] [148/155] (349s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[673.190057] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[673.194285] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[673.270507] [149/155] (349s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[673.369254] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[673.373345] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[673.468393] [150/155] (349s left) kms_content_protection (dp-mst-lic-type-1)
[673.604844] Starting subtest: dp-mst-lic-type-1
[673.609398] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[673.739126] [151/155] (349s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[673.843595] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[673.846947] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[673.940370] [152/155] (349s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[674.025653] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[674.027602] Starting dynamic subtest: pipe-A-eDP-1
[674.046618] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[674.058551] Starting dynamic subtest: pipe-B-eDP-1
[674.076542] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[674.087830] Starting dynamic subtest: pipe-C-eDP-1
[674.105156] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[674.115835] Starting dynamic subtest: pipe-D-eDP-1
[674.133601] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[674.137216] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.004s)
[676.351120] [153/155] (346s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[676.468207] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[676.472782] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[676.621529] [154/155] (346s left) kms_psr (fbc-pr-suspend)
[676.714899] Starting subtest: fbc-pr-suspend
[676.718840] Subtest fbc-pr-suspend: SKIP (0.000s)
[676.789903] [155/155] (346s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[676.919979] Starting subtest: 2x-long-flip-vs-cursor-atomic
[676.924422] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[677.074308] Closing watchdogs
results: parsing output: 0/ for test: kms_pipe_crc_basic
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_exec_reloc
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_render_copy
results: parsing output: 5/ for test: gem_ctx_isolation
results: parsing output: 6/ for test: gem_exec_fence
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_ccs
results: parsing output: 9/ for test: gem_ctx_persistence
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_addfb_basic
results: parsing output: 14/ for test: gem_flink_basic
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_getfb
results: parsing output: 17/ for test: i915_suspend
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: gem_exec_schedule
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_addfb_basic
results: parsing output: 27/ for test: kms_pipe_crc_basic
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_create
results: parsing output: 30/ for test: kms_rotation_crc
results: parsing output: 31/ for test: gem_request_retire
results: parsing output: 32/ for test: kms_ccs
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: api_intel_allocator
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: kms_chamelium_edid
results: parsing output: 39/ for test: kms_psr2_sf
results: parsing output: 40/ for test: kms_selftest
results: parsing output: 41/ for test: kms_ccs
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: gem_lmem_swapping
results: parsing output: 44/ for test: core_getclient
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: gem_exec_create
results: parsing output: 47/ for test: kms_plane_scaling
results: parsing output: 48/ for test: gem_softpin
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_cursor_legacy
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: gem_exec_reloc
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: i915_pm_rc6_residency
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: perf
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_userptr_blits
results: parsing output: 64/ for test: kms_atomic
results: parsing output: 65/ for test: gem_exec_schedule
results: parsing output: 66/ for test: gem_fenced_exec_thrash
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: sysfs_preempt_timeout
results: parsing output: 70/ for test: gem_exec_params
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_cursor_legacy
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: drm_read
results: parsing output: 78/ for test: gem_eio
results: parsing output: 79/ for test: gem_exec_whisper
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_rotation_crc
results: parsing output: 82/ for test: kms_dither
results: parsing output: 83/ for test: kms_rotation_crc
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_dsc
results: parsing output: 86/ for test: gem_mmap_wc
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: gem_exec_schedule
results: parsing output: 89/ for test: gen9_exec_parse
results: parsing output: 90/ for test: kms_color
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_pxp
results: parsing output: 93/ for test: prime_mmap
results: parsing output: 94/ for test: kms_plane_multiple
results: parsing output: 95/ for test: vgem_basic
results: parsing output: 96/ for test: kms_pm_dc
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_chamelium_hpd
results: parsing output: 102/ for test: gem_gtt_cpu_tlb
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_eio
results: parsing output: 105/ for test: kms_cursor_crc
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: gem_softpin
results: parsing output: 108/ for test: gem_mmap_gtt
results: parsing output: 109/ for test: gem_exec_balancer
results: parsing output: 110/ for test: perf_pmu
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: kms_psr2_sf
results: parsing output: 113/ for test: kms_flip_scaled_crc
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: sw_sync
results: parsing output: 117/ for test: kms_plane_scaling
results: parsing output: 118/ for test: i915_query
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: core_auth
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: prime_busy
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_exec_reloc
results: parsing output: 126/ for test: kms_invalid_mode
results: parsing output: 127/ for test: kms_plane
results: parsing output: 128/ for test: gem_workarounds
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: i915_module_load
results: parsing output: 132/ for test: i915_drm_fdinfo
results: parsing output: 133/ for test: syncobj_timeline
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_rotation_crc
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: gem_render_copy
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: syncobj_timeline
results: parsing output: 140/ for test: gem_mmap_wc
results: parsing output: 141/ for test: kms_flip
results: parsing output: 142/ for test: kms_vblank
results: parsing output: 143/ for test: gem_ctx_shared
results: parsing output: 144/ for test: kms_cursor_crc
results: parsing output: 145/ for test: kms_busy
results: parsing output: 146/ for test: kms_big_fb
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_content_protection
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_ccs
results: parsing output: 152/ for test: kms_cursor_legacy
results: parsing output: 153/ for test: kms_psr
results: parsing output: 154/ for test: kms_cursor_legacy
Done.