[CI][BAT]igt@i915_selftest@live_gem_contexts - incomplete -
@l4kshmi
Submitted by LAKSHMINARAYANA VUDUM Assigned to Intel GFX Bugs mailing list
Link to original bug (#111700)
Description
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_5182/fi-cfl-8700k/igt@i915_selftest@live_gem_contexts.html
<6>
[302.011127] [IGT] i915_selftest: executing
<6>
[302.016408] [IGT] i915_selftest: starting subtest live_gem_contexts
<5>
[302.066946] Setting dangerous option live_selftests - tainting kernel
<7>
[302.084371] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] WOPCM: 1024K
<7>
[302.084425] i915 0000:00:02.0: [drm:intel_uc_init_early [i915]] enable_guc=0 (guc:no submission:no huc:no)
<7>
[302.084556] [drm:intel_pch_type [i915]] Found Kaby Lake PCH (KBP)
<7>
[302.084593] [drm:intel_power_domains_init [i915]] Allowed DC state mask 03
<6>
[302.085041] [drm] Display disabled (module parameter)
<7>
[302.085132] [drm:i915_ggtt_probe_hw [i915]] GGTT size = 4096M
<7>
[302.085186] [drm:i915_ggtt_probe_hw [i915]] GMADR size = 256M
<7>
[302.085221] [drm:i915_ggtt_probe_hw [i915]] DSM size = 32M
<6>
[302.085228] i915 0000:00:02.0: vgaarb: deactivate vga console
<7>
[302.085336] [drm:i915_gem_init_stolen [i915]] GEN6_STOLEN_RESERVED = 8ff00047
<7>
[302.085369] [drm:i915_gem_init_stolen [i915]] Memory reserved for graphics device: 32768K, usable: 31744K
<7>
[302.085458] [drm:i915_driver_probe [i915]] Initialized 4 GT workarounds on global
<7>
[302.085585] [drm:intel_gvt_init [i915]] GVT-g is disabled by kernel params
<7>
[302.085621] [drm:intel_opregion_setup [i915]] graphic opregion physical addr: 0x8c916018
<7>
[302.085670] [drm:intel_opregion_setup [i915]] ACPI OpRegion version 2.0.0
<7>
[302.085704] [drm:intel_opregion_setup [i915]] Public ACPI methods supported
<7>
[302.085738] [drm:intel_opregion_setup [i915]] SWSCI supported
<7>
[302.090261] [drm:intel_opregion_setup [i915]] SWSCI GBDA callbacks 00000cb3, SBCB callbacks 00300483
<7>
[302.090310] [drm:intel_opregion_setup [i915]] ASLE supported
<7>
[302.090358] [drm:intel_opregion_setup [i915]] ASLE extension supported
<7>
[302.090426] [drm:intel_opregion_setup [i915]] Found valid VBT in ACPI OpRegion (Mailbox #4 (moved))
<7>
[302.090473] [drm:i915_driver_probe [i915]] DRAM type: DDR4
<7>
[302.090537] [drm:skl_dram_get_dimm_info [i915]] CH0 DIMM L size: 4 GB, width: X8, ranks: 1, 16Gb DIMMs: no
<7>
[302.090581] [drm:skl_dram_get_dimm_info [i915]] CH0 DIMM S size: 0 GB, width: X0, ranks: 0, 16Gb DIMMs: no
<7>
[302.090606] [drm:skl_dram_get_channel_info [i915]] CH0 ranks: 1, 16Gb DIMMs: no
<7>
[302.090650] [drm:skl_dram_get_dimm_info [i915]] CH1 DIMM L size: 4 GB, width: X8, ranks: 1, 16Gb DIMMs: no
<7>
[302.090673] [drm:skl_dram_get_dimm_info [i915]] CH1 DIMM S size: 0 GB, width: X0, ranks: 0, 16Gb DIMMs: no
<7>
[302.090696] [drm:skl_dram_get_channel_info [i915]] CH1 ranks: 1, 16Gb DIMMs: no
<7>
[302.090720] [drm:i915_driver_probe [i915]] Memory configuration is symmetric? yes
<7>
[302.090780] [drm:i915_driver_probe [i915]] DRAM bandwidth: 38400000 kBps, channels: 2
<7>
[302.090816] [drm:i915_driver_probe [i915]] DRAM ranks: 1, 16Gb DIMMs: no
<7>
[302.090866] [drm:intel_bios_init [i915]] Skipping VBT init due to disabled display.
<7>
[302.091284] [drm:intel_dsm_detect [i915]] no _DSM method for intel device
<7>
[302.091333] [drm:i915_driver_probe [i915]] rawclk rate: 24000 kHz
<7>
[302.091380] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 00
<7>
[302.091468] [drm:intel_power_well_enable [i915]] enabling power well 1
<7>
[302.091536] [drm:intel_power_well_enable [i915]] enabling MISC IO power well
<7>
[302.091585] [drm:intel_cdclk_init [i915]] Sanitizing cdclk programmed by pre-os
<7>
[302.091925] [drm:intel_update_max_cdclk [i915]] Max CD clock rate: 675000 kHz
<7>
[302.091968] [drm:skl_set_cdclk [i915]] Max dotclock rate: 675000 kHz
<7>
[302.092061] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[302.092105] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[302.092148] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 00
<7>
[302.092204] [drm:intel_power_well_enable [i915]] enabling power well 2
<7>
[302.092287] [drm:intel_power_well_enable [i915]] enabling DDI A/E IO power well
<7>
[302.092340] [drm:intel_power_well_enable [i915]] enabling DDI B IO power well
<7>
[302.092375] [drm:intel_power_well_enable [i915]] enabling DDI C IO power well
<7>
[302.092411] [drm:intel_power_well_enable [i915]] enabling DDI D IO power well
<7>
[302.092459] [drm:intel_csr_ucode_init [i915]] Loading i915/kbl_dmc_ver1_04.bin
<6>
[302.093052] [drm] Finished loading DMC firmware i915/kbl_dmc_ver1_04.bin (v1.4)
<7>
[302.093830] [drm:intel_fbc_init [i915]] Sanitized enable_fbc value: 1
<7>
[302.093866] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM0 latency 2 (2.0 usec)
<7>
[302.093953] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM1 latency 19 (19.0 usec)
<7>
[302.093979] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM2 latency 28 (28.0 usec)
<7>
[302.094004] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM3 latency 32 (32.0 usec)
<7>
[302.094029] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM4 latency 63 (63.0 usec)
<7>
[302.094053] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM5 latency 77 (77.0 usec)
<7>
[302.094077] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM6 latency 83 (83.0 usec)
<7>
[302.094101] [drm:intel_print_wm_latency [i915]] Gen9 Plane WM7 latency 99 (99.0 usec)
<7>
[302.094137] [drm:intel_modeset_init [i915]] 0 display pipe available.
<7>
[302.094175] [drm:intel_dump_cdclk_state [i915]] Current CDCLK 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0
<7>
[302.094559] [drm:intel_modeset_setup_hw_state [i915]] DPLL 0 hw state readout: crtc_mask 0x00000000, on 1
<7>
[302.094599] [drm:intel_modeset_setup_hw_state [i915]] DPLL 1 hw state readout: crtc_mask 0x00000000, on 0
<7>
[302.094638] [drm:intel_modeset_setup_hw_state [i915]] DPLL 2 hw state readout: crtc_mask 0x00000000, on 0
<7>
[302.094677] [drm:intel_modeset_setup_hw_state [i915]] DPLL 3 hw state readout: crtc_mask 0x00000000, on 0
<7>
[302.094713] [drm:intel_modeset_setup_hw_state [i915]] DPLL 0 enabled but not in use, disabling
<7>
[302.095005] [drm:i915_init_ggtt [i915]] clearing unused GTT space: [1000, 100000000]
<7>
[302.095269] [drm:intel_engines_setup [i915]] Initialized 5 engine workarounds on rcs'0
<7>
[302.095307] [drm:intel_engines_setup [i915]] Initialized 5 whitelist workarounds on rcs'0
<7>
[302.095347] [drm:__intel_engine_init_ctx_wa [i915]] Initialized 14 context workarounds on rcs'0
<7>
[302.095589] [drm:i915_gem_contexts_init [i915]] logical context support initialized
<7>
[302.099362] [drm:intel_engines_driver_register [i915]] renamed rcs'0 to rcs0
<7>
[302.099406] [drm:intel_engines_driver_register [i915]] renamed bcs'0 to bcs0
<7>
[302.099447] [drm:intel_engines_driver_register [i915]] renamed vcs'0 to vcs0
<7>
[302.099487] [drm:intel_engines_driver_register [i915]] renamed vecs'0 to vecs0
<6>
[302.101181] [drm] Initialized i915 1.6.0 20190822 for 0000:00:02.0 on minor 0
<7>
[302.101782] [drm:intel_power_well_disable [i915]] disabling DDI D IO power well
<7>
[302.101827] [drm:intel_power_well_disable [i915]] disabling DDI C IO power well
<7>
[302.101904] [drm:intel_power_well_disable [i915]] disabling DDI B IO power well
<7>
[302.101951] [drm:intel_power_well_disable [i915]] disabling DDI A/E IO power well
<7>
[302.101989] [drm:intel_power_well_disable [i915]] disabling power well 2
<7>
[302.102036] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[302.102092] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[302.102146] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[302.102610] [drm:intel_power_well_disable [i915]] disabling always-on
<7>
[302.102622] i915 device info: pciid=0x3e92 rev=0x00 platform=COFFEELAKE (subplatform=0x0) gen=9
<7>
[302.102623] i915 device info: is_mobile: no
<7>
[302.102624] i915 device info: is_lp: no
<7>
[302.102625] i915 device info: require_force_probe: no
<7>
[302.102626] i915 device info: has_64bit_reloc: yes
<7>
[302.102627] i915 device info: gpu_reset_clobbers_display: no
<7>
[302.102628] i915 device info: has_reset_engine: yes
<7>
[302.102629] i915 device info: has_fpga_dbg: yes
<7>
[302.102630] i915 device info: has_global_mocs: no
<7>
[302.102631] i915 device info: has_gt_uc: yes
<7>
[302.102632] i915 device info: has_l3_dpf: no
<7>
[302.102633] i915 device info: has_llc: yes
<7>
[302.102634] i915 device info: has_logical_ring_contexts: yes
<7>
[302.102635] i915 device info: has_logical_ring_elsq: no
<7>
[302.102636] i915 device info: has_logical_ring_preemption: yes
<7>
[302.102637] i915 device info: has_pooled_eu: no
<7>
[302.102638] i915 device info: has_rc6: yes
<7>
[302.102639] i915 device info: has_rc6p: no
<7>
[302.102640] i915 device info: has_rps: yes
<7>
[302.102641] i915 device info: has_runtime_pm: yes
<7>
[302.102642] i915 device info: has_snoop: no
<7>
[302.102643] i915 device info: has_coherent_ggtt: yes
<7>
[302.102644] i915 device info: unfenced_needs_alignment: no
<7>
[302.102645] i915 device info: hws_needs_physical: no
<7>
[302.102646] i915 device info: cursor_needs_physical: no
<7>
[302.102647] i915 device info: has_csr: yes
<7>
[302.102648] i915 device info: has_ddi: yes
<7>
[302.102649] i915 device info: has_dp_mst: yes
<7>
[302.102650] i915 device info: has_fbc: yes
<7>
[302.102651] i915 device info: has_gmch: no
<7>
[302.102652] i915 device info: has_hotplug: yes
<7>
[302.102653] i915 device info: has_ipc: yes
<7>
[302.102654] i915 device info: has_modular_fia: no
<7>
[302.102655] i915 device info: has_overlay: no
<7>
[302.102656] i915 device info: has_psr: yes
<7>
[302.102657] i915 device info: overlay_needs_physical: no
<7>
[302.102658] i915 device info: supports_tv: no
<7>
[302.102659] i915 device info: slice total: 1, mask=0001
<7>
[302.102660] i915 device info: subslice total: 3
<7>
[302.102662] i915 device info: slice0: 3 subslices, mask=00000007
<7>
[302.102663] i915 device info: slice1: 0 subslices, mask=00000000
<7>
[302.102664] i915 device info: slice2: 0 subslices, mask=00000000
<7>
[302.102665] i915 device info: EU total: 24
<7>
[302.102666] i915 device info: EU per subslice: 8
<7>
[302.102667] i915 device info: has slice power gating: no
<7>
[302.102668] i915 device info: has subslice power gating: no
<7>
[302.102669] i915 device info: has EU power gating: yes
<7>
[302.102670] i915 device info: CS timestamp frequency: 12000 kHz
<6>
[302.102671] [drm] DRM_I915_DEBUG enabled
<6>
[302.102672] [drm] DRM_I915_DEBUG_GEM enabled
<6>
[302.102672] [drm] DRM_I915_DEBUG_RUNTIME_PM enabled
<6>
[302.102674] i915: Performing live selftests with st_random_seed=0xc064a0b3 st_timeout=1000
<6>
[302.102675] i915: Running gem_contexts
<6>
[302.102684] i915: Running i915_gem_context_live_selftests/live_nop_switch
<7>
[302.136213] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[302.136259] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[302.136593] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[302.306573] Populated 1024 contexts on rcs0 in 170717904ns
<7>
[303.338761] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[303.338817] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[303.338854] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[303.339359] [drm:intel_power_well_disable [i915]] disabling always-on
<6>
[303.339362] Switch latencies on rcs0: 1 = 719085ns, 726 = 22075ns
<7>
[303.339635] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[303.339707] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[303.340080] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[303.458011] Populated 1024 contexts on bcs0 in 118645756ns
<7>
[304.485796] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[304.485840] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[304.485933] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[304.486442] [drm:intel_power_well_disable [i915]] disabling always-on
<6>
[304.486487] Switch latencies on bcs0: 1 = 156157ns, 880 = 16244ns
<7>
[304.486774] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[304.486812] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[304.487214] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[304.614532] Populated 1024 contexts on vcs0 in 128042237ns
<7>
[305.643305] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[305.643346] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[305.643382] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[305.643846] [drm:intel_power_well_disable [i915]] disabling always-on
<6>
[305.643919] Switch latencies on vcs0: 1 = 77366ns, 880 = 16484ns
<7>
[305.644187] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[305.644222] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[305.644542] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[305.771300] Populated 1024 contexts on vecs0 in 127377882ns
<7>
[306.805106] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[306.805147] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[306.805183] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[306.805647] [drm:intel_power_well_disable [i915]] disabling always-on
<6>
[306.805665] Switch latencies on vecs0: 1 = 121167ns, 882 = 15974ns
<6>
[306.807476] i915: Running i915_gem_context_live_selftests/igt_ctx_exec
<7>
[306.885831] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[306.885876] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[306.886317] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[307.812149] Submitted 91 contexts to rcs0, filling 91 dwords
<7>
[307.815987] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[307.816028] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[307.816064] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[307.816528] [drm:intel_power_well_disable [i915]] disabling always-on
<7>
[308.690293] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[308.690329] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[308.690647] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[309.671948] Submitted 97 contexts to bcs0, filling 97 dwords
<7>
[309.675691] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[309.675736] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[309.675776] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[309.676284] [drm:intel_power_well_disable [i915]] disabling always-on
<7>
[310.351767] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[310.351804] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[310.352175] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[311.337947] Submitted 97 contexts to vcs0, filling 97 dwords
<7>
[311.341450] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[311.341490] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[311.341526] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[311.342055] [drm:intel_power_well_disable [i915]] disabling always-on
<7>
[312.026609] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[312.026650] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[312.027024] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[313.004028] Submitted 91 contexts to vecs0, filling 91 dwords
<7>
[313.007512] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[313.007555] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[313.007593] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[313.008136] [drm:intel_power_well_disable [i915]] disabling always-on
<6>
[313.624459] i915: Running i915_gem_context_live_selftests/igt_ctx_readonly
<7>
[313.649717] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[313.649760] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[313.650144] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<6>
[314.625950] Submitted 8464 dwords (across 4 engines)
<7>
[314.632700] [drm:intel_power_well_disable [i915]] disabling DC off
<7>
[314.632742] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7>
[314.632780] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7>
[314.633327] [drm:intel_power_well_disable [i915]] disabling always-on
<6>
[314.976681] i915: Running i915_gem_context_live_selftests/igt_ctx_sseu
<6>
[314.976691] i915: Running i915_gem_context_live_selftests/igt_shared_ctx_exec
<7>
[315.002150] [drm:intel_power_well_enable [i915]] enabling always-on
<7>
[315.002188] [drm:intel_power_well_enable [i915]] enabling DC off
<7>
[315.002507] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<0>
[324.974025] <idle>
-0 4.Ns1 314579866us : process_csb: vcs0 csb[1]: status=0x00000818:0x00000001
<0>
[324.974054] <idle>
-0 4.Ns1 314579866us : trace_ports: vcs0: completed { 28e34:8080!, 0:0 }
<0>
[324.974083] <idle>
-0 4dNs2 314579867us : __execlists_submission_tasklet: vcs0: queue_priority_hint:-2147483648, submit:no
<0>
[324.974112] <idle>
-0 4.Ns1 314579867us : process_csb: vecs0 cs-irq head=5, tail=0
<0>
[324.974140] <idle>
-0 4.Ns1 314579867us : process_csb: vecs0 csb[0]: status=0x00000001:0x00000000
<0>
[324.974169] <idle>
-0 4.Ns1 314579867us : trace_ports: vecs0: promote { 28e35:8078, 0:0 }
<0>
[324.974203] i915_sel-5014 1.... 314579868us : i915_request_retire: vecs0 fence 28e35:8076, current 8076
<0>
[324.974238] <idle>
-0 4dNs2 314579868us : __i915_request_submit: vecs0 fence 28e35:8080, current 8076
<0>
[324.974268] <idle>
-0 4dNs2 314579869us : __execlists_submission_tasklet: vecs0: queue_priority_hint:-2147483648, submit:yes
<0>
[324.974297] <idle>
-0 4dNs2 314579869us : trace_ports: vecs0: submit { 28e35:8080, 0:0 }
<0>
[324.974326] rngd-1047 4..s2 314579897us : process_csb: vecs0 cs-irq head=0, tail=1
<0>
[324.974355] rngd-1047 4..s2 314579897us : process_csb: vecs0 csb[1]: status=0x00008002:0x00000001
<0>
[324.974384] rngd-1047 4..s2 314579897us : trace_ports: vecs0: preempted { 28e35:8078, 0:0 }
<0>
[324.974413] rngd-1047 4..s2 314579898us : trace_ports: vecs0: promote { 28e35:8080, 0:0 }
<0>
[324.974442] rngd-1047 4d.s3 314579899us : __execlists_submission_tasklet: vecs0: queue_priority_hint:-2147483648, submit:no
<0>
[324.974476] i915_sel-5014 1.... 314579921us : __i915_request_commit: vecs0 fence 28e35:8084
<0>
[324.974510] i915_sel-5014 1.... 314579986us : i915_request_retire: rcs0 fence 28e32:8078, current 8080
<0>
[324.974544] <idle>
-0 4d.h3 314579993us : __i915_request_submit: rcs0 fence 28e32:8082, current 8080
<0>
[324.974574] <idle>
-0 4d.h3 314579997us : __execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648, submit:yes
<0>
[324.974604] <idle>
-0 4d.h3 314579997us : trace_ports: rcs0: submit { 28e32:8082, 0:0 }
<0>
[324.974634] <idle>
-0 4..s1 314580005us : process_csb: vecs0 cs-irq head=1, tail=2