110668 – [CI][DRMTIP] igt@kms_content_protection@atomic-dpms - dmesg-fail - *ERROR* Timed out waiting for R0 ready

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

Importance:	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)
CC List:	2 users (show)

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

Attachments

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.