2016-01-22 17:55:06,939 - INFO [net.shibboleth.idp.log.LogbackLoggingService:240] - Shibboleth IdP Version 3.2.0
2016-01-22 17:55:06,944 - INFO [net.shibboleth.idp.log.LogbackLoggingService:241] - Java version='1.8.0_66' vendor='Oracle Corporation'
2016-01-22 17:55:06,945 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.LoggingService': Reload time set to: 300000, starting refresh thread
2016-01-22 17:55:06,967 - INFO [org.opensaml.core.config.InitializationService:48] - Initializing OpenSAML using the Java Services API
2016-01-22 17:55:07,687 - INFO [org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed runtime support check, will not be usable: http://www.w3.org/2001/04/xmlenc#ripemd160
2016-01-22 17:55:07,687 - INFO [org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed runtime support check, will not be usable: http://www.w3.org/2001/04/xmldsig-more#hmac-ripemd160
2016-01-22 17:55:07,707 - INFO [org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed runtime support check, will not be usable: http://www.w3.org/2001/04/xmldsig-more#rsa-ripemd160
2016-01-22 17:55:08,085 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.AttributeFilterService': Performing initial load
2016-01-22 17:55:08,085 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeFilterService': Reloading service configuration
2016-01-22 17:55:08,091 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\attribute-filter.xml]
2016-01-22 17:55:08,143 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.AttributeFilterService: startup date [Fri Jan 22 17:55:08 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:08,166 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.AttributeFilterService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeFilterService'
2016-01-22 17:55:08,167 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.AttributeFilterService': Reload complete
2016-01-22 17:55:08,167 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.AttributeFilterService': Reload time set to: 900000, starting refresh thread
2016-01-22 17:55:08,172 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.AttributeResolverService': Performing initial load
2016-01-22 17:55:08,172 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeResolverService': Reloading service configuration
2016-01-22 17:55:08,174 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\attribute-resolver.xml]
2016-01-22 17:55:08,214 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:59] - Parsing configuration for AttributeDefinition plugin with id: lastname
2016-01-22 17:55:08,215 - INFO [net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:54] - Parsing configuration for Dependency with pluginId: users
2016-01-22 17:55:08,233 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:59] - Parsing configuration for DataConnector plugin with id: users
2016-01-22 17:55:08,244 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.AttributeResolverService: startup date [Fri Jan 22 17:55:08 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:08,785 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.AttributeResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeResolverService'
2016-01-22 17:55:08,785 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.AttributeResolverService': Reload complete
2016-01-22 17:55:08,786 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.AttributeResolverService': Reload time set to: 900000, starting refresh thread
2016-01-22 17:55:08,788 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.NameIdentifierGenerationService': Performing initial load
2016-01-22 17:55:08,788 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.NameIdentifierGenerationService': Reloading service configuration
2016-01-22 17:55:08,788 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\saml-nameid.xml]
2016-01-22 17:55:08,805 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\system\conf\saml-nameid-system.xml]
2016-01-22 17:55:08,808 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.NameIdentifierGenerationService: startup date [Fri Jan 22 17:55:08 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:08,872 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.NameIdentifierGenerationService': Completed reload and swapped in latest configuration for service 'shibboleth.NameIdentifierGenerationService'
2016-01-22 17:55:08,873 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.NameIdentifierGenerationService': Reload complete
2016-01-22 17:55:08,873 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.NameIdentifierGenerationService': Reload time set to: 900000, starting refresh thread
2016-01-22 17:55:08,878 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.RelyingPartyResolverService': Performing initial load
2016-01-22 17:55:08,879 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.RelyingPartyResolverService': Reloading service configuration
2016-01-22 17:55:08,881 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\relying-party.xml]
2016-01-22 17:55:08,897 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\credentials.xml]
2016-01-22 17:55:08,908 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\system\conf\relying-party-system.xml]
2016-01-22 17:55:08,920 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Fri Jan 22 17:55:08 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:09,098 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.MetadataResolverService': Performing initial load
2016-01-22 17:55:09,098 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.MetadataResolverService': Reloading service configuration
2016-01-22 17:55:09,115 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\metadata-providers.xml]
2016-01-22 17:55:09,159 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\system\conf\metadata-providers-system.xml]
2016-01-22 17:55:09,165 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup date [Fri Jan 22 17:55:09 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:09,246 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:465] - New metadata successfully loaded for 'C:\opt\shibboleth-idp\metadata\sp-metadata.xml'
2016-01-22 17:55:09,259 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:306] - Next refresh cycle for metadata provider 'C:\opt\shibboleth-idp\metadata\sp-metadata.xml' will occur on '2016-01-22T20:55:09.204Z' ('2016-01-22T20:55:09.204Z' local time)
2016-01-22 17:55:09,268 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:465] - New metadata successfully loaded for 'C:\opt\shibboleth-idp\metadata\sp-metadata2.xml'
2016-01-22 17:55:09,269 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:306] - Next refresh cycle for metadata provider 'C:\opt\shibboleth-idp\metadata\sp-metadata2.xml' will occur on '2016-01-22T20:55:09.263Z' ('2016-01-22T20:55:09.263Z' local time)
2016-01-22 17:55:09,271 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.MetadataResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.MetadataResolverService'
2016-01-22 17:55:09,272 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.MetadataResolverService': Reload complete
2016-01-22 17:55:09,586 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.RelyingPartyResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.RelyingPartyResolverService'
2016-01-22 17:55:09,586 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.RelyingPartyResolverService': Reload complete
2016-01-22 17:55:09,586 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.RelyingPartyResolverService': Reload time set to: 900000, starting refresh thread
2016-01-22 17:55:09,589 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.ReloadableAccessControlService': Performing initial load
2016-01-22 17:55:09,590 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableAccessControlService': Reloading service configuration
2016-01-22 17:55:09,591 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\access-control.xml]
2016-01-22 17:55:09,605 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\system\conf\access-control-system.xml]
2016-01-22 17:55:09,610 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.ReloadableAccessControlService: startup date [Fri Jan 22 17:55:09 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:09,625 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.ReloadableAccessControlService': Completed reload and swapped in latest configuration for service 'shibboleth.ReloadableAccessControlService'
2016-01-22 17:55:09,626 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.ReloadableAccessControlService': Reload complete
2016-01-22 17:55:09,626 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.ReloadableAccessControlService': Reload time set to: 300000, starting refresh thread
2016-01-22 17:55:09,630 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.ReloadableCASServiceRegistry': Performing initial load
2016-01-22 17:55:09,630 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableCASServiceRegistry': Reloading service configuration
2016-01-22 17:55:09,631 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [C:\opt\shibboleth-idp\conf\cas-protocol.xml]
2016-01-22 17:55:09,639 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Fri Jan 22 17:55:09 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:09,645 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.ReloadableCASServiceRegistry': Completed reload and swapped in latest configuration for service 'shibboleth.ReloadableCASServiceRegistry'
2016-01-22 17:55:09,646 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload complete
2016-01-22 17:55:09,646 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload time set to: 900000, starting refresh thread
2016-01-22 17:55:09,788 - INFO [net.shibboleth.ext.spring.context.DelimiterAwareApplicationContext:577] - Refreshing WebApplicationContext for namespace 'idp-servlet': startup date [Fri Jan 22 17:55:09 GMT 2016]; parent: Root WebApplicationContext
2016-01-22 17:55:10,220 - INFO [net.shibboleth.idp.authn.impl.RemoteUserAuthServlet:193] - RemoteUserAuthServlet will process REMOTE_USER, along with attributes [] and headers []
2016-01-22 17:56:07,702 - INFO [net.shibboleth.idp.authn.impl.ValidateUsernamePasswordAgainstJAAS:151] - Profile Action ValidateUsernamePasswordAgainstJAAS: Login by 'yasser' succeeded
2016-01-22 17:56:08,640 - INFO [Shibboleth-Audit.SSO:241] - 20160122T175608Z|urn:oasis:names:tc:SAML:2.0:bindings:HTTP-Redirect|_3c1f9b10fac62270aafda5db46e87e71|https://localhost/shibboleth|http://shibboleth.net/ns/profiles/saml2/sso/browser|https://localhost:8443/idp/shibboleth|urn:oasis:names:tc:SAML:2.0:bindings:HTTP-POST|_b90ad8ec4c0d5fcb93641e58dbd33242|yasser|urn:oasis:names:tc:SAML:2.0:ac:classes:PasswordProtectedTransport|lastname|AAdzZWNyZXQxar8KLG5eaSu/rPU5dAh7t8jfXpUhMsTQyt+l887MFgc1svIw/8YZp0deoRatCg5O3OQIRMBfmfpBAcab5dSJknaHemoGWUZyOpS2UageSKfPDUqN/bmZvp81iF0lvnoiwA==|_e1cbf16605fc6a4e84e42529582755e8|