Bug 110668

Summary: [CI][DRMTIP] igt@kms_content_protection@atomic-dpms - dmesg-fail - *ERROR* Timed out waiting for R0 ready
Product: DRI Reporter: Lakshmi <lakshminarayana.vudum>
Component: DRM/IntelAssignee: Intel GFX Bugs mailing list <intel-gfx-bugs>
Status: RESOLVED WORKSFORME QA Contact: Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity: normal    
Priority: medium CC: intel-gfx-bugs, ramalingam.c
Version: DRI git   
Hardware: Other   
OS: All   
Whiteboard: ReadyForDev
i915 platform: SKL i915 features: display/Other

Description Lakshmi 2019-05-13 07:41:09 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_283/fi-skl-lmem/igt@kms_content_protection@atomic-dpms.html

Dmesg-Warnings	
<3> [268.666171] [drm:intel_hdcp_auth [i915]] *ERROR* Timed out waiting for R0 ready

Dmesg
<7> [268.660252] [drm:intel_dump_pipe_config [i915]] requested mode:
<7> [268.660256] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [268.660294] [drm:intel_dump_pipe_config [i915]] adjusted mode:
<7> [268.660298] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [268.660336] [drm:intel_dump_pipe_config [i915]] crtc timings: 65000 1024 1048 1184 1344 768 771 777 806, type: 0x40 flags: 0xa
<7> [268.660375] [drm:intel_dump_pipe_config [i915]] port clock: 162000, pipe src size: 1024x768, pixel rate 65000
<7> [268.660413] [drm:intel_dump_pipe_config [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1
<7> [268.660451] [drm:intel_dump_pipe_config [i915]] pch pfit: pos: 0x00000000, size: 0x00000000, disabled, force thru: no
<7> [268.660506] [drm:intel_dump_pipe_config [i915]] ips: 0, double wide: 0
<7> [268.660544] [drm:intel_dump_pipe_config [i915]] dpll_hw_state: ctrl1: 0x5, cfgcr1: 0x0, cfgcr2: 0x0
<7> [268.660583] [drm:intel_dump_pipe_config [i915]] planes on this crtc
<7> [268.660622] [drm:intel_dump_pipe_config [i915]] [PLANE:30:plane 1A] FB:116, fb = 1024x768 format = XR24 little-endian (0x34325258)
<7> [268.660661] [drm:intel_dump_pipe_config [i915]] 	scaler:-1 src 0x0+1024+768 dst 0x0+1024+768
<7> [268.660701] [drm:intel_dump_pipe_config [i915]] [PLANE:37:plane 2A] disabled, scaler_id = -1
<7> [268.660740] [drm:intel_dump_pipe_config [i915]] [PLANE:44:cursor A] disabled, scaler_id = -1
<7> [268.660856] [drm:intel_ddi_update_pipe [i915]] Panel doesn't support DRRS
<7> [268.661632] [drm:_intel_hdcp_enable [i915]] [DP-1:85] HDCP is being enabled...
<3> [268.666171] [drm:intel_hdcp_auth [i915]] *ERROR* Timed out waiting for R0 ready
<7> [268.666391] [drm:_intel_hdcp_enable [i915]] HDCP Auth failure (-110)
<7> [268.666435] [drm:_intel_hdcp_disable [i915]] [DP-1:85] HDCP is being disabled...
<7> [268.666497] [drm:_intel_hdcp_disable [i915]] HDCP is disabled
<7> [274.117667] [drm:intel_hdcp_auth [i915]] KSV list failed to become ready (-110)
<7> [274.117771] [drm:_intel_hdcp_enable [i915]] HDCP Auth failure (-110)
<7> [274.117867] [drm:_intel_hdcp_disable [i915]] [DP-1:85] HDCP is being disabled...
<7> [274.126592] [drm:_intel_hdcp_disable [i915]] HDCP is disabled
<7> [279.625448] [drm:intel_hdcp_auth [i915]] KSV list failed to become ready (-110)
<7> [279.625590] [drm:_intel_hdcp_enable [i915]] HDCP Auth failure (-110)
<7> [279.625686] [drm:_intel_hdcp_disable [i915]] [DP-1:85] HDCP is being disabled...
<7> [279.630621] [drm:_intel_hdcp_disable [i915]] HDCP is disabled
<7> [279.630730] [drm:_intel_hdcp_enable [i915]] HDCP authentication failed (3 tries/-110)
<7> [279.642139] [drm:verify_connector_state.isra.81 [i915]] [CONNECTOR:85:DP-1]
<7> [279.642220] [drm:intel_atomic_commit_tail [i915]] [CRTC:47:pipe A]
<7> [279.642328] [drm:verify_single_dpll_state.isra.114 [i915]] DPLL 1
<7> [302.743664] [drm:intel_atomic_check [i915]] [CONNECTOR:85:DP-1] Limiting display bpp to 24 instead of EDID bpp 0, requested bpp 36, max platform bpp 36
<7> [302.743790] [drm:intel_dp_compute_config [i915]] DP link computation with max lane count 4 max rate 540000 max bpp 24 pixel clock 65000KHz
<7> [302.743892] [drm:intel_dp_compute_config [i915]] Force DSC en = 0
<7> [302.743993] [drm:intel_dp_compute_config [i915]] DP lane count 2 clock 162000 bpp 24
<7> [302.744092] [drm:intel_dp_compute_config [i915]] DP link rate required 195000 available 324000
<7> [302.744196] [drm:intel_atomic_check [i915]] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [302.744300] [drm:intel_dump_pipe_config [i915]] [CRTC:47:pipe A][fastset]
<7> [302.744401] [drm:intel_dump_pipe_config [i915]] output_types: DP (0x80)
<7> [302.744501] [drm:intel_dump_pipe_config [i915]] output format: RGB
<7> [302.744662] [drm:intel_dump_pipe_config [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [302.744770] [drm:intel_dump_pipe_config [i915]] dp m_n: lanes: 2; gmch_m: 2524349, gmch_n: 4194304, link_m: 105181, link_n: 262144, tu: 64
<7> [302.744873] [drm:intel_dump_pipe_config [i915]] audio: 0, infoframes: 0
<7> [302.744972] [drm:intel_dump_pipe_config [i915]] infoframes enabled: 0x0
<7> [302.745073] [drm:intel_dump_pipe_config [i915]] requested mode:
<7> [302.745086] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [302.745184] [drm:intel_dump_pipe_config [i915]] adjusted mode:
<7> [302.745194] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [302.745305] [drm:intel_dump_pipe_config [i915]] crtc timings: 65000 1024 1048 1184 1344 768 771 777 806, type: 0x40 flags: 0xa
<7> [302.745430] [drm:intel_dump_pipe_config [i915]] port clock: 162000, pipe src size: 1024x768, pixel rate 65000
<7> [302.745600] [drm:intel_dump_pipe_config [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1
<7> [302.745726] [drm:intel_dump_pipe_config [i915]] pch pfit: pos: 0x00000000, size: 0x00000000, disabled, force thru: no
<7> [302.745850] [drm:intel_dump_pipe_config [i915]] ips: 0, double wide: 0
<7> [302.745976] [drm:intel_dump_pipe_config [i915]] dpll_hw_state: ctrl1: 0x5, cfgcr1: 0x0, cfgcr2: 0x0
<7> [302.746093] [drm:intel_dump_pipe_config [i915]] planes on this crtc
<7> [302.746187] [drm:intel_dump_pipe_config [i915]] [PLANE:30:plane 1A] FB:116, fb = 1024x768 format = XR24 little-endian (0x34325258)
<7> [302.746276] [drm:intel_dump_pipe_config [i915]] 	scaler:-1 src 0x0+1024+768 dst 0x0+1024+768
<7> [302.746367] [drm:intel_dump_pipe_config [i915]] [PLANE:37:plane 2A] disabled, scaler_id = -1
<7> [302.746466] [drm:intel_dump_pipe_config [i915]] [PLANE:44:cursor A] disabled, scaler_id = -1
<7> [302.746787] [drm:intel_ddi_update_pipe [i915]] Panel doesn't support DRRS
<7> [302.757203] [drm:verify_connector_state.isra.81 [i915]] [CONNECTOR:85:DP-1]
<7> [302.757263] [drm:intel_atomic_commit_tail [i915]] [CRTC:47:pipe A]
<7> [302.757346] [drm:verify_single_dpll_state.isra.114 [i915]] DPLL 1
<7> [302.757630] [drm:intel_atomic_check [i915]] [CONNECTOR:85:DP-1] Limiting display bpp to 24 instead of EDID bpp 0, requested bpp 36, max platform bpp 36
<7> [302.757678] [drm:intel_dp_compute_config [i915]] DP link computation with max lane count 4 max rate 540000 max bpp 24 pixel clock 65000KHz
<7> [302.757718] [drm:intel_dp_compute_config [i915]] Force DSC en = 0
<7> [302.757758] [drm:intel_dp_compute_config [i915]] DP lane count 2 clock 162000 bpp 24
<7> [302.757796] [drm:intel_dp_compute_config [i915]] DP link rate required 195000 available 324000
<7> [302.757835] [drm:intel_atomic_check [i915]] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [302.757875] [drm:intel_dump_pipe_config [i915]] [CRTC:47:pipe A][fastset]
<7> [302.757914] [drm:intel_dump_pipe_config [i915]] output_types: DP (0x80)
<7> [302.757952] [drm:intel_dump_pipe_config [i915]] output format: RGB
<7> [302.757990] [drm:intel_dump_pipe_config [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [302.758028] [drm:intel_dump_pipe_config [i915]] dp m_n: lanes: 2; gmch_m: 2524349, gmch_n: 4194304, link_m: 105181, link_n: 262144, tu: 64
<7> [302.758066] [drm:intel_dump_pipe_config [i915]] audio: 0, infoframes: 0
<7> [302.758104] [drm:intel_dump_pipe_config [i915]] infoframes enabled: 0x0
<7> [302.758141] [drm:intel_dump_pipe_config [i915]] requested mode:
<7> [302.758145] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [302.758182] [drm:intel_dump_pipe_config [i915]] adjusted mode:
<7> [302.758186] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [302.758224] [drm:intel_dump_pipe_config [i915]] crtc timings: 65000 1024 1048 1184 1344 768 771 777 806, type: 0x40 flags: 0xa
<7> [302.758261] [drm:intel_dump_pipe_config [i915]] port clock: 162000, pipe src size: 1024x768, pixel rate 65000
<7> [302.758299] [drm:intel_dump_pipe_config [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1
<7> [302.758337] [drm:intel_dump_pipe_config [i915]] pch pfit: pos: 0x00000000, size: 0x00000000, disabled, force thru: no
<7> [302.758374] [drm:intel_dump_pipe_config [i915]] ips: 0, double wide: 0
<7> [302.758413] [drm:intel_dump_pipe_config [i915]] dpll_hw_state: ctrl1: 0x5, cfgcr1: 0x0, cfgcr2: 0x0
<7> [302.758450] [drm:intel_dump_pipe_config [i915]] planes on this crtc
<7> [302.758503] [drm:intel_dump_pipe_config [i915]] [PLANE:30:plane 1A] FB:116, fb = 1024x768 format = XR24 little-endian (0x34325258)
<7> [302.758542] [drm:intel_dump_pipe_config [i915]] 	scaler:-1 src 0x0+1024+768 dst 0x0+1024+768
<7> [302.758581] [drm:intel_dump_pipe_config [i915]] [PLANE:37:plane 2A] disabled, scaler_id = -1
<7> [302.758620] [drm:intel_dump_pipe_config [i915]] [PLANE:44:cursor A] disabled, scaler_id = -1
<7> [302.758733] [drm:intel_ddi_update_pipe [i915]] Panel doesn't support DRRS
<7> [302.759519] [drm:_intel_hdcp_enable [i915]] [DP-1:85] HDCP is being enabled...
<7> [308.266069] [drm:intel_hdcp_auth [i915]] KSV list failed to become ready (-110)
<7> [308.266172] [drm:_intel_hdcp_enable [i915]] HDCP Auth failure (-110)
<7> [308.266268] [drm:_intel_hdcp_disable [i915]] [DP-1:85] HDCP is being disabled...
<7> [308.267612] [drm:_intel_hdcp_disable [i915]] HDCP is disabled
<7> [313.777614] [drm:intel_hdcp_auth [i915]] KSV list failed to become ready (-110)
<7> [313.777716] [drm:_intel_hdcp_enable [i915]] HDCP Auth failure (-110)
<7> [313.777813] [drm:_intel_hdcp_disable [i915]] [DP-1:85] HDCP is being disabled...
<7> [313.784530] [drm:_intel_hdcp_disable [i915]] HDCP is disabled
<7> [319.233435] [drm:intel_hdcp_auth [i915]] KSV list failed to become ready (-110)
<7> [319.233589] [drm:_intel_hdcp_enable [i915]] HDCP Auth failure (-110)
<7> [319.233687] [drm:_intel_hdcp_disable [i915]] [DP-1:85] HDCP is being disabled...
<7> [319.235303] [drm:_intel_hdcp_disable [i915]] HDCP is disabled
<7> [319.235406] [drm:_intel_hdcp_enable [i915]] HDCP authentication failed (3 tries/-110)
<7> [319.239676] [drm:verify_connector_state.isra.81 [i915]] [CONNECTOR:85:DP-1]
<7> [319.239817] [drm:intel_atomic_commit_tail [i915]] [CRTC:47:pipe A]
<7> [319.240007] [drm:verify_single_dpll_state.isra.114 [i915]] DPLL 1
<7> [342.335323] [drm:intel_atomic_check [i915]] [CONNECTOR:85:DP-1] Limiting display bpp to 24 instead of EDID bpp 0, requested bpp 36, max platform bpp 36
<7> [342.335450] [drm:intel_dp_compute_config [i915]] DP link computation with max lane count 4 max rate 540000 max bpp 24 pixel clock 65000KHz
<7> [342.335638] [drm:intel_dp_compute_config [i915]] Force DSC en = 0
<7> [342.335756] [drm:intel_dp_compute_config [i915]] DP lane count 2 clock 162000 bpp 24
<7> [342.335892] [drm:intel_dp_compute_config [i915]] DP link rate required 195000 available 324000
<7> [342.336031] [drm:intel_atomic_check [i915]] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [342.336167] [drm:intel_dump_pipe_config [i915]] [CRTC:47:pipe A][fastset]
<7> [342.336297] [drm:intel_dump_pipe_config [i915]] output_types: DP (0x80)
<7> [342.336422] [drm:intel_dump_pipe_config [i915]] output format: RGB
<7> [342.336544] [drm:intel_dump_pipe_config [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [342.336669] [drm:intel_dump_pipe_config [i915]] dp m_n: lanes: 2; gmch_m: 2524349, gmch_n: 4194304, link_m: 105181, link_n: 262144, tu: 64
<7> [342.336763] [drm:intel_dump_pipe_config [i915]] audio: 0, infoframes: 0
<7> [342.336853] [drm:intel_dump_pipe_config [i915]] infoframes enabled: 0x0
<7> [342.336944] [drm:intel_dump_pipe_config [i915]] requested mode:
<7> [342.336957] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [342.337047] [drm:intel_dump_pipe_config [i915]] adjusted mode:
<7> [342.337056] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [342.337146] [drm:intel_dump_pipe_config [i915]] crtc timings: 65000 1024 1048 1184 1344 768 771 777 806, type: 0x40 flags: 0xa
<7> [342.337235] [drm:intel_dump_pipe_config [i915]] port clock: 162000, pipe src size: 1024x768, pixel rate 65000
<7> [342.337324] [drm:intel_dump_pipe_config [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1
<7> [342.337415] [drm:intel_dump_pipe_config [i915]] pch pfit: pos: 0x00000000, size: 0x00000000, disabled, force thru: no
<7> [342.337503] [drm:intel_dump_pipe_config [i915]] ips: 0, double wide: 0
<7> [342.337666] [drm:intel_dump_pipe_config [i915]] dpll_hw_state: ctrl1: 0x5, cfgcr1: 0x0, cfgcr2: 0x0
<7> [342.337794] [drm:intel_dump_pipe_config [i915]] planes on this crtc
<7> [342.337924] [drm:intel_dump_pipe_config [i915]] [PLANE:30:plane 1A] FB:116, fb = 1024x768 format = XR24 little-endian (0x34325258)
<7> [342.338040] [drm:intel_dump_pipe_config [i915]] 	scaler:-1 src 0x0+1024+768 dst 0x0+1024+768
<7> [342.338131] [drm:intel_dump_pipe_config [i915]] [PLANE:37:plane 2A] disabled, scaler_id = -1
<7> [342.338220] [drm:intel_dump_pipe_config [i915]] [PLANE:44:cursor A] disabled, scaler_id = -1
<7> [342.338463] [drm:intel_ddi_update_pipe [i915]] Panel doesn't support DRRS
<7> [342.354777] [drm:verify_connector_state.isra.81 [i915]] [CONNECTOR:85:DP-1]
<7> [342.354834] [drm:intel_atomic_commit_tail [i915]] [CRTC:47:pipe A]
<7> [342.354914] [drm:verify_single_dpll_state.isra.114 [i915]] DPLL 1
<6> [342.418000] [IGT] kms_content_protection: exiting, ret=98
<5> [342.418221] Setting dangerous option reset - tainting kernel
<6> [342.421551] Console: switching to colour frame buffer device 128x48
<6> [342.438301] kms_content_pro (2436) used greatest stack depth: 10064 bytes left
Comment 1 CI Bug Log 2019-05-13 07:43:50 UTC
The CI Bug Log issue associated to this bug has been updated.

### New filters associated

* SKL: igt@kms_content_protection@atomic-dpms - dmesg-fail - *ERROR* Timed out waiting for R0 ready
  - https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_283/fi-skl-lmem/igt@kms_content_protection@atomic-dpms.html
Comment 2 ashutosh.dixit 2019-10-29 21:37:27 UTC
Bug assessment: this issue has been seen once in 1400 runs at this time, last seen 5 months ago. Following the 10x rule it can probably be closed?

Also there seems to be a failure with an identical signature on APL (bug 110889), so it appears that at the minimum these two issues should be combined into one.

Further, this test continues to fail 100% on APL and KBL as can be seen here:

http://gfx-ci.fi.intel.com/tree/drm-tip/igt@kms_content_protection@atomic-dpms.html

But this failure has a different signature. Also I cannot find any filter corresponding to this 100% failure signature.

The story is nearly the same for bug 110889.
Comment 3 Lakshmi 2019-10-30 12:19:54 UTC
This issue occurred only once drmtip_283 (5 months, 3 weeks old).
Closing and archiving this issue.
Comment 4 CI Bug Log 2019-10-30 12:20:11 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.