Bug 110235

Summary: [gvt][CI][BAT] igt@i915_selftest@live_contexts - igt_shared_ctx_exec: Invalid value at object 0 page 0/1024, offset 0/1024: found 153c4083 expected 0
Product: DRI Reporter: Lakshmi <lakshminarayana.vudum>
Component: DRM/iGVT-gAssignee: Terrence Xu <terrence.xu>
Status: CLOSED WORKSFORME QA Contact: Terrence Xu <terrence.xu>
Severity: normal    
Priority: highest CC: intel-gfx-bugs
Version: DRI git   
Hardware: Other   
OS: All   
Whiteboard: ReadyForDev
i915 platform: BDW, SKL i915 features: GEM/Other

Description Lakshmi 2019-03-25 09:09:49 UTC 
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5796/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html

6> [383.459798] [IGT] i915_selftest: executing
<6> [383.508966] [IGT] i915_selftest: starting subtest live_contexts
<5> [383.603263] Setting dangerous option live_selftests - tainting kernel
<7> [383.642789] [drm:i915_driver_load [i915]] Assuming PCH ID 9c00
<7> [383.642840] [drm:intel_pch_type [i915]] Found LynxPoint LP PCH
<7> [383.642885] [drm:i915_driver_load [i915]] WOPCM size: 1024KiB
<7> [383.642947] [drm:intel_uc_init_early [i915]] enable_guc=0 (submission:no huc:no)
<7> [383.643002] [drm:intel_uc_init_early [i915]] guc_log_level=0 (enabled:no, verbose:no, verbosity:0)
<7> [383.643050] [drm:intel_power_domains_init [i915]] Allowed DC state mask 00
<6> [383.654680] [drm] Display disabled (module parameter)
<7> [383.654862] [drm:i915_ggtt_probe_hw [i915]] GGTT size = 4096M
<7> [383.654931] [drm:i915_ggtt_probe_hw [i915]] GMADR size = 256M
<7> [383.654982] [drm:i915_ggtt_probe_hw [i915]] DSM size = 0M
<7> [383.657042] [drm:intel_gvt_init [i915]] GVT-g is disabled by kernel params
<7> [383.657116] [drm:intel_opregion_setup [i915]] graphic opregion physical addr: 0xdaccf018
<7> [383.657229] [drm:intel_opregion_setup [i915]] opregion signature mismatch
<7> [383.657301] [drm:intel_bios_init [i915]] Skipping VBT init due to disabled display.
<7> [383.662514] [drm:intel_dsm_detect [i915]] no _DSM method for intel device
<7> [383.662642] [drm:intel_dsm_detect [i915]] no _DSM method for intel device
<7> [383.662691] [drm:i915_driver_load [i915]] rawclk rate: 24000 kHz
<7> [383.662814] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.662875] [drm:intel_power_well_enable [i915]] enabling display
<7> [383.664812] [drm:intel_fbc_init [i915]] Sanitized enable_fbc value: 1
<7> [383.664862] [drm:intel_print_wm_latency [i915]] Primary WM0 latency 20 (2.0 usec)
<7> [383.664907] [drm:intel_print_wm_latency [i915]] Primary WM1 latency 50 (25.0 usec)
<7> [383.664949] [drm:intel_print_wm_latency [i915]] Primary WM2 latency 90 (45.0 usec)
<7> [383.664989] [drm:intel_print_wm_latency [i915]] Primary WM3 latency 130 (65.0 usec)
<7> [383.665028] [drm:intel_print_wm_latency [i915]] Primary WM4 latency 160 (80.0 usec)
<7> [383.665068] [drm:intel_print_wm_latency [i915]] Sprite WM0 latency 20 (2.0 usec)
<7> [383.665107] [drm:intel_print_wm_latency [i915]] Sprite WM1 latency 50 (25.0 usec)
<7> [383.665146] [drm:intel_print_wm_latency [i915]] Sprite WM2 latency 90 (45.0 usec)
<7> [383.665184] [drm:intel_print_wm_latency [i915]] Sprite WM3 latency 130 (65.0 usec)
<7> [383.665221] [drm:intel_print_wm_latency [i915]] Sprite WM4 latency 160 (80.0 usec)
<7> [383.665259] [drm:intel_print_wm_latency [i915]] Cursor WM0 latency 20 (2.0 usec)
<7> [383.665313] [drm:intel_print_wm_latency [i915]] Cursor WM1 latency 50 (25.0 usec)
<7> [383.665363] [drm:intel_print_wm_latency [i915]] Cursor WM2 latency 90 (45.0 usec)
<7> [383.665402] [drm:intel_print_wm_latency [i915]] Cursor WM3 latency 130 (65.0 usec)
<7> [383.667327] [drm:intel_print_wm_latency [i915]] Cursor WM4 latency 160 (80.0 usec)
<7> [383.672479] [drm:intel_modeset_init [i915]] 0 display pipe available.
<7> [383.672560] [drm:intel_dump_cdclk_state [i915]] Current CDCLK 337500 kHz, VCO 0 kHz, ref 0 kHz, bypass 0 kHz, voltage level 2
<7> [383.672633] [drm:intel_update_max_cdclk [i915]] Max CD clock rate: 540000 kHz
<7> [383.672689] [drm:intel_modeset_init [i915]] Max dotclock rate: 540000 kHz
<7> [383.673219] [drm:intel_modeset_setup_hw_state [i915]] WRPLL 1 hw state readout: crtc_mask 0x00000000, on 0
<7> [383.673281] [drm:intel_modeset_setup_hw_state [i915]] WRPLL 2 hw state readout: crtc_mask 0x00000000, on 0
<7> [383.673340] [drm:intel_modeset_setup_hw_state [i915]] SPLL hw state readout: crtc_mask 0x00000000, on 0
<7> [383.673396] [drm:intel_modeset_setup_hw_state [i915]] LCPLL 810 hw state readout: crtc_mask 0x00000000, on 1
<7> [383.674711] [drm:intel_modeset_setup_hw_state [i915]] LCPLL 1350 hw state readout: crtc_mask 0x00000000, on 1
<7> [383.674767] [drm:intel_modeset_setup_hw_state [i915]] LCPLL 2700 hw state readout: crtc_mask 0x00000000, on 1
<7> [383.674823] [drm:intel_modeset_setup_hw_state [i915]] LCPLL 810 enabled but not in use, disabling
<7> [383.674875] [drm:intel_modeset_setup_hw_state [i915]] LCPLL 1350 enabled but not in use, disabling
<7> [383.674926] [drm:intel_modeset_setup_hw_state [i915]] LCPLL 2700 enabled but not in use, disabling
<7> [383.685591] [drm:i915_gem_init_ggtt [i915]] clearing unused GTT space: [1000, 100000000]
<7> [383.686120] [drm:intel_engine_init_ctx_wa [i915]] Initialized 11 context workarounds
<7> [383.686825] [drm:i915_gem_contexts_init [i915]] logical context support initialized
<6> [383.726472] [drm] Initialized i915 1.6.0 20190320 for 0000:00:06.0 on minor 0
<7> [383.728731] [drm:intel_power_well_disable [i915]] disabling display
<7> [383.728838] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.728848] i915 device info: pciid=0x1626 rev=0x09 platform=BROADWELL gen=8
<7> [383.728851] i915 device info: is_mobile: no
<7> [383.728853] i915 device info: is_lp: no
<7> [383.728855] i915 device info: is_alpha_support: no
<7> [383.728856] i915 device info: has_64bit_reloc: yes
<7> [383.728858] i915 device info: gpu_reset_clobbers_display: no
<7> [383.728860] i915 device info: has_reset_engine: yes
<7> [383.728862] i915 device info: has_fpga_dbg: yes
<7> [383.728863] i915 device info: has_guc: no
<7> [383.728865] i915 device info: has_guc_ct: no
<7> [383.728867] i915 device info: has_l3_dpf: no
<7> [383.728868] i915 device info: has_llc: yes
<7> [383.728870] i915 device info: has_logical_ring_contexts: yes
<7> [383.728872] i915 device info: has_logical_ring_elsq: no
<7> [383.728874] i915 device info: has_logical_ring_preemption: no
<7> [383.728875] i915 device info: has_pooled_eu: no
<7> [383.728877] i915 device info: has_rc6: yes
<7> [383.728879] i915 device info: has_rc6p: no
<7> [383.728880] i915 device info: has_runtime_pm: yes
<7> [383.728882] i915 device info: has_snoop: no
<7> [383.728884] i915 device info: has_coherent_ggtt: yes
<7> [383.728886] i915 device info: unfenced_needs_alignment: no
<7> [383.728887] i915 device info: hws_needs_physical: no
<7> [383.728889] i915 device info: cursor_needs_physical: no
<7> [383.728891] i915 device info: has_csr: no
<7> [383.728892] i915 device info: has_ddi: yes
<7> [383.728894] i915 device info: has_dp_mst: yes
<7> [383.728896] i915 device info: has_fbc: yes
<7> [383.728898] i915 device info: has_gmch: no
<7> [383.728899] i915 device info: has_hotplug: yes
<7> [383.728901] i915 device info: has_ipc: no
<7> [383.728903] i915 device info: has_overlay: no
<7> [383.728904] i915 device info: has_psr: yes
<7> [383.728906] i915 device info: overlay_needs_physical: no
<7> [383.728908] i915 device info: supports_tv: no
<7> [383.728910] i915 device info: slice total: 2, mask=0003
<7> [383.728911] i915 device info: subslice total: 6
<7> [383.728914] i915 device info: slice0: 3 subslices, mask=0007
<7> [383.728915] i915 device info: slice1: 3 subslices, mask=0007
<7> [383.728917] i915 device info: slice2: 0 subslices, mask=0000
<7> [383.728919] i915 device info: EU total: 48
<7> [383.728921] i915 device info: EU per subslice: 8
<7> [383.728923] i915 device info: has slice power gating: yes
<7> [383.728924] i915 device info: has subslice power gating: no
<7> [383.728926] i915 device info: has EU power gating: no
<7> [383.728928] i915 device info: CS timestamp frequency: 12500 kHz
<6> [383.728929] [drm] DRM_I915_DEBUG enabled
<6> [383.728931] [drm] DRM_I915_DEBUG_GEM enabled
<6> [383.728932] [drm] DRM_I915_DEBUG_RUNTIME_PM enabled
<6> [383.728935] i915: Performing live selftests with st_random_seed=0xa61a0ec3 st_timeout=1000
<6> [383.728937] i915: Running contexts
<6> [383.728949] i915: Running i915_gem_context_live_selftests/igt_switch_to_kernel_context
<7> [383.729347] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.738585] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.738812] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.739934] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.740229] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.740741] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.740823] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.741166] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.742721] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.743100] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.748324] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.748931] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.749761] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.750189] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.751165] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.751796] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.753915] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.754326] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.757599] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.758037] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.758151] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.760116] [drm:intel_power_well_disable [i915]] disabling always-on
<7> [383.763075] [drm:intel_power_well_enable [i915]] enabling always-on
<7> [383.770149] [drm:intel_power_well_disable [i915]] disabling always-on
<6> [383.770372] i915: Running i915_gem_context_live_selftests/live_nop_switch
<7> [383.817641] [drm:intel_power_well_enable [i915]] enabling always-on
<6> [384.161052] Populated 1024 contexts on rcs0 in 343884283ns
<6> [385.204170] Switch latencies on rcs0: 1 = 310698ns, 478 = 52674ns
<6> [385.424730] Populated 1024 contexts on bcs0 in 220551547ns
<6> [386.447740] Switch latencies on bcs0: 1 = 124118ns, 540 = 40182ns
<6> [386.651907] Populated 1024 contexts on vcs0 in 204144087ns
<6> [387.693211] Switch latencies on vcs0: 1 = 121050ns, 576 = 37058ns
<6> [387.897170] Populated 1024 contexts on vcs1 in 203953284ns
<6> [388.917882] Switch latencies on vcs1: 1 = 126815ns, 568 = 37675ns
<6> [389.121843] Populated 1024 contexts on vecs0 in 203952588ns
<6> [390.156658] Switch latencies on vecs0: 1 = 126033ns, 576 = 37302ns
<6> [390.164428] i915: Running i915_gem_context_live_selftests/igt_ctx_exec
<6> [391.175070] Submitted 53 contexts to rcs0, filling 53 dwords
<6> [392.208647] Submitted 38 contexts to bcs0, filling 38 dwords
<6> [393.227198] Submitted 38 contexts to vcs0, filling 38 dwords
<6> [394.251948] Submitted 38 contexts to vcs1, filling 38 dwords
<6> [395.275108] Submitted 38 contexts to vecs0, filling 38 dwords
<6> [395.283301] i915: Running i915_gem_context_live_selftests/igt_ctx_readonly
<6> [396.286153] Submitted 780 dwords (across 5 engines)
<6> [396.294477] i915: Running i915_gem_context_live_selftests/igt_ctx_sseu
<6> [396.294490] i915: Running i915_gem_context_live_selftests/igt_shared_ctx_exec
<6> [397.327832] Submitted 1253 contexts to rcs0, filling 1253 dwords
<6> [398.337607] Submitted 1614 contexts to bcs0, filling 1614 dwords
<6> [399.664225] Submitted 1025 contexts to vcs0, filling 1025 dwords
<6> [400.669450] Submitted 1914 contexts to vcs1, filling 1914 dwords
<6> [402.386245] Submitted 1025 contexts to vecs0, filling 1025 dwords
<3> [403.900275] igt_shared_ctx_exec+0x255/0x400 [i915]: Invalid value at object 0 page 0/1024, offset 0/1024: found 153c4083 expected 0
<3> [403.913449] i915/i915_gem_context_live_selftests: igt_shared_ctx_exec failed with error -22
<7> [403.913798] [drm:intel_power_well_enable [i915]] enabling display
<4> [404.700696] i915: probe of 0000:00:06.0 failed with error -22
<6> [404.944745] [IGT] i915_selftest: exiting, ret=99
<6> [404.950086] i915_selftest (3842) used greatest stack depth: 10976 bytes left

On SKL: 
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5796/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
Comment 1 CI Bug Log 2019-03-25 09:10:51 UTC 
The CI Bug Log issue associated to this bug has been updated.

### New filters associated

* BDW SKL : igt@i915_selftest@live_contexts - dmesg-fail - igt_shared_ctx_exec failed with error -22
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12450/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12451/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Trybot_3980/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12496/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12569/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12570/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12571/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5796/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5796/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12576/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12577/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_2692/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_2692/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_2693/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_2693/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12579/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4900/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_2694/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Trybot_4000/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5801/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5802/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_2696/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12585/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_12586/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5805/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_2697/fi-bdw-gvtdvm/igt@i915_selftest@live_contexts.html
  - https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5808/fi-skl-gvtdvm/igt@i915_selftest@live_contexts.html
Comment 2 Joonas Lahtinen 2019-04-01 06:50:57 UTC 
Seems to be a problem in hypervisor:

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5796/fi-bdw-gvtdvm/hostdmesg0.log

So I think this needs to be moved to GVT team to look into.
Comment 3 CI Bug Log 2019-04-02 06:28:37 UTC 
A CI Bug Log filter associated to this bug has been updated:

{- BDW SKL : igt@i915_selftest@live_contexts - dmesg-fail - igt_shared_ctx_exec failed with error -22 -}
{+ HSW BDW SKL : igt@i915_selftest@live_contexts - dmesg-fail - igt_shared_ctx_exec failed with error -22 +}

New failures caught by the filter:

* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5852/fi-hsw-peppy/igt@i915_selftest@live_contexts.html
Comment 4 CI Bug Log 2019-05-08 05:30:05 UTC 
A CI Bug Log filter associated to this bug has been updated:

{- HSW BDW SKL : igt@i915_selftest@live_contexts - dmesg-fail - igt_shared_ctx_exec failed with error -22 -}
{+ HSW BDW SKL KBL : igt@i915_selftest@live_contexts - dmesg-fail - igt_shared_ctx_exec failed with error -22 +}

New failures caught by the filter:

  * https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_6060/fi-kbl-8809g/igt@i915_selftest@live_contexts.html
Comment 5 Lakshmi 2019-08-13 12:16:42 UTC 
The reproduction rate of this issue is once in 2.07 CI_DRM runs(251 failures from 521 runs). Last seen this issue CI_DRM_6317 (1 month, 3 weeks old) and current CI_DRM run is 6691.

Closing this issue as WORKSFORME.
Comment 6 CI Bug Log 2019-08-13 12:16:54 UTC 
The CI Bug Log issue associated to this bug has been archived.

New failures matching the above filters will not be associated to this bug anymore.

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.