IDP Proces Log - 2019-07-26

idp-process.log

2019-07-26 14:24:20,061 - DEBUG [net.shibboleth.idp.attribute.resolver.impl.AttributeResolverImpl:236] - Attribute Resolver 'ShibbolethAttributeResolver': Final resolved attribute collection: [uid, mail, eduPersonScopedAffiliation, givenName, eduPersonPrincipalName, sn, eduPersonPrincipalNameWid]
2019-07-26 14:24:20,073 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:125] - Attribute filtering engine 'ShibbolethAttributeFilter'  Beginning process of filtering the following 7 attributes: [uid, mail, eduPersonScopedAffiliation, givenName, eduPersonPrincipalName, sn, eduPersonPrincipalNameWid]
2019-07-26 14:24:20,073 - DEBUG [net.shibboleth.idp.attribute.filter.AttributeFilterPolicy:128] - Attribute Filter Policy 'bamboohr'  Checking if attribute filter policy is active
2019-07-26 14:24:20,074 - DEBUG [net.shibboleth.idp.attribute.filter.policyrule.filtercontext.impl.AttributeRequesterPolicyRule:54] - Attribute Filter '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PolicyRequirementRule:_82e6059eba1f6ac6d2cea6255c6b1331': Found attribute requester: BambooHR-SAML
2019-07-26 14:24:20,074 - DEBUG [net.shibboleth.idp.attribute.filter.AttributeFilterPolicy:137] - Attribute Filter Policy 'bamboohr'  Policy is not active for this request
2019-07-26 14:24:20,075 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:176] - Attribute filtering engine 'ShibbolethAttributeFilter': no policy permitted release of attribute uid values
2019-07-26 14:24:20,075 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:176] - Attribute filtering engine 'ShibbolethAttributeFilter': no policy permitted release of attribute mail values
2019-07-26 14:24:20,075 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:176] - Attribute filtering engine 'ShibbolethAttributeFilter': no policy permitted release of attribute eduPersonScopedAffiliation values
2019-07-26 14:24:20,076 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:176] - Attribute filtering engine 'ShibbolethAttributeFilter': no policy permitted release of attribute givenName values
2019-07-26 14:24:20,076 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:176] - Attribute filtering engine 'ShibbolethAttributeFilter': no policy permitted release of attribute eduPersonPrincipalName values
2019-07-26 14:24:20,076 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:176] - Attribute filtering engine 'ShibbolethAttributeFilter': no policy permitted release of attribute sn values
2019-07-26 14:24:20,077 - DEBUG [net.shibboleth.idp.attribute.filter.impl.AttributeFilterImpl:176] - Attribute filtering engine 'ShibbolethAttributeFilter': no policy permitted release of attribute eduPersonPrincipalNameWid values
2019-07-26 14:24:20,138 - DEBUG [net.shibboleth.idp.saml.profile.impl.BaseAddAuthenticationStatementToAssertion:170] - Profile Action AddAuthnStatementToAssertion: Attempting to add an AuthenticationStatement to outgoing Assertion
2019-07-26 14:24:20,143 - DEBUG [net.shibboleth.idp.saml.saml2.profile.impl.AddAuthnStatementToAssertion:165] - Profile Action AddAuthnStatementToAssertion: Added AuthenticationStatement to Assertion _bbfbccf75c3696fe7e8a848fc1e62699
2019-07-26 14:24:20,169 - DEBUG [net.shibboleth.idp.saml.profile.impl.BaseAddAttributeStatementToAssertion:229] - Profile Action AddAttributeStatementToAssertion: Attempting to add an AttributeStatement to outgoing Assertion
2019-07-26 14:24:20,170 - DEBUG [net.shibboleth.idp.saml.saml2.profile.impl.AddAttributeStatementToAssertion:137] - Profile Action AddAttributeStatementToAssertion: No attributes available to be encoded, nothing to do
2019-07-26 14:24:20,170 - DEBUG [net.shibboleth.idp.saml.saml2.profile.impl.AddAttributeStatementToAssertion:103] - Profile Action AddAttributeStatementToAssertion: No AttributeStatement was built, nothing to do
2019-07-26 14:24:20,194 - DEBUG [net.shibboleth.idp.saml.nameid.impl.AttributeSourcedSAML2NameIDGenerator:197] - Checking for source attribute mail
2019-07-26 14:24:20,195 - INFO [net.shibboleth.idp.saml.nameid.impl.AttributeSourcedSAML2NameIDGenerator:227] - Attribute sources [mail] did not produce a usable identifier
2019-07-26 14:24:20,195 - WARN [org.opensaml.saml.saml2.profile.impl.AddNameIDToSubjects:337] - Profile Action AddNameIDToSubjects: Request specified use of an unsupportable identifier format: urn:oasis:names:tc:SAML:1.1:nameid-format:emailAddress
2019-07-26 14:24:20,208 - WARN [org.opensaml.profile.action.impl.LogEvent:105] - A non-proceed event occurred while processing the request: InvalidNameIDPolicy
2019-07-26 14:24:20,282 - DEBUG [net.shibboleth.idp.profile.impl.WebFlowMessageHandlerAdaptor:179] - Profile Action WebFlowMessageHandlerAdaptor: Invoking message handler of type 'org.opensaml.messaging.handler.impl.BasicMessageHandlerChain' on OUTBOUND message context
2019-07-26 14:24:20,283 - DEBUG [net.shibboleth.idp.profile.impl.WebFlowMessageHandlerAdaptor:195] - Profile Action WebFlowMessageHandlerAdaptor: Invoking message handler on message context containing a message of type 'org.opensaml.saml.saml2.core.impl.ResponseImpl'
2019-07-26 14:24:20,331 - DEBUG [net.shibboleth.idp.saml.profile.impl.SpringAwareMessageEncoderFactory:100] - Looking up message encoder based on binding URI: urn:oasis:names:tc:SAML:2.0:bindings:HTTP-POST
2019-07-26 14:24:20,358 - DEBUG [net.shibboleth.idp.profile.impl.RecordResponseComplete:89] - Profile Action RecordResponseComplete: Record response complete