[119.395328] Initializing watchdogs
[119.395476]   /dev/watchdog0
[119.419576] [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)
[119.427364] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[119.431060] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[119.434127] [001/133] (960s left) kms_chamelium_color (ctm-0-25)
[119.959143] Subtest ctm-0-25: SKIP (0.000s)
[120.251684] [002/133] (959s left) syncobj_timeline (reset-unsignaled)
[120.309374] Starting subtest: reset-unsignaled
[120.310009] Subtest reset-unsignaled: SUCCESS (0.000s)
[120.388599] [003/133] (959s left) gem_unref_active_buffers
[120.626426] [004/133] (959s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[120.688356] Starting subtest: multi-wait-all-for-submit-signaled
[120.689836] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[120.811231] [005/133] (959s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[121.307340] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[121.598503] [006/133] (958s left) gem_exec_reloc (basic-cpu-read)
[121.656590] Starting subtest: basic-cpu-read
[121.659663] Subtest basic-cpu-read: SUCCESS (0.003s)
[122.701257] [007/133] (957s left) kms_psr2_su (page_flip-nv12)
[122.761965] Subtest page_flip-NV12: SKIP (0.000s)
[122.814308] [008/133] (957s left) gem_ctx_persistence (engines-mixed-process)
[122.868737] Subtest engines-mixed-process: SKIP (0.000s)
[122.913119] [009/133] (957s left) kms_chamelium_frames (hdmi-aspect-ratio)
[123.479150] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[123.892590] [010/133] (956s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[123.949732] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[124.010952] [011/133] (956s left) gem_lmem_swapping (verify-random-ccs)
[124.071510] Subtest verify-random-ccs: SKIP (0.000s)
[124.126097] [012/133] (956s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[124.184697] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[124.289109] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[124.382468] [013/133] (956s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[124.895508] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[124.898408] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[125.477314] [014/133] (955s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[125.972944] Starting subtest: sprite-rotation-90-pos-100-0
[125.974568] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[126.577543] [015/133] (953s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-mmap-wc)
[127.077817] Starting subtest: fbcpsr-abgr161616f-draw-mmap-wc
[127.079434] Subtest fbcpsr-abgr161616f-draw-mmap-wc: SKIP (0.000s)
[127.672549] [016/133] (952s left) gem_exec_params (no-vebox)
[127.735136] Starting subtest: no-vebox
[127.735867] Subtest no-vebox: SUCCESS (0.000s)
[127.872353] [017/133] (952s left) gem_busy (busy)
[127.937189] Starting subtest: busy
[127.938100] Starting dynamic subtest: all-engines
[127.963448] Dynamic subtest all-engines: SUCCESS (0.026s)
[127.981553] Starting dynamic subtest: rcs0
[127.982501] Dynamic subtest rcs0: SUCCESS (0.018s)
[127.991299] Starting dynamic subtest: bcs0
[127.997292] Dynamic subtest bcs0: SUCCESS (0.015s)
[128.012324] Starting dynamic subtest: vcs0
[128.013300] Dynamic subtest vcs0: SUCCESS (0.015s)
[128.014102] Subtest busy: SUCCESS (0.076s)
[128.388606] [018/133] (952s left) api_intel_allocator (fork-simple-once)
[128.442268] Starting subtest: fork-simple-once
[128.465824] Subtest fork-simple-once: SUCCESS (0.024s)
[128.622219] [019/133] (952s left) gem_ctx_persistence (engines-queued)
[128.680999] Subtest engines-queued: SKIP (0.000s)
[128.731266] [020/133] (952s left) api_intel_bb (intel-bb-blit-x)
[128.783635] Starting subtest: intel-bb-blit-x
[131.725827] Subtest intel-bb-blit-x: SUCCESS (2.942s)
[131.864324] [021/133] (948s left) gem_exercise_blt (fast-copy)
[131.920883] Subtest fast-copy: SKIP (0.000s)
[131.975163] [022/133] (948s left) kms_plane_multiple (2x-tiling-x)
[132.470316] Starting subtest: 2x-tiling-x
[132.471782] Subtest 2x-tiling-x: SKIP (0.000s)
[133.080619] [023/133] (947s left) i915_drm_fdinfo (isolation)
[133.136312] Subtest isolation: SKIP (0.000s)
[133.191462] [024/133] (947s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[133.247619] Starting subtest: no-spare-fences-busy-interruptible
[135.431177] Subtest no-spare-fences-busy-interruptible: SUCCESS (2.183s)
[135.592026] [025/133] (945s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[136.080961] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.000s)
[136.368646] [026/133] (944s left) kms_atomic (plane-invalid-params)
[136.862195] Starting subtest: plane-invalid-params
[136.863090] Starting dynamic subtest: pipe-A-HDMI-A-1
[137.128823] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.266s)
[137.129706] Subtest plane-invalid-params: SUCCESS (0.267s)
[137.845923] [027/133] (943s left) kms_pm_rpm (system-suspend-idle)
[149.647394] Subtest system-suspend-idle: SKIP (0.000s)
[149.930698] [028/133] (931s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[150.427867] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[150.429242] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[151.015999] [029/133] (930s left) kms_flip (flip-vs-fences)
[151.503461] Starting subtest: flip-vs-fences
[151.504189] Starting dynamic subtest: A-VGA1
[159.277640] Dynamic subtest A-VGA1: SUCCESS (7.773s)
[159.288941] Starting dynamic subtest: B-VGA1
[167.027033] Dynamic subtest B-VGA1: SUCCESS (7.748s)
[167.037336] Starting dynamic subtest: A-HDMI-A1
[174.773234] Dynamic subtest A-HDMI-A1: SUCCESS (7.745s)
[174.784044] Starting dynamic subtest: B-HDMI-A1
[182.556901] Dynamic subtest B-HDMI-A1: SUCCESS (7.783s)
[182.557724] Subtest flip-vs-fences: SUCCESS (31.055s)
[183.315066] [030/133] (897s left) perf (unprivileged-single-ctx-counters)
[183.465024] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[183.513367] [031/133] (897s left) kms_selftest (drm_dp_mst_helper)
[183.568167] Starting subtest: drm_dp_mst_helper
[183.575919] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[183.663202] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.087s)
[183.673902] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[183.743655] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.079s)
[183.757512] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[183.831707] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.088s)
[183.832331] Subtest drm_dp_mst_helper: SUCCESS (0.263s)
[183.999729] [032/133] (897s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[184.061538] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[184.063536] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[184.280212] [033/133] (897s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[184.778929] Starting subtest: basic-flip-before-cursor-legacy
[185.754690] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.975s)
[186.460986] [034/133] (894s left) gem_ctx_param (set-priority-range)
[186.519547] Subtest set-priority-range: SKIP (0.000s)
[186.648723] [035/133] (894s left) perf_pmu (semaphore-busy)
[186.708061] Starting subtest: semaphore-busy
[186.708861] Starting dynamic subtest: rcs0
[186.710383] Dynamic subtest rcs0: SKIP (0.001s)
[186.722610] Starting dynamic subtest: bcs0
[186.724682] Dynamic subtest bcs0: SKIP (0.000s)
[186.740036] Starting dynamic subtest: vcs0
[186.742328] Dynamic subtest vcs0: SKIP (0.000s)
[186.744513] Subtest semaphore-busy: SKIP (0.003s)
[186.827734] [036/133] (894s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[187.330013] Starting subtest: 4-tiled-32bpp-rotate-0
[187.331312] Subtest 4-tiled-32bpp-rotate-0: SKIP (0.000s)
[187.908271] [037/133] (893s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[187.964464] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[188.021270] [038/133] (893s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[188.515265] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[188.516611] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[189.107151] [039/133] (892s left) kms_big_fb (linear-8bpp-rotate-180)
[189.609918] Starting subtest: linear-8bpp-rotate-180
[190.843880] Subtest linear-8bpp-rotate-180: SUCCESS (1.233s)
[191.485312] [040/133] (890s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[191.978321] Starting subtest: plane-downscale-factor-0-5-with-rotation
[191.978982] Starting dynamic subtest: pipe-A
[191.980989] Dynamic subtest pipe-A: SKIP (0.001s)
[191.990940] Starting dynamic subtest: pipe-B
[191.992923] Dynamic subtest pipe-B: SKIP (0.001s)
[191.994168] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.003s)
[192.564914] [041/133] (889s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[193.066117] Starting subtest: x-tiled-32bpp-rotate-0
[194.503083] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.437s)
[195.110323] [042/133] (886s left) core_sysfs (read-all-entries)
[195.164367] Starting subtest: read-all-entries
[195.166480] Subtest read-all-entries: SUCCESS (0.003s)
[195.294359] [043/133] (886s left) gem_pwrite (basic-self)
[195.346084] Starting subtest: basic-self
[195.436782] Subtest basic-self: SUCCESS (0.088s)
[195.575789] [044/133] (886s left) gem_spin_batch (resubmit-new)
[195.641619] Starting subtest: resubmit-new
[195.642344] Starting dynamic subtest: rcs0
[195.644677] Dynamic subtest rcs0: SUCCESS (0.003s)
[195.656289] Starting dynamic subtest: bcs0
[195.657069] Dynamic subtest bcs0: SUCCESS (0.002s)
[195.666062] Starting dynamic subtest: vcs0
[195.666776] Dynamic subtest vcs0: SUCCESS (0.002s)
[195.667585] Subtest resubmit-new: SUCCESS (0.009s)
[195.874874] [045/133] (885s left) kms_pm_lpsp (kms-lpsp)
[207.410637] Starting subtest: kms-lpsp
[207.413570] Subtest kms-lpsp: SKIP (0.000s)
[208.003153] [046/133] (873s left) gem_ctx_shared (exec-single-timeline)
[208.063651] Subtest exec-single-timeline: SKIP (0.000s)
[208.182717] [047/133] (873s left) kms_feature_discovery (display-2x)
[208.679468] Starting subtest: display-2x
[208.680941] Subtest display-2x: SKIP (0.000s)
[209.267884] [048/133] (872s left) syncobj_wait (invalid-single-wait-unsubmitted)
[209.327542] Starting subtest: invalid-single-wait-unsubmitted
[209.328288] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[209.452217] [049/133] (872s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[209.942681] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[209.945188] Subtest psr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[210.568466] [050/133] (871s left) kms_frontbuffer_tracking (psr-slowdraw)
[211.069622] Starting subtest: psr-slowdraw
[211.071242] Subtest psr-slowdraw: SKIP (0.000s)
[211.663799] [051/133] (870s left) sw_sync (sync_busy)
[211.719886] Starting subtest: sync_busy
[211.723361] Subtest sync_busy: SUCCESS (0.004s)
[211.831464] [052/133] (870s left) kms_flip (2x-wf_vblank-ts-check)
[212.316770] Starting subtest: 2x-wf_vblank-ts-check
[212.317467] Starting dynamic subtest: AB-VGA1-HDMI-A1
[243.060125] Dynamic subtest AB-VGA1-HDMI-A1: SUCCESS (30.742s)
[243.062868] Subtest 2x-wf_vblank-ts-check: SUCCESS (30.746s)
[243.849646] [053/133] (838s left) gem_exec_reloc (basic-gtt-wc-active)
[243.909966] Starting subtest: basic-gtt-wc-active
[244.021016] Subtest basic-gtt-wc-active: SUCCESS (0.110s)
[245.044883] [054/133] (837s left) perf (invalid-oa-exponent)
[245.206940] Subtest invalid-oa-exponent: SKIP (0.000s)
[245.255326] [055/133] (837s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[245.753119] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[245.754648] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[246.360221] [056/133] (836s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[246.857363] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[246.858766] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[247.456721] [057/133] (834s left) gem_exec_schedule (preempt-self)
[247.526654] Subtest preempt-self: SKIP (0.000s)
[247.759573] [058/133] (834s left) prime_mmap (test_invalid_sync_flags)
[247.816875] Starting subtest: test_invalid_sync_flags
[247.817742] Starting dynamic subtest: test_invalid_sync_flags-smem
[247.818478] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[247.819414] Subtest test_invalid_sync_flags: SUCCESS (0.001s)
[247.956323] [059/133] (834s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[248.450882] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[248.453957] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.001s)
[249.052973] [060/133] (833s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[249.542439] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[249.543933] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[250.135430] [061/133] (832s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[250.629545] Starting subtest: fbc-rgb565-draw-pwrite
[252.537063] Subtest fbc-rgb565-draw-pwrite: SUCCESS (1.907s)
[253.162550] [062/133] (829s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[253.657105] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[253.938558] [063/133] (828s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[254.426816] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[254.466803] Starting dynamic subtest: pipe-A-plane-0
[255.630321] Dynamic subtest pipe-A-plane-0: SUCCESS (1.163s)
[255.644761] Starting dynamic subtest: pipe-A-plane-1
[255.996143] Dynamic subtest pipe-A-plane-1: SUCCESS (0.363s)
[256.010201] Starting dynamic subtest: pipe-A-plane-2
[256.011629] Dynamic subtest pipe-A-plane-2: SKIP (0.000s)
[256.244733] Starting dynamic subtest: pipe-B-plane-0
[257.390213] Dynamic subtest pipe-B-plane-0: SUCCESS (1.163s)
[257.402742] Starting dynamic subtest: pipe-B-plane-1
[257.755980] Dynamic subtest pipe-B-plane-1: SUCCESS (0.363s)
[257.770440] Starting dynamic subtest: pipe-B-plane-2
[257.772144] Dynamic subtest pipe-B-plane-2: SKIP (0.000s)
[257.855813] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.429s)
[258.559110] [064/133] (824s left) gem_pread (snoop)
[258.621185] Starting subtest: snoop
[259.929832] Subtest snoop: SUCCESS (1.308s)
[260.039451] [065/133] (822s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[260.524861] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[260.526160] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[261.121165] [066/133] (821s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[261.617192] Starting subtest: flip-vs-dpms-on-nop-interruptible
[261.619804] Starting dynamic subtest: A-VGA1
[262.624667] Dynamic subtest A-VGA1: SUCCESS (1.005s)
[262.636962] Starting dynamic subtest: B-VGA1
[263.580536] Dynamic subtest B-VGA1: SUCCESS (0.951s)
[263.590242] Starting dynamic subtest: A-HDMI-A1
[264.616332] Dynamic subtest A-HDMI-A1: SUCCESS (1.035s)
[264.627744] Starting dynamic subtest: B-HDMI-A1
[265.676289] Dynamic subtest B-HDMI-A1: SUCCESS (1.057s)
[265.680418] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (4.060s)
[266.471533] [067/133] (816s left) core_auth (basic-auth)
[266.530848] Starting subtest: basic-auth
[266.531540] Subtest basic-auth: SUCCESS (0.001s)
[266.661764] [068/133] (816s left) gem_softpin (softpin)
[266.720745] Starting subtest: softpin
[298.934105] Subtest softpin: SUCCESS (32.213s)
[299.090423] [069/133] (783s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[299.581479] Starting subtest: fbc-indfb-scaledprimary
[299.582943] Subtest fbc-indfb-scaledprimary: SKIP (0.000s)
[300.170843] [070/133] (782s left) syncobj_wait (wait-any-interrupted)
[300.229142] Starting subtest: wait-any-interrupted
[300.330231] Subtest wait-any-interrupted: SUCCESS (0.101s)
[300.452017] [071/133] (782s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[300.941419] Starting subtest: cursor-tearing-framebuffer-change
[300.942238] Starting dynamic subtest: pipe-A-HDMI-A-1
[301.291501] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.348s)
[301.303785] Starting dynamic subtest: pipe-B-HDMI-A-1
[301.651938] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.360s)
[301.654209] Subtest cursor-tearing-framebuffer-change: SUCCESS (0.712s)
[302.384965] [072/133] (780s left) kms_chamelium_hpd (hdmi-hpd)
[302.939980] Subtest hdmi-hpd: SKIP (0.000s)
[303.337569] [073/133] (779s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[303.829663] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[303.831083] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[304.423720] [074/133] (778s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[304.479465] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[304.605136] [075/133] (778s left) gem_mmap_offset (bad-extensions)
[304.661589] Starting subtest: bad-extensions
[304.662306] Subtest bad-extensions: SUCCESS (0.000s)
[304.790930] [076/133] (778s left) i915_pm_freq_api (freq-reset)
[304.846748] Subtest freq-reset: SKIP (0.000s)
[304.902340] [077/133] (778s left) gem_exec_balancer (hang)
[304.960991] Subtest hang: SKIP (0.000s)
[305.089478] [078/133] (778s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[305.576833] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[305.578168] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[306.179952] [079/133] (777s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[306.668061] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[306.669270] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[307.268280] [080/133] (776s left) i915_pm_rps (reset)