[89.412282] Initializing watchdogs
[89.412488]   /dev/watchdog0
[89.452559] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.467807] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.475692] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.481599] [001/133] (960s left) gem_spin_batch (user-each)
[89.633085] Starting subtest: user-each
[92.927645] Subtest user-each: SUCCESS (3.297s)
[93.207521] [002/133] (956s left) core_debugfs (read-all-entries)
[93.308107] Starting subtest: read-all-entries
[93.389850] Subtest read-all-entries: SUCCESS (0.083s)
[93.523217] [003/133] (956s left) perf_pmu (enable-race)
[93.635237] Starting subtest: enable-race
[93.637166] Starting dynamic subtest: rcs0
[106.272305] Dynamic subtest rcs0: SUCCESS (12.635s)
[106.289613] Starting dynamic subtest: bcs0
[118.934712] Dynamic subtest bcs0: SUCCESS (12.663s)
[118.952082] Starting dynamic subtest: vcs0
[131.572966] Dynamic subtest vcs0: SUCCESS (12.635s)
[131.590441] Starting dynamic subtest: vecs0
[144.206633] Dynamic subtest vecs0: SUCCESS (12.631s)
[144.208461] Subtest enable-race: SUCCESS (50.568s)
[144.346499] [004/133] (905s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[144.452011] Starting subtest: single-wait-for-submit-unsubmitted
[144.563286] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.108s)
[144.720517] [005/133] (905s left) syncobj_timeline (wait-any-snapshot)
[144.824383] Starting subtest: wait-any-snapshot
[144.912571] Subtest wait-any-snapshot: SUCCESS (0.087s)
[145.044362] [006/133] (905s left) gem_tiled_wb
[189.828994] [007/133] (860s left) kms_hdr (static-toggle-suspend)
[190.014493] Starting subtest: static-toggle-suspend
[190.021119] Subtest static-toggle-suspend: SKIP (0.001s)
[190.145166] [008/133] (860s left) i915_pm_rc6_residency (media-rc6-accuracy)
[190.249018] Starting subtest: media-rc6-accuracy
[190.252130] Subtest media-rc6-accuracy: SKIP (0.000s)
[190.377930] [009/133] (860s left) gem_exec_schedule (noreorder-corked)
[190.644189] Starting subtest: noreorder-corked
[190.646032] Starting dynamic subtest: rcs0
[191.763234] Dynamic subtest rcs0: SUCCESS (1.119s)
[191.800234] Starting dynamic subtest: bcs0
[192.909107] Dynamic subtest bcs0: SUCCESS (1.146s)
[192.928114] Starting dynamic subtest: vcs0
[194.037865] Dynamic subtest vcs0: SUCCESS (1.127s)
[194.056285] Starting dynamic subtest: vecs0
[195.145083] Dynamic subtest vecs0: SUCCESS (1.109s)
[195.147226] Subtest noreorder-corked: SUCCESS (4.505s)
[195.395369] [010/133] (855s left) gem_exec_schedule (fifo)
[195.530189] Starting subtest: fifo
[195.533528] Starting dynamic subtest: rcs0
[195.661169] Dynamic subtest rcs0: SUCCESS (0.118s)
[195.688114] Starting dynamic subtest: bcs0
[195.782117] Dynamic subtest bcs0: SUCCESS (0.131s)
[195.812099] Starting dynamic subtest: vcs0
[195.907972] Dynamic subtest vcs0: SUCCESS (0.124s)
[195.936702] Starting dynamic subtest: vecs0
[196.026072] Dynamic subtest vecs0: SUCCESS (0.116s)
[196.032644] Subtest fifo: SUCCESS (0.493s)
[196.411569] [011/133] (854s left) kms_pm_rpm (legacy-planes)
[198.391757] Starting subtest: legacy-planes
[198.394663] Starting dynamic subtest: plane-45
[199.940503] Dynamic subtest plane-45: SUCCESS (1.549s)
[199.967397] Starting dynamic subtest: plane-54
[201.493602] Dynamic subtest plane-54: SUCCESS (1.551s)
[201.514169] Starting dynamic subtest: plane-63
[203.225637] Dynamic subtest plane-63: SUCCESS (1.730s)
[203.227249] Subtest legacy-planes: SUCCESS (4.835s)
[203.691196] [012/133] (847s left) gem_userptr_blits (coherency-sync)
[203.833582] Starting subtest: coherency-sync
[204.371656] Subtest coherency-sync: SKIP (0.504s)
[204.513732] [013/133] (846s left) vgem_basic (debugfs)
[204.879058] Starting subtest: debugfs
[204.884454] Subtest debugfs: SUCCESS (0.003s)
[204.997668] [FACT vgem_basic (debugfs)] new: kernel.kmod_is_loaded.vgem: true
[205.002657] [014/133] (846s left) kms_atomic_transition (modeset-transition)
[205.197206] Starting subtest: modeset-transition
[205.201105] Starting dynamic subtest: 1x-outputs
[208.786483] Dynamic subtest 1x-outputs: SUCCESS (3.588s)
[208.807089] Starting dynamic subtest: 2x-outputs
[216.160116] Dynamic subtest 2x-outputs: SUCCESS (7.374s)
[216.162517] Subtest modeset-transition: SUCCESS (10.963s)
[216.479038] [015/133] (834s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[216.682655] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[216.689084] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[216.827922] [016/133] (834s left) kms_chamelium_edid (hdmi-mode-timings)
[217.442633] Subtest hdmi-mode-timings: SKIP (0.000s)
[217.742721] [017/133] (833s left) gem_exec_reloc (basic-wc-active)
[217.869161] Starting subtest: basic-wc-active
[218.007767] Subtest basic-wc-active: SUCCESS (0.138s)
[218.231421] [018/133] (833s left) perf (oa-formats)
[218.482310] Starting subtest: oa-formats
[218.485189] Starting dynamic subtest: 0-rcs0
[218.566979] Dynamic subtest 0-rcs0: SUCCESS (0.084s)
[218.569498] Subtest oa-formats: SUCCESS (0.085s)
[218.677576] [019/133] (832s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[218.861769] Starting subtest: plane-scaler-unity-scaling-with-rotation
[218.863326] Starting dynamic subtest: pipe-A
[225.224536] Dynamic subtest pipe-A: SKIP (6.351s)
[225.265360] Starting dynamic subtest: pipe-B
[230.041451] Dynamic subtest pipe-B: SKIP (4.821s)
[230.083902] Starting dynamic subtest: pipe-C
[235.156264] Dynamic subtest pipe-C: SKIP (5.107s)
[235.161214] Subtest plane-scaler-unity-scaling-with-rotation: SKIP (16.287s)
[235.699868] [020/133] (815s left) gen9_exec_parse (batch-invalid-length)
[235.876801] Starting subtest: batch-invalid-length
[235.889478] Subtest batch-invalid-length: SUCCESS (0.014s)
[236.156112] [021/133] (815s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-sf)
[236.362092] Subtest fbc-pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[236.474678] [022/133] (815s left) gem_flink_basic (double-flink)
[236.588758] Starting subtest: double-flink
[236.590494] Subtest double-flink: SUCCESS (0.001s)
[236.783493] [023/133] (815s left) sw_sync (sync_multi_producer_single_consumer)
[236.926135] Starting subtest: sync_multi_producer_single_consumer
[238.003869] Subtest sync_multi_producer_single_consumer: SUCCESS (1.079s)
[238.138210] [024/133] (814s left) kms_cursor_edge_walk (256x256-left-edge)
[238.336126] Starting subtest: 256x256-left-edge
[238.346540] Starting dynamic subtest: pipe-A-HDMI-A-1
[242.384269] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.039s)
[242.435218] Starting dynamic subtest: pipe-C-HDMI-A-1
[245.896794] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.503s)
[245.900348] Subtest 256x256-left-edge: SUCCESS (7.561s)
[246.214759] [025/133] (806s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[246.445267] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[246.450386] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.001s)
[246.611757] [026/133] (805s left) gem_mmap_wc (read)
[246.729094] Starting subtest: read
[246.754537] Subtest read: SUCCESS (0.029s)
[246.912922] [027/133] (805s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[247.132234] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[249.721219] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SUCCESS (2.591s)
[249.933174] [028/133] (802s left) gem_exec_schedule (u-fairslice)
[250.076099] Starting subtest: u-fairslice
[250.079466] Starting dynamic subtest: rcs0
[252.114688] Dynamic subtest rcs0: SUCCESS (2.028s)
[252.135466] Starting dynamic subtest: bcs0
[254.149916] Dynamic subtest bcs0: SUCCESS (2.031s)
[254.169694] Starting dynamic subtest: vcs0
[256.177773] Dynamic subtest vcs0: SUCCESS (2.024s)
[256.197459] Starting dynamic subtest: vecs0
[258.203481] Dynamic subtest vecs0: SUCCESS (2.025s)
[258.205396] Subtest u-fairslice: SUCCESS (8.122s)
[258.599351] [029/133] (794s left) kms_pm_rpm (drm-resources-equal)
[260.566598] Starting subtest: drm-resources-equal
[262.003089] Subtest drm-resources-equal: SUCCESS (1.440s)
[262.339899] [030/133] (790s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[262.559888] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[262.565182] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[262.718909] [031/133] (790s left) kms_color (ctm-green-to-red)
[262.908603] Starting subtest: ctm-green-to-red
[262.910115] Starting dynamic subtest: pipe-A-HDMI-A-1
[263.875438] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.964s)
[263.924066] Starting dynamic subtest: pipe-A-HDMI-A-2
[264.556291] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.672s)
[264.594384] Starting dynamic subtest: pipe-B-HDMI-A-1
[265.225079] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.663s)
[265.274120] Starting dynamic subtest: pipe-B-HDMI-A-2
[265.888407] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.665s)
[265.927219] Starting dynamic subtest: pipe-C-HDMI-A-1
[266.567333] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.675s)
[266.591274] Starting dynamic subtest: pipe-C-HDMI-A-2
[267.307180] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.733s)
[267.309645] Subtest ctm-green-to-red: SUCCESS (4.396s)
[267.677971] [032/133] (785s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[267.903256] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[270.452073] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SUCCESS (2.547s)
[270.698254] [033/133] (782s left) kms_rmfb (close-fd)
[270.886481] Starting subtest: close-fd
[270.891485] Starting dynamic subtest: pipe-A-HDMI-A-1
[271.222811] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.337s)
[271.268275] Starting dynamic subtest: pipe-B-HDMI-A-2
[271.687747] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.457s)
[271.724298] Starting dynamic subtest: pipe-C-HDMI-A-1
[272.168259] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.474s)
[272.170975] Subtest close-fd: SUCCESS (1.290s)
[272.755400] [034/133] (780s left) kms_flip (flip-vs-absolute-wf_vblank)
[272.950882] Starting subtest: flip-vs-absolute-wf_vblank
[272.960332] Starting dynamic subtest: A-HDMI-A1
[278.920700] Dynamic subtest A-HDMI-A1: SUCCESS (5.963s)
[278.946309] Starting dynamic subtest: B-HDMI-A1
[284.787503] Dynamic subtest B-HDMI-A1: SUCCESS (5.860s)
[284.807636] Starting dynamic subtest: C-HDMI-A1
[290.521805] Dynamic subtest C-HDMI-A1: SUCCESS (5.727s)
[290.546714] Starting dynamic subtest: A-HDMI-A2
[296.234128] Dynamic subtest A-HDMI-A2: SUCCESS (5.706s)
[296.259787] Starting dynamic subtest: B-HDMI-A2
[301.892048] Dynamic subtest B-HDMI-A2: SUCCESS (5.655s)
[301.922933] Starting dynamic subtest: C-HDMI-A2
[307.607074] Dynamic subtest C-HDMI-A2: SUCCESS (5.700s)
[307.612067] Subtest flip-vs-absolute-wf_vblank: SUCCESS (34.653s)
[308.142884] [035/133] (745s left) kms_cursor_crc (cursor-random-32x32)
[308.346120] Starting subtest: cursor-random-32x32
[308.658265] Subtest cursor-random-32x32: SKIP (0.305s)
[308.832759] [036/133] (744s left) kms_psr (psr-sprite-mmap-cpu)
[309.039618] Subtest psr-sprite-mmap-cpu: SKIP (0.000s)
[309.156856] [037/133] (744s left) kms_debugfs (display-on-read-all)
[309.353133] Starting subtest: display-on-read-all
[309.517441] Subtest display-on-read-all: SUCCESS (0.165s)
[309.763676] [038/133] (743s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[309.973888] Starting subtest: yf-tiled-16bpp-rotate-180
[314.032885] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (4.059s)
[314.393499] [039/133] (739s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[314.608595] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[314.611710] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[314.749359] [040/133] (738s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[314.937166] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[314.939264] Starting dynamic subtest: pipe-A-HDMI-A-1
[314.962127] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[315.006689] Starting dynamic subtest: pipe-A-HDMI-A-2
[315.046272] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[315.081235] Starting dynamic subtest: pipe-B-HDMI-A-1
[315.097239] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[315.118477] Starting dynamic subtest: pipe-B-HDMI-A-2
[315.134630] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[315.154650] Starting dynamic subtest: pipe-C-HDMI-A-1
[315.169679] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[315.189078] Starting dynamic subtest: pipe-C-HDMI-A-2
[315.204217] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[315.207236] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.035s)
[316.048468] [041/133] (737s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[316.246021] Starting subtest: flip-vs-dpms-off-vs-modeset
[316.249141] Starting dynamic subtest: A-HDMI-A1
[317.794021] Dynamic subtest A-HDMI-A1: SUCCESS (1.545s)
[317.820156] Starting dynamic subtest: B-HDMI-A1
[318.817136] Dynamic subtest B-HDMI-A1: SUCCESS (1.010s)
[318.855078] Starting dynamic subtest: C-HDMI-A1
[319.858420] Dynamic subtest C-HDMI-A1: SUCCESS (1.038s)
[319.898500] Starting dynamic subtest: A-HDMI-A2
[320.979139] Dynamic subtest A-HDMI-A2: SUCCESS (1.122s)
[321.003972] Starting dynamic subtest: B-HDMI-A2
[322.022747] Dynamic subtest B-HDMI-A2: SUCCESS (1.035s)
[322.045232] Starting dynamic subtest: C-HDMI-A2
[323.073102] Dynamic subtest C-HDMI-A2: SUCCESS (1.041s)
[323.076173] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (6.825s)
[323.399100] [042/133] (730s left) kms_pm_backlight (bad-brightness)
[323.605043] Subtest bad-brightness: SKIP (0.000s)
[323.721316] [043/133] (730s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[323.924325] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[323.929402] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[324.079089] [044/133] (730s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[324.191189] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[324.194247] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.002s)
[324.349573] [045/133] (729s left) perf_pmu (busy-accuracy-50)
[324.469112] Starting subtest: busy-accuracy-50
[324.472153] Starting dynamic subtest: rcs0
[326.023496] Dynamic subtest rcs0: SUCCESS (1.554s)
[326.044383] Starting dynamic subtest: bcs0
[327.583173] Dynamic subtest bcs0: SUCCESS (1.555s)
[327.605242] Starting dynamic subtest: vcs0
[329.141206] Dynamic subtest vcs0: SUCCESS (1.553s)
[329.163338] Starting dynamic subtest: vecs0
[330.693111] Dynamic subtest vecs0: SUCCESS (1.551s)
[330.694887] Subtest busy-accuracy-50: SUCCESS (6.226s)
[330.865928] [046/133] (723s left) perf_pmu (semaphore-wait-idle)
[330.984364] Starting subtest: semaphore-wait-idle
[330.986432] Starting dynamic subtest: rcs0
[331.499816] Dynamic subtest rcs0: SUCCESS (0.518s)
[331.524963] Starting dynamic subtest: bcs0
[332.018909] Dynamic subtest bcs0: SUCCESS (0.514s)
[332.046350] Starting dynamic subtest: vcs0
[332.541329] Dynamic subtest vcs0: SUCCESS (0.513s)
[332.563756] Starting dynamic subtest: vecs0
[333.058069] Dynamic subtest vecs0: SUCCESS (0.514s)
[333.061142] Subtest semaphore-wait-idle: SUCCESS (2.079s)
[333.232329] [047/133] (721s left) fbdev (nullptr)
[333.362447] Starting subtest: nullptr
[333.364828] Subtest nullptr: SUCCESS (0.000s)
[333.497514] [048/133] (721s left) gem_mmap_offset (mmap-unmap)
[333.610176] Starting subtest: mmap-unmap
[333.611720] Starting dynamic subtest: smem0
[423.784621] Dynamic subtest smem0: SUCCESS (90.167s)
[423.796066] Subtest mmap-unmap: SUCCESS (90.170s)
[423.966348] [049/133] (630s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[424.078087] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[424.080218] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[424.248597] [050/133] (630s left) gem_exec_reloc (basic-softpin)
[424.377687] Starting subtest: basic-softpin
[424.379498] Subtest basic-softpin: SUCCESS (0.006s)
[424.626205] [051/133] (630s left) kms_psr (fbc-psr2-primary-render)
[424.807186] Subtest fbc-psr2-primary-render: SKIP (0.000s)
[424.921064] [052/133] (629s left) gem_exec_suspend (basic-s3-devices)
[425.049920] Starting subtest: basic-S3-devices
[425.054923] Starting dynamic subtest: smem
[434.973797] Dynamic subtest smem: SUCCESS (9.921s)
[434.976184] Subtest basic-S3-devices: SUCCESS (9.926s)
[444.277344] [053/133] (619s left) gem_exec_reloc (basic-cpu-wc)
[444.401420] Starting subtest: basic-cpu-wc
[444.421404] Subtest basic-cpu-wc: SUCCESS (0.019s)
[444.664804] [054/133] (619s left) kms_psr (fbc-psr-primary-mmap-gtt)
[444.852143] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.000s)
[444.969855] [055/133] (619s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[445.219053] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[445.462427] Starting dynamic subtest: pipe-A-plane-0
[446.196079] Dynamic subtest pipe-A-plane-0: SUCCESS (0.731s)
[446.219546] Starting dynamic subtest: pipe-A-plane-1
[446.878098] Dynamic subtest pipe-A-plane-1: SUCCESS (0.682s)
[446.904465] Starting dynamic subtest: pipe-A-plane-2
[447.362338] Dynamic subtest pipe-A-plane-2: SUCCESS (0.477s)
[447.385573] Starting dynamic subtest: pipe-A-plane-3
[447.844045] Dynamic subtest pipe-A-plane-3: SUCCESS (0.480s)
[448.239433] Starting dynamic subtest: pipe-B-plane-0
[448.865099] Dynamic subtest pipe-B-plane-0: SUCCESS (0.648s)
[448.888682] Starting dynamic subtest: pipe-B-plane-1
[449.534660] Dynamic subtest pipe-B-plane-1: SUCCESS (0.664s)
[449.557294] Starting dynamic subtest: pipe-B-plane-2
[450.015997] Dynamic subtest pipe-B-plane-2: SUCCESS (0.478s)
[450.039436] Starting dynamic subtest: pipe-B-plane-3
[450.497849] Dynamic subtest pipe-B-plane-3: SUCCESS (0.480s)
[450.739092] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (5.520s)
[451.071938] [056/133] (613s left) gem_workarounds (suspend-resume-context)
[451.223392] Starting subtest: suspend-resume-context