[91.604196] Initializing watchdogs [91.604366] /dev/watchdog0 [91.643889] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605] [91.657453] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [91.665586] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [91.672048] [001/131] (960s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180) [91.861638] Starting subtest: primary-yf-tiled-reflect-x-180 [91.869132] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.002s) [92.005433] [002/131] (960s left) kms_cursor_crc (cursor-random-64x21) [92.191741] Starting subtest: cursor-random-64x21 [92.248761] Starting dynamic subtest: pipe-A-HDMI-A-1 [96.675710] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.395s) [96.717088] Starting dynamic subtest: pipe-C-HDMI-A-1 [100.522929] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.821s) [100.524732] Subtest cursor-random-64x21: SUCCESS (8.331s) [100.829438] [003/131] (951s left) gem_exec_balancer (full-pulse) [100.959987] Starting subtest: full-pulse [105.381126] Subtest full-pulse: SUCCESS (4.421s) [105.634500] [004/131] (946s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled) [105.732240] Starting subtest: multi-wait-for-submit-unsubmitted-signaled [105.734192] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.002s) [105.864409] [005/131] (946s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [106.048705] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [108.058471] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.008s) [108.266449] [006/131] (944s left) kms_big_fb (linear-16bpp-rotate-180) [108.464809] Starting subtest: linear-16bpp-rotate-180 [112.502785] Subtest linear-16bpp-rotate-180: SUCCESS (4.038s) [112.814631] [007/131] (939s left) gem_exec_schedule (submit-golden-slice) [112.943977] Starting subtest: submit-golden-slice [112.946245] Starting dynamic subtest: rcs0 [113.002548] Dynamic subtest rcs0: SUCCESS (0.055s) [113.025646] Starting dynamic subtest: bcs0 [113.048879] Dynamic subtest bcs0: SUCCESS (0.047s) [113.075866] Starting dynamic subtest: vcs0 [113.102444] Dynamic subtest vcs0: SUCCESS (0.053s) [113.128832] Starting dynamic subtest: vecs0 [113.148052] Dynamic subtest vecs0: SUCCESS (0.047s) [113.150985] Subtest submit-golden-slice: SUCCESS (0.205s) [113.536435] [008/131] (939s left) kms_dsc (dsc-fractional-bpp-with-bpc) [113.709704] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s) [113.806885] [009/131] (939s left) dmabuf_sync_file (import-multiple-read-write) [113.993106] Starting subtest: import-multiple-read-write [114.002785] Subtest import-multiple-read-write: SUCCESS (0.012s) [114.119078] [FACT dmabuf_sync_file (import-multiple-read-write)] new: kernel.kmod_is_loaded.vgem: true [114.126072] [010/131] (938s left) gem_watchdog (default-physical) [114.243170] Starting subtest: default-physical [115.260223] Subtest default-physical: SUCCESS (1.019s) [115.397756] [011/131] (937s left) gem_exec_capture (capture-invisible) [115.504810] Starting subtest: capture-invisible [115.506940] Starting dynamic subtest: smem0 [115.511066] Dynamic subtest smem0: SKIP (0.000s) [115.515016] Subtest capture-invisible: SKIP (0.001s) [115.659399] [012/131] (937s left) kms_draw_crc (fill-fb) [115.860090] Starting subtest: fill-fb [116.385572] Subtest fill-fb: SUCCESS (0.557s) [116.606949] [013/131] (936s left) sysfs_heartbeat_interval (invalid) [116.713473] Starting subtest: invalid [116.715493] Starting dynamic subtest: vecs0 [116.717322] Dynamic subtest vecs0: SUCCESS (0.001s) [116.736806] Starting dynamic subtest: vcs0 [116.739496] Dynamic subtest vcs0: SUCCESS (0.001s) [116.759204] Starting dynamic subtest: bcs0 [116.762486] Dynamic subtest bcs0: SUCCESS (0.001s) [116.782346] Starting dynamic subtest: rcs0 [116.785200] Dynamic subtest rcs0: SUCCESS (0.001s) [116.787264] Subtest invalid: SUCCESS (0.008s) [116.871975] [014/131] (936s left) kms_chamelium_edid (dp-edid-read) [117.406491] Subtest dp-edid-read: SKIP (0.000s) [117.646194] [015/131] (935s left) syncobj_wait (multi-wait-all-submitted) [117.745919] Starting subtest: multi-wait-all-submitted [117.851021] Subtest multi-wait-all-submitted: SUCCESS (0.103s) [117.982397] [016/131] (935s left) kms_cursor_legacy (forked-bo) [118.154339] Starting subtest: forked-bo [118.156353] Starting dynamic subtest: pipe-A [123.615201] Dynamic subtest pipe-A: SUCCESS (5.458s) [123.634207] Starting dynamic subtest: pipe-B [129.069412] Dynamic subtest pipe-B: SUCCESS (5.455s) [129.089100] Starting dynamic subtest: pipe-C [134.541269] Dynamic subtest pipe-C: SUCCESS (5.473s) [134.611646] Starting dynamic subtest: all-pipes [140.057692] Dynamic subtest all-pipes: SUCCESS (5.515s) [140.059764] Subtest forked-bo: SUCCESS (21.903s) [140.200471] [017/131] (913s left) syncobj_timeline (host-signal-points) [140.303157] Starting subtest: host-signal-points [140.323015] Subtest host-signal-points: SUCCESS (0.021s) [140.465760] [018/131] (913s left) gem_exec_schedule (pi-userfault) [140.715886] Starting subtest: pi-userfault [140.718127] Starting dynamic subtest: rcs0 [140.727905] Dynamic subtest rcs0: SUCCESS (0.011s) [140.749207] Starting dynamic subtest: bcs0 [140.751321] Dynamic subtest bcs0: SUCCESS (0.011s) [140.772866] Starting dynamic subtest: vcs0 [140.775948] Dynamic subtest vcs0: SUCCESS (0.010s) [140.796575] Starting dynamic subtest: vecs0 [140.799527] Dynamic subtest vecs0: SUCCESS (0.009s) [140.802640] Subtest pi-userfault: SUCCESS (0.046s) [141.047739] [019/131] (912s left) kms_joiner (basic-big-joiner) [141.225095] Starting subtest: basic-big-joiner [141.228974] Subtest basic-big-joiner: SKIP (0.000s) [141.367514] [020/131] (912s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt) [141.553414] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt [144.278902] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SUCCESS (2.724s) [144.550200] [021/131] (909s left) syncobj_timeline (wait-all-for-submit-snapshot) [144.651217] Starting subtest: wait-all-for-submit-snapshot [144.740762] Subtest wait-all-for-submit-snapshot: SUCCESS (0.086s) [144.883241] [022/131] (909s left) kms_big_fb (4-tiled-16bpp-rotate-90) [145.070105] Starting subtest: 4-tiled-16bpp-rotate-90 [145.073819] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.000s) [145.217319] [023/131] (908s left) kms_psr (psr2-sprite-blt) [145.388497] Subtest psr2-sprite-blt: SKIP (0.000s) [145.492660] [024/131] (908s left) kms_psr (fbc-psr2-cursor-mmap-cpu) [145.662770] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s) [145.773213] [025/131] (908s left) gem_lmem_swapping (heavy-verify-multi) [145.878627] Subtest heavy-verify-multi: SKIP (0.000s) [145.977324] [026/131] (908s left) kms_sequence (queue-busy) [146.144098] Starting subtest: queue-busy [146.146242] Starting dynamic subtest: pipe-A-HDMI-A-1 [149.010139] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.864s) [149.031214] Starting dynamic subtest: pipe-A-HDMI-A-2 [151.601348] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.588s) [151.622772] Starting dynamic subtest: pipe-B-HDMI-A-1 [154.169241] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.565s) [154.189378] Starting dynamic subtest: pipe-B-HDMI-A-2 [156.736545] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.564s) [156.757348] Starting dynamic subtest: pipe-C-HDMI-A-1 [159.304762] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.566s) [159.327685] Starting dynamic subtest: pipe-C-HDMI-A-2 [161.883152] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.575s) [161.887030] Subtest queue-busy: SUCCESS (15.740s) [162.156545] [027/131] (892s left) kms_chamelium_hpd (vga-hpd) [162.702604] Subtest vga-hpd: SKIP (0.000s) [162.942978] [028/131] (891s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt) [163.127309] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt [163.131496] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [163.281313] [029/131] (891s left) gem_exec_suspend (basic-s4-devices) [163.397501] Starting subtest: basic-S4-devices [163.399417] Starting dynamic subtest: smem [174.091519] Dynamic subtest smem: SUCCESS (10.670s) [174.114620] Subtest basic-S4-devices: SUCCESS (10.671s) [184.438388] [030/131] (880s left) kms_big_fb (y-tiled-8bpp-rotate-180) [184.787940] Starting subtest: y-tiled-8bpp-rotate-180 [187.268429] Subtest y-tiled-8bpp-rotate-180: SUCCESS (2.598s) [187.558937] [031/131] (877s left) gem_partial_pwrite_pread (reads-display) [187.668886] Starting subtest: reads-display [193.214261] Subtest reads-display: SUCCESS (5.548s) [193.373257] [032/131] (871s left) fbdev (write) [193.488122] Starting subtest: write [194.268627] Subtest write: SUCCESS (0.779s) [194.391746] [033/131] (870s left) perf_pmu (interrupts-sync) [194.498576] Starting subtest: interrupts-sync [195.626828] Subtest interrupts-sync: SUCCESS (1.127s) [195.773637] [034/131] (869s left) gem_ctx_param (basic) [195.872827] Starting subtest: basic [195.875212] Subtest basic: SUCCESS (0.000s) [196.009416] [035/131] (869s left) gem_ctx_isolation (nonpriv-switch) [196.126852] Starting subtest: nonpriv-switch [196.128915] Starting dynamic subtest: rcs0 [196.965964] Dynamic subtest rcs0: SUCCESS (0.837s) [196.990197] Starting dynamic subtest: bcs0 [198.273346] Dynamic subtest bcs0: SUCCESS (1.304s) [198.296559] Starting dynamic subtest: vcs0 [199.094327] Dynamic subtest vcs0: SUCCESS (0.822s) [199.117493] Starting dynamic subtest: vecs0 [199.911074] Dynamic subtest vecs0: SUCCESS (0.815s) [199.913303] Subtest nonpriv-switch: SUCCESS (3.783s) [200.161541] [036/131] (865s left) kms_lease (master-vs-lease) [200.330100] Starting subtest: master-vs-lease [200.336061] Subtest master-vs-lease: SUCCESS (0.002s) [200.475228] [037/131] (865s left) sw_sync (alloc_merge_fence) [200.573196] Starting subtest: alloc_merge_fence [200.575385] Subtest alloc_merge_fence: SUCCESS (0.001s) [200.693461] [038/131] (864s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [200.863043] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [200.864918] Starting dynamic subtest: pipe-A-valid-mode [201.770172] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.906s) [201.772530] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SUCCESS (0.909s) [202.068520] [039/131] (863s left) kms_properties (crtc-properties-atomic) [202.239547] Starting subtest: crtc-properties-atomic [202.241497] Starting dynamic subtest: pipe-A-HDMI-A-1 [202.819236] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.577s) [202.843292] Starting dynamic subtest: pipe-B-HDMI-A-2 [203.232533] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.412s) [203.262177] Starting dynamic subtest: pipe-C-HDMI-A-1 [203.645573] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.405s) [203.647722] Subtest crtc-properties-atomic: SUCCESS (1.405s) [203.926772] [040/131] (861s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75) [204.101214] Starting subtest: planes-unity-scaling-downscale-factor-0-75 [204.103144] Starting dynamic subtest: pipe-A [204.381418] Dynamic subtest pipe-A: SUCCESS (0.283s) [204.406085] Starting dynamic subtest: pipe-B [204.729019] Dynamic subtest pipe-B: SUCCESS (0.346s) [204.754244] Starting dynamic subtest: pipe-C [204.780512] Dynamic subtest pipe-C: SUCCESS (0.049s) [204.782480] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (0.682s) [205.214970] [041/131] (860s left) gem_close_race (multigpu-basic-process) [205.335642] Starting subtest: multigpu-basic-process [205.340500] Subtest multigpu-basic-process: SKIP (0.000s) [205.582412] [042/131] (860s left) api_intel_allocator (simple-allocator) [205.679485] Starting subtest: simple-allocator [205.681528] Starting dynamic subtest: basic [205.686882] Dynamic subtest basic: SUCCESS (0.007s) [205.711034] Starting dynamic subtest: parallel-one [206.109424] Dynamic subtest parallel-one: SUCCESS (0.419s) [206.132034] Starting dynamic subtest: print [206.134074] Dynamic subtest print: SUCCESS (0.000s) [206.159966] Starting dynamic subtest: reuse [206.162636] Dynamic subtest reuse: SUCCESS (0.000s) [206.185695] Starting dynamic subtest: reserve [206.188223] Dynamic subtest reserve: SUCCESS (0.000s) [206.211753] Starting dynamic subtest: fork-reopen-allocator [206.214597] Dynamic subtest fork-reopen-allocator: SUCCESS (0.031s) [206.217445] Subtest simple-allocator: SUCCESS (0.467s) [206.303927] [043/131] (859s left) prime_mmap (test_map_unmap) [206.401768] Starting subtest: test_map_unmap [206.403659] Starting dynamic subtest: test_map_unmap-smem [206.408248] Dynamic subtest test_map_unmap-smem: SUCCESS (0.008s) [206.410357] Subtest test_map_unmap: SUCCESS (0.010s) [206.565689] [044/131] (859s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt) [206.752329] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt [206.756059] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [206.899546] [045/131] (859s left) kms_cursor_edge_walk (128x128-left-edge) [207.067987] Starting subtest: 128x128-left-edge [207.078044] Starting dynamic subtest: pipe-A-HDMI-A-1 [210.899044] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.827s) [210.925256] Starting dynamic subtest: pipe-C-HDMI-A-1 [214.332558] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.429s) [214.336741] Subtest 128x128-left-edge: SUCCESS (7.264s) [214.603712] [046/131] (851s left) syncobj_timeline (multi-wait-for-submit-signaled) [214.708960] Starting subtest: multi-wait-for-submit-signaled [214.711061] Subtest multi-wait-for-submit-signaled: SUCCESS (0.002s) [214.853572] [047/131] (851s left) gem_exec_fence (syncobj-backward-timeline-chain-engines) [215.510161] Starting subtest: syncobj-backward-timeline-chain-engines [215.621727] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.114s) [215.904472] [048/131] (850s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [216.003643] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [216.005919] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s) [216.153714] [049/131] (850s left) gem_exec_flush (basic-wb-set-default) [216.272726] Starting subtest: basic-wb-set-default [221.714898] Subtest basic-wb-set-default: SUCCESS (5.444s) [221.970825] [050/131] (844s left) gem_render_copy_redux (normal) [222.079795] Starting subtest: normal [223.377096] Subtest normal: SUCCESS (1.297s) [223.538755] [051/131] (843s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling) [223.707201] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling [223.710928] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (0.000s) [223.855535] [052/131] (843s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs) [224.025886] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs [224.027767] Starting dynamic subtest: pipe-A-HDMI-A-1 [224.103950] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.010s) [224.127195] Starting dynamic subtest: pipe-A-HDMI-A-2 [224.206567] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.010s) [224.229506] Starting dynamic subtest: pipe-B-HDMI-A-1 [224.305123] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.010s) [224.328354] Starting dynamic subtest: pipe-B-HDMI-A-2 [224.404701] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.010s) [224.427667] Starting dynamic subtest: pipe-C-HDMI-A-1 [224.504099] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.010s) [224.527280] Starting dynamic subtest: pipe-C-HDMI-A-2 [224.606307] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.010s) [224.609974] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.083s) [224.983821] [053/131] (842s left) kms_psr (psr-sprite-mmap-gtt) [225.157519] Subtest psr-sprite-mmap-gtt: SKIP (0.000s) [225.259758] [054/131] (841s left) kms_psr (pr-primary-blt) [225.432999] Subtest pr-primary-blt: SKIP (0.000s) [225.543977] [055/131] (841s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs) [225.714766] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs [225.718552] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s) [226.349847] [056/131] (840s left) kms_content_protection (dp-mst-type-0) [226.527492] Starting subtest: dp-mst-type-0 [226.531380] Subtest dp-mst-type-0: SKIP (0.000s) [226.681079] [057/131] (840s left) kms_vblank (wait-busy) [226.852682] Starting subtest: wait-busy [226.854611] Starting dynamic subtest: pipe-A-HDMI-A-1 [229.683365] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.827s) [229.709043] Starting dynamic subtest: pipe-A-HDMI-A-2 [232.258193] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.571s) [232.289921] Starting dynamic subtest: pipe-C-HDMI-A-1 [234.833559] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.567s) [234.862157] Starting dynamic subtest: pipe-C-HDMI-A-2 [237.396282] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.555s) [237.398383] Subtest wait-busy: SUCCESS (10.544s) [237.692350] [058/131] (829s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt) [237.881372] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt [240.697994] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SUCCESS (2.815s) [240.922693] [059/131] (826s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [241.118765] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [241.123197] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [241.275996] [060/131] (826s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt) [241.463839] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt [241.467649] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [241.624053] [061/131] (826s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf) [241.802723] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s) [241.915128] [062/131] (825s left) perf_pmu (busy-accuracy-98) [242.020916] Starting subtest: busy-accuracy-98 [242.022977] Starting dynamic subtest: rcs0 [245.072313] Dynamic subtest rcs0: SUCCESS (3.051s) [245.096375] Starting dynamic subtest: bcs0 [248.126693] Dynamic subtest bcs0: SUCCESS (3.050s) [248.150379] Starting dynamic subtest: vcs0 [251.178139] Dynamic subtest vcs0: SUCCESS (3.051s) [251.201591] Starting dynamic subtest: vecs0 [254.229585] Dynamic subtest vecs0: SUCCESS (3.048s) [254.231704] Subtest busy-accuracy-98: SUCCESS (12.209s) [254.372977] [063/131] (813s left) kms_cursor_crc (cursor-alpha-opaque) [254.542991] Starting subtest: cursor-alpha-opaque [254.544978] Starting dynamic subtest: pipe-A-HDMI-A-1 [255.396390] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.850s) [255.423235] Starting dynamic subtest: pipe-C-HDMI-A-1 [255.939990] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.536s) [255.941920] Subtest cursor-alpha-opaque: SUCCESS (1.397s) [256.253329] [064/131] (811s left) gem_workarounds (suspend-resume-context) [256.385991] Starting subtest: suspend-resume-context