[73.790072] Initializing watchdogs
[73.790281]   /dev/watchdog0
[73.817147] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0102 Intel Sandybridge (Gen6) 2nd Generation Core Processor Family Integrated Graphics Controller (XPS 8300)
[73.824853] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[73.828786] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[73.832049] [001/134] (960s left) kms_cursor_crc (cursor-offscreen-256x85)
[74.376383] Starting subtest: cursor-offscreen-256x85
[74.440236] Subtest cursor-offscreen-256x85: SKIP (0.060s)
[75.005426] [002/134] (959s left) gem_mmap_gtt (cpuset-big-copy-odd)
[75.062394] Starting subtest: cpuset-big-copy-odd
[78.639630] Subtest cpuset-big-copy-odd: SUCCESS (3.579s)
[78.883610] [003/134] (955s left) gem_ctx_engines (execute-allforone)
[79.066399] Starting subtest: execute-allforone
[79.069027] Subtest execute-allforone: SUCCESS (0.003s)
[79.310111] [004/134] (955s left) perf_pmu (busy-idle)
[79.370938] Starting subtest: busy-idle
[79.371837] Starting dynamic subtest: rcs0
[80.011255] Dynamic subtest rcs0: SUCCESS (0.637s)
[80.022193] Starting dynamic subtest: bcs0
[80.650389] Dynamic subtest bcs0: SUCCESS (0.641s)
[80.668667] Starting dynamic subtest: vcs0
[81.784629] Dynamic subtest vcs0: SUCCESS (1.132s)
[81.785658] Subtest busy-idle: SUCCESS (2.413s)
[81.906131] [005/134] (952s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[82.423063] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[82.426279] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.001s)
[83.023736] [006/134] (951s left) api_intel_bb (lot-of-buffers)
[83.083900] Starting subtest: lot-of-buffers
[83.808655] Subtest lot-of-buffers: SUCCESS (0.723s)
[84.133857] [007/134] (950s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[84.201123] Starting subtest: yf-tiled-to-vebox-x-tiled
[84.203028] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[84.443364] [008/134] (950s left) kms_plane (plane-panning-top-left)
[84.936528] Starting subtest: plane-panning-top-left
[84.937312] Starting dynamic subtest: pipe-A
[85.680884] Dynamic subtest pipe-A: SUCCESS (0.744s)
[85.691999] Starting dynamic subtest: pipe-B
[86.293032] Dynamic subtest pipe-B: SUCCESS (0.611s)
[86.293817] Subtest plane-panning-top-left: SUCCESS (1.358s)
[87.007405] [009/134] (947s left) kms_psr (fbc-psr-cursor-plane-onoff)
[87.506559] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[87.812035] [010/134] (946s left) kms_cursor_crc (cursor-tearing-position-change)
[88.307233] Starting subtest: cursor-tearing-position-change
[88.307978] Starting dynamic subtest: pipe-A-HDMI-A-1
[88.724435] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.416s)
[88.734796] Starting dynamic subtest: pipe-B-HDMI-A-1
[89.084772] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.360s)
[89.085462] Subtest cursor-tearing-position-change: SUCCESS (0.778s)
[89.814088] [011/134] (944s left) kms_frontbuffer_tracking (basic)
[90.320061] Starting subtest: basic
[92.707678] Subtest basic: SUCCESS (2.388s)
[93.339927] [012/134] (941s left) gem_spin_batch (spin-each)
[93.407456] Starting subtest: spin-each
[96.742460] Subtest spin-each: SUCCESS (3.336s)
[96.992857] [013/134] (937s left) gem_exec_reloc (basic-gtt-cpu)
[97.055125] Starting subtest: basic-gtt-cpu
[97.059518] Subtest basic-gtt-cpu: SUCCESS (0.004s)
[98.076176] [014/134] (936s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[98.574020] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[98.611720] Starting dynamic subtest: pipe-A-plane-0
[98.613688] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[98.627342] Starting dynamic subtest: pipe-A-plane-1
[98.629676] Dynamic subtest pipe-A-plane-1: SKIP (0.000s)
[98.640895] Starting dynamic subtest: pipe-A-plane-2
[98.642709] Dynamic subtest pipe-A-plane-2: SKIP (0.000s)
[98.853784] Starting dynamic subtest: pipe-B-plane-0
[98.855732] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[98.870540] Starting dynamic subtest: pipe-B-plane-1
[98.872049] Dynamic subtest pipe-B-plane-1: SKIP (0.000s)
[98.883603] Starting dynamic subtest: pipe-B-plane-2
[98.885034] Dynamic subtest pipe-B-plane-2: SKIP (0.000s)
[98.939956] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SKIP (0.365s)
[99.648476] [015/134] (935s left) gem_render_copy (y-tiled-ccs-to-linear)
[99.713531] Starting subtest: y-tiled-ccs-to-linear
[99.714777] Starting dynamic subtest: smem
[99.716779] Dynamic subtest smem: SKIP (0.000s)
[99.718479] Subtest y-tiled-ccs-to-linear: SKIP (0.001s)
[99.949835] [016/134] (935s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[100.450833] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[100.452312] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[101.045746] [017/134] (934s left) kms_psr (pr-sprite-render)
[101.539486] Subtest pr-sprite-render: SKIP (0.000s)
[101.823068] [018/134] (933s left) gen9_exec_parse (shadow-peek)
[101.882193] Subtest shadow-peek: SKIP (0.000s)
[101.943637] [019/134] (933s left) perf (invalid-remove-userspace-config)
[102.158369] Subtest invalid-remove-userspace-config: SKIP (0.000s)
[102.205353] [020/134] (933s left) gem_ctx_param (invalid-size-set)
[102.260036] Starting subtest: invalid-size-set
[102.260785] Subtest invalid-size-set: SUCCESS (0.000s)
[102.383074] [021/134] (932s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[102.437853] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[102.488590] [022/134] (932s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[102.542891] Starting subtest: invalid-bsd2-flag-on-blt
[102.543691] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[102.679323] [023/134] (932s left) gem_render_copy (yf-tiled-ccs-to-linear)
[102.739571] Starting subtest: yf-tiled-ccs-to-linear
[102.740581] Starting dynamic subtest: smem
[102.742632] Dynamic subtest smem: SKIP (0.000s)
[102.744454] Subtest yf-tiled-ccs-to-linear: SKIP (0.001s)
[102.978364] [024/134] (932s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[103.035689] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[103.137450] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[103.262647] [025/134] (932s left) gem_exec_fence (invalid-timeline-fence-array)
[103.621600] Starting subtest: invalid-timeline-fence-array
[103.625790] Subtest invalid-timeline-fence-array: SUCCESS (0.005s)
[103.876213] [FACT gem_exec_fence (invalid-timeline-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[103.878771] [026/134] (931s left) kms_chamelium_edid (dp-edid-read)
[104.445784] Subtest dp-edid-read: SKIP (0.000s)
[104.851892] [027/134] (930s left) gem_exec_params (invalid-bsd1-flag-on-render)
[104.908156] Starting subtest: invalid-bsd1-flag-on-render
[104.908993] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[105.053419] [028/134] (930s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[105.559169] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[105.560679] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[106.171775] [029/134] (929s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[106.228779] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.000s)
[106.284215] [030/134] (929s left) gem_eio (kms)
[106.781090] Starting subtest: kms
[111.640540] Subtest kms: SUCCESS (4.860s)
[112.425234] [031/134] (923s left) kms_cursor_legacy (torture-move)
[112.924433] Starting subtest: torture-move
[112.925226] Starting dynamic subtest: pipe-A
[119.367370] Dynamic subtest pipe-A: SUCCESS (6.437s)
[119.380331] Starting dynamic subtest: pipe-B
[124.885172] Dynamic subtest pipe-B: SUCCESS (5.523s)
[124.898133] Starting dynamic subtest: all-pipes
[130.483838] Dynamic subtest all-pipes: SUCCESS (5.597s)
[130.484704] Subtest torture-move: SUCCESS (17.560s)
[131.081466] [032/134] (904s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[131.150962] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.000s)
[131.209786] [033/134] (904s left) gem_exec_capture (pi)
[131.269059] Subtest pi: SKIP (0.000s)
[131.341323] [034/134] (904s left) syncobj_timeline (wait-all-snapshot)
[131.394908] Starting subtest: wait-all-snapshot
[131.478071] Subtest wait-all-snapshot: SUCCESS (0.082s)
[131.612560] [035/134] (904s left) kms_async_flips (test-cursor-atomic)
[132.105619] Starting subtest: test-cursor-atomic
[132.247740] Starting dynamic subtest: pipe-A-HDMI-A-1
[132.393465] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.146s)
[132.557136] Starting dynamic subtest: pipe-B-HDMI-A-1
[132.664222] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.122s)
[132.665480] Subtest test-cursor-atomic: SUCCESS (0.559s)
[133.544181] [036/134] (902s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[134.048308] Starting subtest: x-tiled-32bpp-rotate-180
[135.471420] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.422s)
[136.108146] [037/134] (899s left) i915_pm_freq_api (freq-reset)
[136.170375] Subtest freq-reset: SKIP (0.000s)
[136.235526] [038/134] (899s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[136.728493] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[138.564623] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (1.836s)
[139.197464] [039/134] (896s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[139.252020] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[139.306236] [040/134] (896s left) gem_mmap (short-mmap)
[139.358170] Starting subtest: short-mmap
[139.358947] Subtest short-mmap: SUCCESS (0.000s)
[139.494451] [041/134] (896s left) vgem_basic (dmabuf-export)
[139.564959] Starting subtest: dmabuf-export
[139.566377] Subtest dmabuf-export: SUCCESS (0.003s)
[139.713221] [042/134] (896s left) gem_render_copy (y-tiled)
[139.772835] Starting subtest: y-tiled
[139.773659] Starting dynamic subtest: smem
[139.837818] Dynamic subtest smem: SUCCESS (0.065s)
[139.838643] Subtest y-tiled: SUCCESS (0.065s)
[140.081979] [043/134] (896s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[140.573175] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[140.574573] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[141.209486] [044/134] (895s left) gem_exec_schedule (implicit-write-read)
[141.278148] Starting subtest: implicit-write-read
[141.278792] Starting dynamic subtest: rcs0
[141.309368] Dynamic subtest rcs0: SUCCESS (0.032s)
[141.322989] Starting dynamic subtest: bcs0
[141.338946] Dynamic subtest bcs0: SUCCESS (0.029s)
[141.339787] Subtest implicit-write-read: SUCCESS (0.062s)
[141.628420] [045/134] (894s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[142.125291] Starting subtest: 4-tiled-64bpp-rotate-90
[142.126832] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.000s)
[142.722885] [046/134] (893s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[143.218576] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[143.220273] Starting dynamic subtest: A-VGA1
[151.256596] Dynamic subtest A-VGA1: SUCCESS (8.036s)
[151.269853] Starting dynamic subtest: B-VGA1
[159.246866] Dynamic subtest B-VGA1: SUCCESS (7.988s)
[159.260888] Starting dynamic subtest: A-HDMI-A1
[167.403332] Dynamic subtest A-HDMI-A1: SUCCESS (8.154s)
[167.416699] Starting dynamic subtest: B-HDMI-A1
[175.589587] Dynamic subtest B-HDMI-A1: SUCCESS (8.184s)
[175.594225] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (32.373s)
[176.413478] [047/134] (860s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[176.909355] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[176.910858] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[177.506262] [048/134] (859s left) kms_color_pipeline (plane-lut1d)
[177.999735] Starting subtest: plane-lut1d
[178.269374] Subtest plane-lut1d: SKIP (0.270s)
[178.993920] [049/134] (857s left) i915_pm_rc6_residency (rc6-accuracy)
[179.054669] Starting subtest: rc6-accuracy
[179.055436] Starting dynamic subtest: gt0
[182.226087] Dynamic subtest gt0: SUCCESS (3.168s)
[182.227250] Subtest rc6-accuracy: SUCCESS (3.171s)
[182.352427] [050/134] (854s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[182.838735] Starting subtest: basic-flip-before-cursor-atomic
[183.831670] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.992s)
[184.535274] [051/134] (852s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[185.033376] Starting subtest: psr-2p-primscrn-spr-indfb-move
[185.034791] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[185.628297] [052/134] (851s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[186.120540] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[186.122213] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[186.723401] [053/134] (850s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[187.210965] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[187.503539] [054/134] (849s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[187.562178] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[187.665068] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.102s)
[187.789187] [055/134] (849s left) gem_mmap_wc (bad-size)
[187.843206] Starting subtest: bad-size
[187.843777] Subtest bad-size: SUCCESS (0.001s)
[187.989832] [056/134] (849s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[188.482290] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[188.483863] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[189.086756] [057/134] (848s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[189.583749] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[189.586912] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[190.187663] [058/134] (846s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[190.683088] Starting subtest: cursorA-vs-flipB-atomic
[190.684760] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[191.288974] [059/134] (845s left) sysfs_heartbeat_interval (nopreempt)
[191.352219] Starting subtest: nopreempt
[191.354138] Starting dynamic subtest: vcs0
[191.355380] Dynamic subtest vcs0: SKIP (0.000s)
[191.368445] Starting dynamic subtest: bcs0
[191.370518] Dynamic subtest bcs0: SKIP (0.000s)
[191.387388] Starting dynamic subtest: rcs0
[191.389628] Dynamic subtest rcs0: SKIP (0.000s)
[191.391494] Subtest nopreempt: SKIP (0.005s)
[191.485735] [060/134] (845s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[191.976064] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[191.977586] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[192.584795] [061/134] (844s left) gem_wait (busy)
[192.661438] Starting subtest: busy
[192.662222] Starting dynamic subtest: all-engines
[193.177633] Dynamic subtest all-engines: SUCCESS (0.516s)
[193.191551] Starting dynamic subtest: rcs0
[193.696638] Dynamic subtest rcs0: SUCCESS (0.517s)
[193.710503] Starting dynamic subtest: bcs0
[194.214731] Dynamic subtest bcs0: SUCCESS (0.515s)
[194.228741] Starting dynamic subtest: vcs0
[194.733411] Dynamic subtest vcs0: SUCCESS (0.516s)
[194.736696] Subtest busy: SUCCESS (2.074s)
[194.992724] [062/134] (842s left) kms_setmode (clone-exclusive-crtc)
[195.046612] Starting subtest: clone-exclusive-crtc
[195.048879] Subtest clone-exclusive-crtc: SKIP (0.000s)
[195.172418] [063/134] (842s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[195.658715] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[195.954456] [064/134] (841s left) sw_sync (sync_merge_same)
[196.010425] Starting subtest: sync_merge_same
[196.011203] Subtest sync_merge_same: SUCCESS (0.000s)
[196.125051] [065/134] (841s left) kms_hdr (static-toggle)
[196.611045] Starting subtest: static-toggle
[196.613440] Subtest static-toggle: SKIP (0.000s)
[197.203901] [066/134] (840s left) gem_exec_await (wide-contexts)
[197.290590] Starting subtest: wide-contexts
[219.039649] Subtest wide-contexts: SUCCESS (21.749s)
[219.270600] [067/134] (818s left) kms_invalid_mode (uint-max-clock)
[219.892930] Starting subtest: uint-max-clock
[219.893871] Starting dynamic subtest: pipe-A-HDMI-A-1
[219.958437] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.078s)
[219.975074] Starting dynamic subtest: pipe-B-HDMI-A-1
[219.975770] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[219.976452] Subtest uint-max-clock: SUCCESS (0.084s)
[220.442537] [068/134] (817s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[220.931270] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[222.853805] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (1.923s)
[223.509263] [069/134] (814s left) kms_pm_rpm (modeset-non-lpsp)
[235.359278] Subtest modeset-non-lpsp: SKIP (0.000s)
[235.657883] [070/134] (802s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[236.153566] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[236.458950] [071/134] (801s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[236.946325] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[238.390126] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (1.444s)
[239.036614] [072/134] (798s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[239.523272] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[239.524681] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[240.134172] [073/134] (797s left) i915_pm_rps (thresholds-idle)
[240.196904] Starting subtest: thresholds-idle
[240.197700] Starting dynamic subtest: gt0
[250.528353] Dynamic subtest gt0: SUCCESS (10.330s)
[250.532683] Subtest thresholds-idle: SUCCESS (10.333s)
[250.663775] [074/134] (787s left) kms_vblank (query-idle-hang)
[251.151479] Starting subtest: query-idle-hang
[251.153889] Starting dynamic subtest: pipe-A-HDMI-A-1
[256.966368] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (5.813s)
[256.980893] Starting dynamic subtest: pipe-B-HDMI-A-1
[262.965183] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (5.997s)
[262.970234] Subtest query-idle-hang: SUCCESS (11.816s)
[263.701642] [075/134] (774s left) perf_pmu (most-busy-idle-check-all)
[263.763124] Starting subtest: most-busy-idle-check-all
[263.763931] Starting dynamic subtest: rcs0
[264.380430] Dynamic subtest rcs0: SUCCESS (0.616s)
[264.395543] Starting dynamic subtest: bcs0
[265.003360] Dynamic subtest bcs0: SUCCESS (0.621s)
[265.020139] Starting dynamic subtest: vcs0
[265.624634] Dynamic subtest vcs0: SUCCESS (0.618s)
[265.627771] Subtest most-busy-idle-check-all: SUCCESS (1.863s)
[265.743544] [076/134] (772s left) kms_atomic_interruptible (universal-setplane-cursor)
[266.239864] Starting subtest: universal-setplane-cursor
[266.242238] Starting dynamic subtest: pipe-A-HDMI-A-1
[272.538630] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.296s)
[272.542390] Subtest universal-setplane-cursor: SUCCESS (6.298s)
[273.250650] [077/134] (764s left) gem_userptr_blits (process-exit-busy)
[273.326208] Starting subtest: process-exit-busy
[273.345818] Subtest process-exit-busy: SUCCESS (0.019s)
[273.515986] [078/134] (764s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[274.000608] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[274.002236] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[274.610843] [079/134] (763s left) gem_lmem_swapping (heavy-verify-multi)
[274.671172] Subtest heavy-verify-multi: SKIP (0.000s)
[274.726734] [080/134] (763s left) gen9_exec_parse (bb-start-cmd)
[274.782781] Subtest bb-start-cmd: SKIP (0.000s)
[274.847363] [081/134] (763s left) fbdev (unaligned-read)
[274.916772] Starting subtest: unaligned-read
[274.928540] Subtest unaligned-read: SUCCESS (0.012s)
[275.030666] [082/134] (763s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[275.578654] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[275.991606] [083/134] (762s left) gem_ppgtt (blt-vs-render-ctx0)
[276.053722] Starting subtest: blt-vs-render-ctx0
[308.622528] Subtest blt-vs-render-ctx0: SUCCESS (32.566s)
[308.786782] [084/134] (729s left) core_setmaster_vs_auth
[309.082533] [085/134] (729s left) prime_busy (hang)
[309.139080] Starting subtest: hang
[309.141839] Starting dynamic subtest: rcs0
[314.867863] Dynamic subtest rcs0: SUCCESS (5.727s)
[314.884779] Starting dynamic subtest: bcs0
[320.879933] Dynamic subtest bcs0: SUCCESS (6.008s)
[320.892257] Starting dynamic subtest: vcs0
[326.894169] Dynamic subtest vcs0: SUCCESS (6.013s)
[326.894955] Subtest hang: SUCCESS (17.755s)
[327.044874] [086/134] (711s left) gem_eio (unwedge-stress)
[327.116798] Starting subtest: unwedge-stress
[327.117564] Starting dynamic subtest: render
[328.811794] Dynamic subtest render: SUCCESS (1.694s)
[328.824583] Starting dynamic subtest: bsd
[330.012179] Dynamic subtest bsd: FAIL (1.169s)
[330.012934] Subtest unwedge-stress: FAIL (2.866s)
[331.057307] [087/134] (707s left) kms_cursor_crc (cursor-random-256x256)
[331.568946] Starting subtest: cursor-random-256x256
[331.571849] Starting dynamic subtest: pipe-A-HDMI-A-1
[335.310529] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.737s)
[335.351930] Starting dynamic subtest: pipe-B-HDMI-A-1
[339.026507] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (3.687s)
[339.027244] Subtest cursor-random-256x256: SUCCESS (7.458s)
[339.747766] [088/134] (699s left) syncobj_timeline (multi-wait-submitted-signaled)
[339.818585] Starting subtest: multi-wait-submitted-signaled
[339.820793] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[339.956286] [089/134] (698s left) kms_dsc (dsc-with-formats)
[340.444095] Subtest dsc-with-formats: SKIP (0.000s)
[340.753123] [090/134] (698s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[341.239834] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[341.241083] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[341.818837] [091/134] (697s left) api_intel_allocator (reopen-fork)
[341.874408] Starting subtest: reopen-fork
[345.312748] Subtest reopen-fork: SUCCESS (3.438s)
[345.448825] [092/134] (693s left) gem_exec_whisper (basic-fds-priority-all)
[345.522364] Starting subtest: basic-fds-priority-all
[345.525072] Subtest basic-fds-priority-all: SKIP (0.001s)
[345.761179] [093/134] (693s left) gem_exec_fence (basic-wait-all)
[345.826085] Starting subtest: basic-wait-all
[345.871604] Subtest basic-wait-all: SUCCESS (0.047s)
[346.398865] [094/134] (692s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[346.456942] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[346.526191] [095/134] (692s left) kms_psr (fbc-psr2-no-drrs)
[347.013606] Subtest fbc-psr2-no-drrs: SKIP (0.000s)
[347.320828] [096/134] (691s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[347.813955] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[347.815236] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[348.426100] [097/134] (690s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[348.915071] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[348.916387] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[349.519640] [098/134] (689s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[350.011989] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[351.948832] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (1.937s)
[352.598823] [099/134] (686s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[353.082793] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[353.083638] Starting dynamic subtest: pipe-A
[353.085647] Dynamic subtest pipe-A: SKIP (0.001s)
[353.099470] Starting dynamic subtest: pipe-B
[353.101812] Dynamic subtest pipe-B: SKIP (0.001s)
[353.103253] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.005s)
[353.681840] [100/134] (685s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[353.743692] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[353.814637] [101/134] (685s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[354.297371] Starting subtest: flip-vs-cursor-busy-crc-atomic
[354.859023] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.561s)
[355.576415] [102/134] (683s left) core_getstats (basic)
[355.633011] Starting subtest: basic
[355.633802] Subtest basic: SUCCESS (0.000s)
[355.753509] [103/134] (683s left) gem_mmap_gtt (hang-busy)
[355.810960] Starting subtest: hang-busy
[361.844177] Subtest hang-busy: SUCCESS (6.034s)
[362.009065] [104/134] (677s left) kms_flip (flip-vs-expired-vblank-interruptible)
[362.501299] Starting subtest: flip-vs-expired-vblank-interruptible
[362.503406] Starting dynamic subtest: A-VGA1
[370.223182] Dynamic subtest A-VGA1: SUCCESS (7.720s)
[370.238844] Starting dynamic subtest: B-VGA1
[377.897080] Dynamic subtest B-VGA1: SUCCESS (7.670s)
[377.913667] Starting dynamic subtest: A-HDMI-A1
[385.627612] Dynamic subtest A-HDMI-A1: SUCCESS (7.727s)
[385.643104] Starting dynamic subtest: B-HDMI-A1
[393.408487] Dynamic subtest B-HDMI-A1: SUCCESS (7.778s)
[393.411498] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (30.908s)
[394.200272] [105/134] (645s left) gem_exec_schedule (preempt-queue-contexts)
[394.274492] Subtest preempt-queue-contexts: SKIP (0.000s)
[394.496564] [106/134] (645s left) perf (oa-formats)
[394.704912] Subtest oa-formats: SKIP (0.000s)
[394.758010] [107/134] (645s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[395.242135] Starting subtest: fbcpsr-modesetfrombusy
[395.243370] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[395.814546] [108/134] (644s left) kms_flip (2x-dpms-vs-vblank-race)
[396.299969] Starting subtest: 2x-dpms-vs-vblank-race
[396.301486] Starting dynamic subtest: AB-VGA1-HDMI-A1
[406.917261] Dynamic subtest AB-VGA1-HDMI-A1: SUCCESS (10.614s)
[406.918120] Subtest 2x-dpms-vs-vblank-race: SUCCESS (10.618s)
[407.666607] [109/134] (632s left) gem_exec_params (readonly)
[407.759141] Starting subtest: readonly
[407.759985] Subtest readonly: SUCCESS (0.001s)
[407.931694] [110/134] (631s left) kms_vblank (invalid)
[408.412036] Starting subtest: invalid
[408.412826] Starting dynamic subtest: pipe-A-HDMI-A-1
[408.627726] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.214s)
[408.628567] Subtest invalid: SUCCESS (0.217s)
[409.341905] [111/134] (630s left) gem_exec_reloc (basic-cpu-read-noreloc)
[409.403804] Starting subtest: basic-cpu-read-noreloc
[409.405887] Subtest basic-cpu-read-noreloc: SUCCESS (0.002s)
[410.420343] [112/134] (629s left) gem_ctx_persistence (engines-hostile)
[410.479587] Subtest engines-hostile: SKIP (0.000s)
[410.551489] [113/134] (629s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[411.042090] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[412.982323] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SUCCESS (1.940s)
[413.612821] [114/134] (626s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[414.103460] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[414.104585] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[414.712437] [115/134] (625s left) gem_caching (writes)
[414.776202] Starting subtest: writes
[417.768620] Subtest writes: SUCCESS (2.994s)
[417.939578] [116/134] (622s left) kms_pm_backlight (fade-with-suspend)
[418.433740] Subtest fade-with-suspend: SKIP (0.000s)
[418.730616] [117/134] (621s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[419.219954] Starting subtest: coverage-vs-premult-vs-constant
[419.374926] Subtest coverage-vs-premult-vs-constant: SKIP (0.156s)
[420.183428] [118/134] (620s left) kms_lease (master-vs-lease)
[420.669782] Starting subtest: master-vs-lease
[420.671058] Subtest master-vs-lease: SUCCESS (0.001s)
[421.269724] [119/134] (619s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[421.757265] Starting subtest: psr-rgb565-draw-mmap-wc
[421.758592] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[422.370744] [120/134] (618s left) kms_addfb_basic (addfb25-4-tiled)
[422.861123] Starting subtest: addfb25-4-tiled
[422.861812] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[423.484273] [121/134] (617s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[423.543764] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[423.606784] [122/134] (616s left) kms_plane_multiple (2x-tiling-x)
[424.094279] Starting subtest: 2x-tiling-x
[424.095851] Subtest 2x-tiling-x: SKIP (0.000s)
[424.698497] [123/134] (615s left) kms_psr (psr2-primary-render)
[425.186889] Subtest psr2-primary-render: SKIP (0.000s)
[425.489662] [124/134] (615s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[425.547200] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[425.649171] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s)
[425.775794] [125/134] (614s left) gem_exec_balancer (full)
[425.840111] Subtest full: SKIP (0.000s)
[425.979486] [126/134] (614s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[426.465297] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[426.466635] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[427.074665] [127/134] (613s left) gem_mmap_wc (write-read)
[427.132507] Starting subtest: write-read
[427.147297] Subtest write-read: SUCCESS (0.016s)
[427.291553] [128/134] (613s left) gem_ctx_persistence (process)
[427.347703] Subtest process: SKIP (0.000s)
[427.419427] [129/134] (613s left) gem_ctx_param (non-root-set)
[427.479109] Starting subtest: non-root-set
[427.504231] Subtest non-root-set: SUCCESS (0.024s)
[427.624661] [130/134] (613s left) sw_sync (timeline_closed)
[427.678634] Starting subtest: timeline_closed
[427.679459] Subtest timeline_closed: SUCCESS (0.000s)
[427.770818] [131/134] (613s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[428.255657] Starting subtest: primary-4-tiled-reflect-x-180
[428.257144] Subtest primary-4-tiled-reflect-x-180: SKIP (0.001s)
[428.856849] [132/134] (612s left) gem_exec_parallel (basic)
[428.922456] Starting subtest: basic
[428.923350] Starting dynamic subtest: rcs0
[430.715916] Dynamic subtest rcs0: SUCCESS (1.792s)
[430.733793] Starting dynamic subtest: bcs0
[432.470919] Dynamic subtest bcs0: SUCCESS (1.751s)
[432.473118] Subtest basic: SUCCESS (3.550s)
[432.712735] [133/134] (608s left) kms_content_protection (uevent-hdcp14)
[433.222809] Starting subtest: uevent-hdcp14
[433.224483] Subtest uevent-hdcp14: SKIP (0.000s)
[433.827857] [134/134] (607s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[433.887645] Starting subtest: multi-wait-all-for-submit-available-signaled
[433.888427] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[434.021872] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: gem_ctx_engines
results: parsing output: 3/ for test: perf_pmu
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: api_intel_bb
results: parsing output: 6/ for test: gem_render_copy
results: parsing output: 7/ for test: kms_plane
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_cursor_crc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: gem_spin_batch
results: parsing output: 12/ for test: gem_exec_reloc
results: parsing output: 13/ for test: kms_plane
results: parsing output: 14/ for test: gem_render_copy
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gen9_exec_parse
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: gem_exec_params
results: parsing output: 22/ for test: gem_render_copy
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: gem_exec_fence
results: parsing output: 25/ for test: kms_chamelium_edid
results: parsing output: 26/ for test: gem_exec_params
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: gem_eio
results: parsing output: 30/ for test: kms_cursor_legacy
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_exec_capture
results: parsing output: 33/ for test: syncobj_timeline
results: parsing output: 34/ for test: kms_async_flips
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: i915_pm_freq_api
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_mmap
results: parsing output: 40/ for test: vgem_basic
results: parsing output: 41/ for test: gem_render_copy
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_exec_schedule
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_flip
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_color_pipeline
results: parsing output: 48/ for test: i915_pm_rc6_residency
results: parsing output: 49/ for test: kms_cursor_legacy
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_psr2_sf
results: parsing output: 53/ for test: syncobj_timeline
results: parsing output: 54/ for test: gem_mmap_wc
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: sysfs_heartbeat_interval
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: gem_wait
results: parsing output: 61/ for test: kms_setmode
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: sw_sync
results: parsing output: 64/ for test: kms_hdr
results: parsing output: 65/ for test: gem_exec_await
results: parsing output: 66/ for test: kms_invalid_mode
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_pm_rpm
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_pm_rps
results: parsing output: 73/ for test: kms_vblank
results: parsing output: 74/ for test: perf_pmu
results: parsing output: 75/ for test: kms_atomic_interruptible
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_lmem_swapping
results: parsing output: 79/ for test: gen9_exec_parse
results: parsing output: 80/ for test: fbdev
results: parsing output: 81/ for test: kms_chamelium_hpd
results: parsing output: 82/ for test: gem_ppgtt
results: parsing output: 83/ for test: core_setmaster_vs_auth
results: parsing output: 84/ for test: prime_busy
results: parsing output: 85/ for test: gem_eio
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_dsc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: api_intel_allocator
results: parsing output: 91/ for test: gem_exec_whisper
results: parsing output: 92/ for test: gem_exec_fence
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_plane_scaling
results: parsing output: 99/ for test: kms_flip_scaled_crc
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: core_getstats
results: parsing output: 102/ for test: gem_mmap_gtt
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: gem_exec_params
results: parsing output: 109/ for test: kms_vblank
results: parsing output: 110/ for test: gem_exec_reloc
results: parsing output: 111/ for test: gem_ctx_persistence
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gem_caching
results: parsing output: 115/ for test: kms_pm_backlight
results: parsing output: 116/ for test: kms_plane_alpha_blend
results: parsing output: 117/ for test: kms_lease
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_addfb_basic
results: parsing output: 120/ for test: kms_flip_scaled_crc
results: parsing output: 121/ for test: kms_plane_multiple
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: gem_exec_balancer
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_mmap_wc
results: parsing output: 127/ for test: gem_ctx_persistence
results: parsing output: 128/ for test: gem_ctx_param
results: parsing output: 129/ for test: sw_sync
results: parsing output: 130/ for test: kms_rotation_crc
results: parsing output: 131/ for test: gem_exec_parallel
results: parsing output: 132/ for test: kms_content_protection
results: parsing output: 133/ for test: syncobj_timeline
Done.