WHL: Few tests - Incomplete - No useful logs
A new WHL bug to associate incompletes with not much in logs https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_8760/fi-whl-u/igt@i915_selftest@live@gem_contexts.html
<6> [282.599879] [IGT] i915_selftest: starting dynamic subtest gem_contexts
<5> [282.679164] Setting dangerous option live_selftests - tainting kernel
<7> [282.704910] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] WOPCM: 1024K
<7> [282.704989] i915 0000:00:02.0: [drm:intel_uc_init_early [i915]] enable_guc=0 (guc:no submission:no huc:no)
<7> [282.705045] i915 0000:00:02.0: [drm:i915_gem_init_early [i915]] fake context support initialized
<7> [282.705092] i915 0000:00:02.0: [drm:intel_pch_type [i915]] Found Cannon Lake LP PCH (CNP-LP)
<7> [282.705148] i915 0000:00:02.0: [drm:intel_power_domains_init [i915]] Allowed DC state mask 02
<7> [282.705338] i915 0000:00:02.0: [drm:intel_uncore_init_mmio [i915]] unclaimed mmio detected on uncore init, clearing
<7> [282.705793] i915 0000:00:02.0: [drm:intel_device_info_runtime_init [i915]] rawclk rate: 24000 kHz
<7> [282.705834] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] CS timestamp wraparound in 178241ms
<7> [282.705908] i915 0000:00:02.0: [drm:i915_ggtt_probe_hw [i915]] GGTT size = 4096M
<7> [282.705950] i915 0000:00:02.0: [drm:i915_ggtt_probe_hw [i915]] GMADR size = 256M
<7> [282.705991] i915 0000:00:02.0: [drm:i915_ggtt_probe_hw [i915]] DSM size = 32M
<6> [282.705993] i915 0000:00:02.0: [drm] VT-d active for gfx access
<6> [282.706003] i915 0000:00:02.0: vgaarb: deactivate vga console
<7> [282.706365] i915 0000:00:02.0: [drm:init_stolen [i915]] GEN6_STOLEN_RESERVED = 8ff00047
<7> [282.706435] i915 0000:00:02.0: [drm:init_stolen [i915]] Memory reserved for graphics device: 32768K, usable: 31744K
<7> [282.706490] [drm:i915_driver_probe [i915]] Initialized 4 GT workarounds on global
<7> [282.706690] i915 0000:00:02.0: [drm:intel_gvt_init [i915]] GVT-g is disabled by kernel params
<7> [282.706746] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] graphic opregion physical addr: 0x8bf2f018
<7> [282.706820] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] ACPI OpRegion version 2.0.0
<7> [282.706874] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] Public ACPI methods supported
<7> [282.706931] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] SWSCI supported
<7> [282.710666] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] SWSCI GBDA callbacks 00000cb3, SBCB callbacks 00300483
<7> [282.710725] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] ASLE supported
<7> [282.710781] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] ASLE extension supported
<7> [282.710836] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] Found valid VBT in ACPI OpRegion (Mailbox #4)
<7> [282.710883] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] DRAM type: DDR4
<7> [282.710926] i915 0000:00:02.0: [drm:skl_dram_get_dimm_info [i915]] CH0 DIMM L size: 8 GB, width: X8, ranks: 1, 16Gb DIMMs: no
<7> [282.710968] i915 0000:00:02.0: [drm:skl_dram_get_dimm_info [i915]] CH0 DIMM S size: 0 GB, width: X0, ranks: 0, 16Gb DIMMs: no
<7> [282.711008] i915 0000:00:02.0: [drm:skl_dram_get_channel_info [i915]] CH0 ranks: 1, 16Gb DIMMs: no
<7> [282.711050] i915 0000:00:02.0: [drm:skl_dram_get_dimm_info [i915]] CH1 DIMM L size: 8 GB, width: X8, ranks: 1, 16Gb DIMMs: no
<7> [282.711090] i915 0000:00:02.0: [drm:skl_dram_get_dimm_info [i915]] CH1 DIMM S size: 0 GB, width: X0, ranks: 0, 16Gb DIMMs: no
<7> [282.711128] i915 0000:00:02.0: [drm:skl_dram_get_channel_info [i915]] CH1 ranks: 1, 16Gb DIMMs: no
<7> [282.711167] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] Memory configuration is symmetric? yes
<7> [282.711208] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] DRAM bandwidth: 38400000 kBps, channels: 2
<7> [282.711246] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] DRAM ranks: 1, 16Gb DIMMs: no
<7> [282.711305] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Skipping VBT init due to disabled display.
<7> [282.711365] i915 0000:00:02.0: [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 00
<7> [282.711481] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling power well 1
<7> [282.711556] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling MISC IO power well
<7> [282.711641] i915 0000:00:02.0: [drm:intel_cdclk_init_hw [i915]] Sanitizing cdclk programmed by pre-os
<7> [282.712328] i915 0000:00:02.0: [drm:intel_update_max_cdclk [i915]] Max CD clock rate: 675000 kHz
<7> [282.712397] i915 0000:00:02.0: [drm:skl_set_cdclk [i915]] Max dotclock rate: 675000 kHz
<7> [282.712469] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [282.712574] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [282.712644] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC off
<7> [282.712698] i915 0000:00:02.0: [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 00
<7> [282.712764] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling power well 2
<7> [282.712855] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI A/E IO power well
<7> [282.712911] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI B IO power well
<7> [282.712971] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI C IO power well
<7> [282.713026] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI D IO power well
<7> [282.713110] i915 0000:00:02.0: [drm:intel_csr_ucode_init [i915]] Loading i915/kbl_dmc_ver1_04.bin
<6> [282.713954] i915 0000:00:02.0: [drm] Finished loading DMC firmware i915/kbl_dmc_ver1_04.bin (v1.4)
<7> [282.714184] i915 0000:00:02.0: [drm:intel_fbc_init [i915]] Sanitized enable_fbc value: 1
<7> [282.714864] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM0 latency 2 (2.0 usec)
<7> [282.714903] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM1 latency 19 (19.0 usec)
<7> [282.714940] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM2 latency 28 (28.0 usec)
<7> [282.714977] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM3 latency 32 (32.0 usec)
<7> [282.715013] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM4 latency 63 (63.0 usec)
<7> [282.715048] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM5 latency 77 (77.0 usec)
<7> [282.715083] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM6 latency 83 (83.0 usec)
<7> [282.715118] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] Gen9 Plane WM7 latency 99 (99.0 usec)
<7> [282.715175] i915 0000:00:02.0: [drm:intel_modeset_init [i915]] 3 display pipes available.
<7> [282.715235] [drm:intel_dump_cdclk_config [i915]] Current CDCLK 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0
<7> [282.715344] i915 0000:00:02.0: [drm:i915_hdcp_component_bind [i915]] I915 HDCP comp bind
<6> [282.715420] mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_component_ops [i915])
<7> [282.715869] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 0 hw state readout: crtc_mask 0x00000000, on 1
<7> [282.715952] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 1 hw state readout: crtc_mask 0x00000000, on 0
<7> [282.716024] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 2 hw state readout: crtc_mask 0x00000000, on 0
<7> [282.716084] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 3 hw state readout: crtc_mask 0x00000000, on 0
<7> [282.716139] i915 0000:00:02.0: [drm:intel_dpll_sanitize_state [i915]] DPLL 0 enabled but not in use, disabling
<7> [282.716431] i915 0000:00:02.0: [drm:i915_init_ggtt [i915]] clearing unused GTT space: [1000, 100000000]
<7> [282.718725] [drm:intel_engines_init [i915]] Initialized 5 engine workarounds on rcs'0
<7> [282.718781] [drm:intel_engines_init [i915]] Initialized 5 whitelist workarounds on rcs'0
<7> [282.718843] [drm:__intel_engine_init_ctx_wa [i915]] Initialized 14 context workarounds on rcs'0
<7> [282.725164] [drm:intel_engines_driver_register [i915]] renamed rcs'0 to rcs0
<7> [282.725214] [drm:intel_engines_driver_register [i915]] renamed bcs'0 to bcs0
<7> [282.725258] [drm:intel_engines_driver_register [i915]] renamed vcs'0 to vcs0
<7> [282.725302] [drm:intel_engines_driver_register [i915]] renamed vecs'0 to vecs0
<6> [282.729963] [drm] Initialized i915 1.6.0 20200715 for 0000:00:02.0 on minor 0
<7> [282.731944] i915 0000:00:02.0: [drm:intel_audio_init [i915]] init value of AUD_FREQ_CNTRL of 0x10
<7> [282.732005] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI D IO power well
<7> [282.732083] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI C IO power well
<7> [282.732141] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI B IO power well
<7> [282.732197] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI A/E IO power well
<7> [282.732253] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling power well 2
<7> [282.732890] [drm:intel_dsm_detect [i915]] no _DSM method for intel device
<7> [282.732908] i915 device info: pciid=0x3ea0 rev=0x00 platform=COFFEELAKE (subplatform=0x1) gen=9
<7> [282.732911] i915 device info: gen: 9
<7> [282.732913] i915 device info: gt: 2
<7> [282.732916] i915 device info: iommu: enabled
<7> [282.732919] i915 device info: memory-regions: 5
<7> [282.732921] i915 device info: page-sizes: 11000
<7> [282.732924] i915 device info: platform: COFFEELAKE
<7> [282.732926] i915 device info: ppgtt-size: 48
<7> [282.732929] i915 device info: ppgtt-type: 2
<7> [282.732931] i915 device info: dma_mask_size: 39
<7> [282.732934] i915 device info: is_mobile: no
<7> [282.732936] i915 device info: is_lp: no
<7> [282.732939] i915 device info: require_force_probe: no
<7> [282.732942] i915 device info: is_dgfx: no
<7> [282.732944] i915 device info: has_64bit_reloc: yes
<7> [282.732947] i915 device info: gpu_reset_clobbers_display: no
<7> [282.732949] i915 device info: has_reset_engine: yes
<7> [282.732952] i915 device info: has_fpga_dbg: yes
<7> [282.732954] i915 device info: has_global_mocs: no
<7> [282.732957] i915 device info: has_gt_uc: yes
<7> [282.732959] i915 device info: has_l3_dpf: no
<7> [282.732962] i915 device info: has_llc: yes
<7> [282.732965] i915 device info: has_logical_ring_contexts: yes
<7> [282.732967] i915 device info: has_logical_ring_elsq: no
<7> [282.732970] i915 device info: has_logical_ring_preemption: yes
<7> [282.732972] i915 device info: has_master_unit_irq: no
<7> [282.732975] i915 device info: has_pooled_eu: no
<7> [282.732977] i915 device info: has_rc6: yes
<7> [282.732980] i915 device info: has_rc6p: no
<7> [282.732982] i915 device info: has_rps: yes
<7> [282.732985] i915 device info: has_runtime_pm: yes
<7> [282.732987] i915 device info: has_snoop: no
<7> [282.732990] i915 device info: has_coherent_ggtt: yes
<7> [282.732993] i915 device info: unfenced_needs_alignment: no
<7> [282.732995] i915 device info: hws_needs_physical: no
<7> [282.732998] i915 device info: cursor_needs_physical: no
<7> [282.733000] i915 device info: has_csr: yes
<7> [282.733003] i915 device info: has_ddi: yes
<7> [282.733005] i915 device info: has_dp_mst: yes
<7> [282.733008] i915 device info: has_dsb: no
<7> [282.733010] i915 device info: has_dsc: no
<7> [282.733013] i915 device info: has_fbc: yes
<7> [282.733015] i915 device info: has_gmch: no
<7> [282.733018] i915 device info: has_hdcp: yes
<7> [282.733020] i915 device info: has_hotplug: yes
<7> [282.733023] i915 device info: has_ipc: yes
<7> [282.733025] i915 device info: has_modular_fia: no
<7> [282.733028] i915 device info: has_overlay: no
<7> [282.733030] i915 device info: has_psr: yes
<7> [282.733033] i915 device info: has_psr_hw_tracking: yes
<7> [282.733035] i915 device info: overlay_needs_physical: no
<7> [282.733038] i915 device info: supports_tv: no
<7> [282.733041] i915 device info: rawclk rate: 24000 kHz
<7> [282.733043] i915 device info: CS timestamp frequency: 12000000 Hz
<7> [282.733046] i915 device info: available engines: 47
<7> [282.733049] i915 device info: slice total: 1, mask=0001
<7> [282.733052] i915 device info: subslice total: 3
<7> [282.733055] i915 device info: slice0: 3 subslices, mask=00000007
<7> [282.733057] i915 device info: slice1: 0 subslices, mask=00000000
<7> [282.733060] i915 device info: slice2: 0 subslices, mask=00000000
<7> [282.733063] i915 device info: EU total: 24
<7> [282.733065] i915 device info: EU per subslice: 8
<7> [282.733068] i915 device info: has slice power gating: no
<7> [282.733070] i915 device info: has subslice power gating: no
<7> [282.733073] i915 device info: has EU power gating: yes
<6> [282.733076] i915 0000:00:02.0: [drm] DRM_I915_DEBUG enabled
<6> [282.733078] i915 0000:00:02.0: [drm] DRM_I915_DEBUG_GEM enabled
<6> [282.733081] i915 0000:00:02.0: [drm] DRM_I915_DEBUG_RUNTIME_PM enabled
<6> [282.733086] i915: Performing live selftests with st_random_seed=0x7e0a481b st_timeout=500
<6> [282.733089] i915: Running gem_contexts
<6> [282.733111] i915: Running i915_gem_context_live_selftests/live_nop_switch
<6> [283.174631] Populated 1024 contexts on rcs0 in 346313709ns
<6> [283.689336] Switch latencies on rcs0: 1 = 260834ns, 310 = 56346ns
<6> [283.914011] Populated 1024 contexts on bcs0 in 224671062ns
<6> [284.426887] Switch latencies on bcs0: 1 = 136671ns, 462 = 28600ns
<6> [284.669250] Populated 1024 contexts on vcs0 in 242358430ns
<6> [285.180439] Switch latencies on vcs0: 1 = 103942ns, 462 = 28039ns
<6> [285.422572] Populated 1024 contexts on vecs0 in 242128448ns
<6> [285.934720] Switch latencies on vecs0: 1 = 132976ns, 466 = 27272ns
<6> [285.935012] i915: Running i915_gem_context_live_selftests/live_parallel_switch
<6> [286.438565] rcs0: 2513 switches (sync)
<6> [286.438596] bcs0: 6011 switches (sync)
<6> [286.439549] vecs0: 7915 switches (sync)
<6> [286.439610] vcs0: 6620 switches (sync)
<6> [286.940564] vcs0: 7350 switches (many)
<6> [286.940565] bcs0: 8169 switches (many)
<6> [286.940605] vecs0: 7825 switches (many)
<6> [286.940877] rcs0: 3696 switches (many)