2017-07-29 14:52:59,034 - INFO [net.shibboleth.idp.log.LogbackLoggingService:240] - Shibboleth IdP Version 3.2.1
2017-07-29 14:52:59,073 - INFO [net.shibboleth.idp.log.LogbackLoggingService:241] - Java version='1.8.0_101' vendor='Oracle Corporation'
2017-07-29 14:52:59,137 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.LoggingService': Reload time set to: 300000, starting refresh thread
2017-07-29 14:53:00,520 - INFO [org.opensaml.core.config.InitializationService:48] - Initializing OpenSAML using the Java Services API
2017-07-29 14:53:21,817 - INFO [org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed runtime support check, will not be usable: http://www.w3.org/2001/04/xmlenc#ripemd160
2017-07-29 14:53:21,846 - 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
2017-07-29 14:53:21,934 - 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
2017-07-29 14:53:25,659 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.AttributeFilterService': Performing initial load
2017-07-29 14:53:25,659 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeFilterService': Reloading service configuration
2017-07-29 14:53:25,720 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-filter.xml]
2017-07-29 14:53:26,084 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.AttributeFilterService: startup date [Sat Jul 29 14:53:26 CEST 2017]; parent: Root WebA$
2017-07-29 14:53:26,301 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.AttributeFilterService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeFilterSer$
2017-07-29 14:53:26,301 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.AttributeFilterService': Reload complete
2017-07-29 14:53:26,310 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.AttributeFilterService': Reload time set to: 900000, starting refresh thread
2017-07-29 14:53:26,335 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.AttributeResolverService': Performing initial load
2017-07-29 14:53:26,336 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeResolverService': Reloading service configuration
2017-07-29 14:53:26,337 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-resolver.xml]
2017-07-29 14:53:26,647 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:59] - Parsing configuration for AttributeDefinition plugin with id: uid
2017-07-29 14:53:26,812 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:59] - Parsing configuration for DataConnector plugin with id: staticAttributes
2017-07-29 14:53:26,815 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:59] - Parsing configuration for DataConnector plugin with id: myLDAP
2017-07-29 14:53:27,876 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.AttributeResolverService: startup date [Sat Jul 29 14:53:27 CEST 2017]; parent: Root We$
2017-07-29 14:53:31,567 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.AttributeResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeResolve$
2017-07-29 14:53:31,568 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.AttributeResolverService': Reload complete
2017-07-29 14:53:31,574 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.AttributeResolverService': Reload time set to: 900000, starting refresh thread
2017-07-29 14:53:31,586 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.NameIdentifierGenerationService': Performing initial load
2017-07-29 14:53:31,590 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.NameIdentifierGenerationService': Reloading service configuration
2017-07-29 14:53:31,593 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/saml-nameid.xml]

2017-07-29 14:53:31,849 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.NameIdentifierGenerationService: startup date [Sat Jul 29 14:53:31 CEST 2017]; parent: $
2017-07-29 14:53:32,416 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.NameIdentifierGenerationService': Completed reload and swapped in latest configuration for service 'shibboleth.NameIdent$
2017-07-29 14:53:32,416 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.NameIdentifierGenerationService': Reload complete
2017-07-29 14:53:32,416 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.NameIdentifierGenerationService': Reload time set to: 900000, starting refresh thread
2017-07-29 14:53:32,427 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.RelyingPartyResolverService': Performing initial load
2017-07-29 14:53:32,427 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.RelyingPartyResolverService': Reloading service configuration
2017-07-29 14:53:32,435 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/relying-party.xml]
2017-07-29 14:53:32,870 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/credentials.xml]
2017-07-29 14:53:32,989 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/relying-party-system.xml]
2017-07-29 14:53:33,146 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Sat Jul 29 14:53:33 CEST 2017]; parent: Root$
2017-07-29 14:53:37,876 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.MetadataResolverService': Performing initial load
2017-07-29 14:53:37,877 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.MetadataResolverService': Reloading service configuration
2017-07-29 14:53:37,881 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/metadata-providers.xml]
2017-07-29 14:53:38,349 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/metadata-providers-system.xml]
2017-07-29 14:53:38,364 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup date [Sat Jul 29 14:53:38 CEST 2017]; parent: Root Web$
2017-07-29 14:53:39,356 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:465] - New metadata successfully loaded for '/opt/shibboleth-idp/metadata/med1meta.xml'
2017-07-29 14:53:39,550 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:306] - Next refresh cycle for metadata provider '/opt/shibboleth-idp/metadata/med1meta.xml' will occur on '2017-07-29T15:53:38.706$
2017-07-29 14:53:43,904 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:465] - New metadata successfully loaded for 'http://www.testshib.org/metadata/testshib-providers.xml'
2017-07-29 14:53:43,905 - INFO [org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:306] - Next refresh cycle for metadata provider 'http://www.testshib.org/metadata/testshib-providers.xml' will occur on '2017-07-2$
2017-07-29 14:53:43,913 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.MetadataResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.MetadataResolverS$
2017-07-29 14:53:43,914 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.MetadataResolverService': Reload complete
2017-07-29 14:53:44,205 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.RelyingPartyResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.RelyingPartyR$
2017-07-29 14:53:44,205 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.RelyingPartyResolverService': Reload complete
2017-07-29 14:53:44,210 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.RelyingPartyResolverService': Reload time set to: 900000, starting refresh thread
2017-07-29 14:53:44,214 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.ReloadableAccessControlService': Performing initial load
2017-07-29 14:53:44,214 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableAccessControlService': Reloading service configuration
2017-07-29 14:53:44,221 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/access-control.xml]
2017-07-29 14:53:44,420 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/access-control-system.xml]
2017-07-29 14:53:44,450 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.ReloadableAccessControlService: startup date [Sat Jul 29 14:53:44 CEST 2017]; parent: R$
2017-07-29 14:53:44,489 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.ReloadableAccessControlService': Completed reload and swapped in latest configuration for service 'shibboleth.Reloadable$
2017-07-29 14:53:44,491 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.ReloadableAccessControlService': Reload complete
2017-07-29 14:53:44,491 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.ReloadableAccessControlService': Reload time set to: 300000, starting refresh thread
2017-07-29 14:53:44,502 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.ReloadableCASServiceRegistry': Performing initial load
2017-07-29 14:53:44,503 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableCASServiceRegistry': Reloading service configuration
2017-07-29 14:53:44,504 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/cas-protocol.xml]
2017-07-29 14:53:44,582 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:577] - Refreshing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Sat Jul 29 14:53:44 CEST 2017]; parent: Roo$
2017-07-29 14:53:44,607 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service 'shibboleth.ReloadableCASServiceRegistry': Completed reload and swapped in latest configuration for service 'shibboleth.ReloadableCA$
2017-07-29 14:53:44,608 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload complete
2017-07-29 14:53:44,608 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload time set to: 900000, starting refresh thread
2017-07-29 14:53:45,303 - INFO [net.shibboleth.ext.spring.context.DelimiterAwareApplicationContext:577] - Refreshing WebApplicationContext for namespace 'idp-servlet': startup date [Sat Jul 29 14:53:45 CEST 2017]; parent: Root WebApplica$
2017-07-29 14:53:53,251 - INFO [net.shibboleth.idp.authn.impl.RemoteUserAuthServlet:193] - RemoteUserAuthServlet will process REMOTE_USER, along with attributes [] and headers []
2017-07-29 15:14:16,753 - INFO [net.shibboleth.idp.authn.impl.ValidateUsernamePasswordAgainstLDAP:139] - Profile Action ValidateUsernamePasswordAgainstLDAP: Login by 'sriebeling' succeeded
2017-07-29 15:14:26,349 - INFO [Shibboleth-Consent-Audit.SSO:241] - 20170729T131426Z|https://sso-med1.imib.rwth-aachen.de|AttributeReleaseConsent|sriebeling|uid||true
2017-07-29 15:14:32,361 - INFO [Shibboleth-Audit.SSO:241] - 20170729T131432Z|urn:oasis:names:tc:SAML:2.0:bindings:HTTP-Redirect|_4181a379601c9b8ccf37a35d92cabd68|https://sso-med1.imib.rwth-aachen.de|http://shibboleth.net/ns/profiles/saml$