Bug 110668 - [CI][DRMTIP] igt@kms_content_protection@atomic-dpms - dmesg-fail - *ERROR* Timed out waiting for R0 ready
Summary: [CI][DRMTIP] igt@kms_content_protection@atomic-dpms - dmesg-fail - *ERROR* Ti...
Status: RESOLVED WORKSFORME
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: DRI git
Hardware: Other All
: medium normal
Assignee: Intel GFX Bugs mailing list
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard: ReadyForDev
Keywords:
Depends on:
Blocks:
 
Reported: 2019-05-13 07:41 UTC by Lakshmi
Modified: 2019-10-30 12:20 UTC (History)
2 users (show)

See Also:
i915 platform: SKL
i915 features: display/Other


Attachments

Note You need to log in before you can comment on or make changes to this bug.
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.