Skip to content

Instantly share code, notes, and snippets.

@moos3
Created July 15, 2014 18:25
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save moos3/54ac18ec658ba103f430 to your computer and use it in GitHub Desktop.
Save moos3/54ac18ec658ba103f430 to your computer and use it in GitHub Desktop.
Jul 15, 2014 2:18:59 PM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
Jul 15, 2014 2:18:59 PM org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8080
Jul 15, 2014 2:19:00 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat6/server/classes], exists: [false], isDirectory: [false], canRead: [false]
Jul 15, 2014 2:19:00 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat6/server], exists: [false], isDirectory: [false], canRead: [false]
Jul 15, 2014 2:19:00 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat6/shared/classes], exists: [false], isDirectory: [false], canRead: [false]
Jul 15, 2014 2:19:00 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat6/shared], exists: [false], isDirectory: [false], canRead: [false]
Jul 15, 2014 2:19:01 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
Jul 15, 2014 2:19:01 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 397 ms
Jul 15, 2014 2:19:01 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Jul 15, 2014 2:19:01 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.35
Jul 15, 2014 2:19:01 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor idp.xml
Jul 15, 2014 2:19:38 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
Jul 15, 2014 2:19:38 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/idp] startup failed due to previous errors
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: The web application [/idp] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/idp] appears to have started a thread named [Timer-1] but has failed to stop it. This is very likely to create a memory leak.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/idp] appears to have started a thread named [com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0] but has failed to stop it. This is very likely to create a memory leak.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/idp] appears to have started a thread named [com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1] but has failed to stop it. This is very likely to create a memory leak.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/idp] appears to have started a thread named [com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2] but has failed to stop it. This is very likely to create a memory leak.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/idp] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/idp] appears to have started a thread named [MySQL Statement Cancellation Timer] but has failed to stop it. This is very likely to create a memory leak.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/idp] appears to have started a thread named [MultiThreadedHttpConnectionManager cleanup] but has failed to stop it. This is very likely to create a memory leak.
Jul 15, 2014 2:19:38 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/idp] created a ThreadLocal with key of type [edu.vt.middleware.ldap.ssl.ThreadLocalTLSSocketFactory.ThreadLocalSslConfig] (value [edu.vt.middleware.ldap.ssl.ThreadLocalTLSSocketFactory$ThreadLocalSslConfig@2ab43408]) and a value of type [edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value [edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer@1cc40f1f]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Jul 15, 2014 2:19:39 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor ROOT.xml
Jul 15, 2014 2:19:39 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Jul 15, 2014 2:19:39 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /127.0.0.1:8009
Jul 15, 2014 2:19:39 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/14 config=null
Jul 15, 2014 2:19:39 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 38011 ms
14:13:46.096 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeResolver
14:13:46.137 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: shibTransient
14:13:46.138 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml1Unspec
14:13:46.139 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Transient
14:13:46.139 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Persistent
14:13:46.146 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myLDAP
14:13:46.149 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myStoredId
14:13:46.227 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for AttributeDefinition plugin with ID: transientId
14:13:46.995 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeResolver service loaded new configuration
14:13:47.002 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeFilterEngine
14:13:47.018 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72] - Parsing configuration for attribute filter policy releaseTransientIdToAnyone
14:13:47.037 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeFilterEngine service loaded new configuration
14:13:47.044 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML1AttributeAuthority
14:13:47.050 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML2AttributeAuthority
14:13:47.057 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.RelyingPartyConfigurationManager
14:13:47.115 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: anonymous
14:13:47.116 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: default
14:13:47.141 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: IdPCredential
14:13:47.210 - INFO [edu.internet2.middleware.shibboleth.common.config.security.StaticExplicitKeySignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for StaticExplicitKeySignature trust engine with id: ICTrust
14:13:47.211 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: MyFederation1Credentials
14:13:47.214 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingSignatureTrustEngineBeanDefinitionParser:59] - Parsing configuration for SignatureChaining trust engine with id: shibboleth.SignatureTrustEngine
14:13:47.215 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeySignatureTrustEngineBeanDefinitionParser:50] - Parsing configuration for MetadataExplicitKeySignature trust engine with id: shibboleth.SignatureMetadataExplicitKeyTrustEngine
14:13:47.216 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXSignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXSignature trust engine with id: shibboleth.SignatureMetadataPKIXTrustEngine
14:13:47.217 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingTrustEngineBeanDefinitionParser:59] - Parsing configuration for Chaining trust engine with id: shibboleth.CredentialTrustEngine
14:13:47.217 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeyTrustEngineBeanDefinitionParser:48] - Parsing configuration for MetadataExplicitKey trust engine with id: shibboleth.CredentialMetadataExplictKeyTrustEngine
14:13:47.218 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXX509CredentialTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXX509Credential trust engine with id: shibboleth.CredentialMetadataPKIXTrustEngine
14:13:47.219 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.ShibbolethSSOSecurityPolicy
14:13:47.225 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1AttributeQuerySecurityPolicy
14:13:47.230 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1ArtifactResolutionSecurityPolicy
14:13:47.231 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SSOSecurityPolicy
14:13:47.235 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2AttributeQuerySecurityPolicy
14:13:47.237 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2ArtifactResolutionSecurityPolicy
14:13:47.239 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SLOSecurityPolicy
14:13:47.307 - ERROR [org.opensaml.saml2.metadata.provider.AbstractMetadataProvider:411] - Metadata provider failed to properly initialize, fail-fast=true, halting
org.opensaml.saml2.metadata.provider.MetadataProviderException: org.opensaml.saml2.metadata.provider.MetadataProviderException: Metadata file '/opt/shibboleth-idp/metadata/TrustedPartners-metadata.xml' does not exist
at org.opensaml.saml2.metadata.provider.AbstractReloadingMetadataProvider.refresh(AbstractReloadingMetadataProvider.java:266) ~[opensaml-2.6.0.jar:na]
at org.opensaml.saml2.metadata.provider.AbstractReloadingMetadataProvider.doInitialization(AbstractReloadingMetadataProvider.java:236) ~[opensaml-2.6.0.jar:na]
at org.opensaml.saml2.metadata.provider.AbstractMetadataProvider.initialize(AbstractMetadataProvider.java:407) ~[opensaml-2.6.0.jar:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_31]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.6.0_31]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.6.0_31]
at java.lang.reflect.Method.invoke(Method.java:622) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1414) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1375) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1335) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:473) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at java.security.AccessController.doPrivileged(Native Method) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:728) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:380) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at edu.internet2.middleware.shibboleth.common.config.BaseService.loadContext(BaseService.java:171) ~[shibboleth-common-1.4.0.jar:na]
at edu.internet2.middleware.shibboleth.common.config.BaseReloadableService.initialize(BaseReloadableService.java:148) ~[shibboleth-common-1.4.0.jar:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_31]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.6.0_31]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.6.0_31]
at java.lang.reflect.Method.invoke(Method.java:622) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1414) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1375) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1335) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:473) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at java.security.AccessController.doPrivileged(Native Method) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:728) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:380) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:255) ~[spring-web-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:199) ~[spring-web-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:45) ~[spring-web-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4206) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardContext.start(StandardContext.java:4705) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:799) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:779) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:601) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:675) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:601) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:502) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1317) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:324) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:142) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1065) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardHost.start(StandardHost.java:840) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1057) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:463) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardService.start(StandardService.java:525) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardServer.start(StandardServer.java:754) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.Catalina.start(Catalina.java:595) ~[catalina-6.0.35.jar:6.0.35]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_31]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.6.0_31]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.6.0_31]
at java.lang.reflect.Method.invoke(Method.java:622) ~[na:1.6.0_31]
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289) ~[bootstrap.jar:6.0.35]
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414) ~[bootstrap.jar:6.0.35]
Caused by: org.opensaml.saml2.metadata.provider.MetadataProviderException: Metadata file '/opt/shibboleth-idp/metadata/TrustedPartners-metadata.xml' does not exist
at org.opensaml.saml2.metadata.provider.FilesystemMetadataProvider.validateMetadataFile(FilesystemMetadataProvider.java:149) ~[opensaml-2.6.0.jar:na]
at org.opensaml.saml2.metadata.provider.FilesystemMetadataProvider.fetchMetadata(FilesystemMetadataProvider.java:126) ~[opensaml-2.6.0.jar:na]
at org.opensaml.saml2.metadata.provider.AbstractReloadingMetadataProvider.refresh(AbstractReloadingMetadataProvider.java:255) ~[opensaml-2.6.0.jar:na]
... 69 common frames omitted
14:13:47.308 - ERROR [edu.internet2.middleware.shibboleth.common.config.BaseService:188] - Configuration was not loaded for shibboleth.RelyingPartyConfigurationManager service, error creating components. The root cause of this error was: org.opensaml.saml2.metadata.provider.MetadataProviderException: Metadata file '/opt/shibboleth-idp/metadata/TrustedPartners-metadata.xml' does not exist
14:14:54.407 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeResolver
14:14:54.473 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: shibTransient
14:14:54.474 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml1Unspec
14:14:54.474 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Transient
14:14:54.475 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Persistent
14:14:54.482 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myLDAP
14:14:54.485 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myStoredId
14:14:54.562 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for AttributeDefinition plugin with ID: transientId
14:14:55.333 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeResolver service loaded new configuration
14:14:55.340 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeFilterEngine
14:14:55.356 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72] - Parsing configuration for attribute filter policy releaseTransientIdToAnyone
14:14:55.376 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeFilterEngine service loaded new configuration
14:14:55.383 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML1AttributeAuthority
14:14:55.388 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML2AttributeAuthority
14:14:55.395 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.RelyingPartyConfigurationManager
14:14:55.450 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: anonymous
14:14:55.451 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: default
14:14:55.476 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: IdPCredential
14:14:55.553 - INFO [edu.internet2.middleware.shibboleth.common.config.security.StaticExplicitKeySignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for StaticExplicitKeySignature trust engine with id: ICTrust
14:14:55.554 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: MyFederation1Credentials
14:14:55.556 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingSignatureTrustEngineBeanDefinitionParser:59] - Parsing configuration for SignatureChaining trust engine with id: shibboleth.SignatureTrustEngine
14:14:55.557 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeySignatureTrustEngineBeanDefinitionParser:50] - Parsing configuration for MetadataExplicitKeySignature trust engine with id: shibboleth.SignatureMetadataExplicitKeyTrustEngine
14:14:55.558 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXSignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXSignature trust engine with id: shibboleth.SignatureMetadataPKIXTrustEngine
14:14:55.559 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingTrustEngineBeanDefinitionParser:59] - Parsing configuration for Chaining trust engine with id: shibboleth.CredentialTrustEngine
14:14:55.559 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeyTrustEngineBeanDefinitionParser:48] - Parsing configuration for MetadataExplicitKey trust engine with id: shibboleth.CredentialMetadataExplictKeyTrustEngine
14:14:55.560 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXX509CredentialTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXX509Credential trust engine with id: shibboleth.CredentialMetadataPKIXTrustEngine
14:14:55.561 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.ShibbolethSSOSecurityPolicy
14:14:55.566 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1AttributeQuerySecurityPolicy
14:14:55.571 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1ArtifactResolutionSecurityPolicy
14:14:55.572 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SSOSecurityPolicy
14:14:55.575 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2AttributeQuerySecurityPolicy
14:14:55.577 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2ArtifactResolutionSecurityPolicy
14:14:55.579 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SLOSecurityPolicy
14:14:55.851 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.RelyingPartyConfigurationManager service loaded new configuration
14:14:55.858 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.HandlerManager
14:14:55.874 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.JSPErrorHandlerBeanDefinitionParser:46] - Parsing configuration for JSP error handler.
14:14:55.874 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: Status
14:14:55.875 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAMLMetadata
14:14:55.895 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: ShibbolethSSO
14:14:55.899 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML1AttributeQuery
14:14:55.900 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML1ArtifactResolution
14:14:55.902 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SSO
14:14:55.903 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SSO
14:14:55.903 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SSO
14:14:55.903 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SSO
14:14:55.905 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2ECP
14:14:55.906 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SLO
14:14:55.906 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SLO
14:14:55.907 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SLO
14:14:55.907 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SLO
14:14:55.908 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2SLO
14:14:55.909 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2AttributeQuery
14:14:55.910 - INFO [edu.internet2.middleware.shibboleth.common.config.profile.AbstractRequestURIMappedProfileHandlerBeanDefinitionParser:43] - Parsing configuration for profile handler: SAML2ArtifactResolution
14:14:56.058 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.HandlerManager service loaded new configuration
14:15:44.658 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeResolver
14:15:44.724 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: shibTransient
14:15:44.725 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml1Unspec
14:15:44.725 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Transient
14:15:44.726 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Persistent
14:15:44.732 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myLDAP
14:15:44.735 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myStoredId
14:15:44.812 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for AttributeDefinition plugin with ID: transientId
14:15:45.580 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeResolver service loaded new configuration
14:15:45.587 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeFilterEngine
14:15:45.603 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72] - Parsing configuration for attribute filter policy releaseTransientIdToAnyone
14:15:45.622 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeFilterEngine service loaded new configuration
14:15:45.629 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML1AttributeAuthority
14:15:45.634 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML2AttributeAuthority
14:15:45.641 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.RelyingPartyConfigurationManager
14:15:45.722 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: anonymous
14:15:45.722 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: default
14:15:45.748 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: IdPCredential
14:15:45.820 - INFO [edu.internet2.middleware.shibboleth.common.config.security.StaticExplicitKeySignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for StaticExplicitKeySignature trust engine with id: ICTrust
14:15:45.821 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: MyFederation1Credentials
14:15:45.824 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingSignatureTrustEngineBeanDefinitionParser:59] - Parsing configuration for SignatureChaining trust engine with id: shibboleth.SignatureTrustEngine
14:15:45.824 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeySignatureTrustEngineBeanDefinitionParser:50] - Parsing configuration for MetadataExplicitKeySignature trust engine with id: shibboleth.SignatureMetadataExplicitKeyTrustEngine
14:15:45.825 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXSignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXSignature trust engine with id: shibboleth.SignatureMetadataPKIXTrustEngine
14:15:45.826 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingTrustEngineBeanDefinitionParser:59] - Parsing configuration for Chaining trust engine with id: shibboleth.CredentialTrustEngine
14:15:45.827 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeyTrustEngineBeanDefinitionParser:48] - Parsing configuration for MetadataExplicitKey trust engine with id: shibboleth.CredentialMetadataExplictKeyTrustEngine
14:15:45.828 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXX509CredentialTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXX509Credential trust engine with id: shibboleth.CredentialMetadataPKIXTrustEngine
14:15:45.829 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.ShibbolethSSOSecurityPolicy
14:15:45.835 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1AttributeQuerySecurityPolicy
14:15:45.840 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1ArtifactResolutionSecurityPolicy
14:15:45.842 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SSOSecurityPolicy
14:15:45.845 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2AttributeQuerySecurityPolicy
14:15:45.847 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2ArtifactResolutionSecurityPolicy
14:15:45.849 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SLOSecurityPolicy
14:16:24.200 - ERROR [edu.internet2.middleware.shibboleth.common.config.BaseService:188] - Configuration was not loaded for shibboleth.RelyingPartyConfigurationManager service, error creating components. The root cause of this error was: java.lang.NullPointerException: null
14:19:02.486 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.LogbackLogging' to allow for resolving potential circular references
14:19:02.489 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.LogbackLogging'
14:19:02.490 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:02.490 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.OpensamlConfig'
14:19:02.490 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.OpensamlConfig'
14:19:02.491 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibMetadataExtensions'
14:19:02.494 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibMetadataExtensions'
14:19:02.496 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.OpensamlConfig' to allow for resolving potential circular references
14:19:02.497 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:02.497 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.ParserPool'
14:19:02.497 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.ParserPool'
14:19:02.499 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.ParserPool' to allow for resolving potential circular references
14:19:02.506 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.XercesSecurityManager'
14:19:02.506 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.XercesSecurityManager'
14:19:02.507 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'util:constant#62a49a92'
14:19:02.510 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'util:constant#62a49a92'
14:19:02.510 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'util:constant#62a49a92'
14:19:02.511 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'util:constant#24a019d6'
14:19:02.511 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'util:constant#24a019d6'
14:19:02.511 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'util:constant#24a019d6'
14:19:02.512 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'shibboleth.ParserPool'
14:19:02.514 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.ParserPool'
14:19:02.516 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'shibboleth.OpensamlConfig'
14:19:03.683 - DEBUG [edu.internet2.middleware.shibboleth.common.config.OpensamlConfigBean:80] - Loading OpenSAML configuration file: jar:file:/var/cache/tomcat6/Catalina/localhost/idp/WEB-INF/lib/shibboleth-common-1.4.0.jar!/shibboleth-saml-ext-config.xml
14:19:03.692 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.OpensamlConfig'
14:19:03.692 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.692 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.IdGenerator'
14:19:03.693 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.IdGenerator'
14:19:03.693 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.IdGenerator' to allow for resolving potential circular references
14:19:03.693 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.IdGenerator'
14:19:03.694 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.694 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.VelocityEngine'
14:19:03.694 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.VelocityEngine'
14:19:03.695 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.VelocityEngine' to allow for resolving potential circular references
14:19:03.700 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'shibboleth.VelocityEngine'
14:19:03.771 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.VelocityEngine'
14:19:03.772 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.772 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.TemplateEngine'
14:19:03.772 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.TemplateEngine'
14:19:03.772 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.VelocityEngine'
14:19:03.774 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.TemplateEngine' to allow for resolving potential circular references
14:19:03.774 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.TemplateEngine'
14:19:03.774 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:03.774 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.774 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.StorageService'
14:19:03.775 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.StorageService'
14:19:03.776 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.StorageService' to allow for resolving potential circular references
14:19:03.776 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.StorageService'
14:19:03.776 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.776 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.StorageServiceSweeper'
14:19:03.776 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.StorageServiceSweeper'
14:19:03.777 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.TaskTimer'
14:19:03.777 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:03.778 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.StorageServiceSweeper' to allow for resolving potential circular references
14:19:03.778 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.StorageServiceSweeper'
14:19:03.778 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.778 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.SessionManager'
14:19:03.778 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SessionManager'
14:19:03.779 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:03.780 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.SessionManager' to allow for resolving potential circular references
14:19:03.780 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SessionManager'
14:19:03.780 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.780 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.ArtifactMap'
14:19:03.781 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.ArtifactMap'
14:19:03.781 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:03.782 - DEBUG [org.springframework.beans.BeanUtils:386] - No property editor [org.opensaml.common.binding.artifact.SAMLArtifactMap$SAMLArtifactMapEntryFactoryEditor] found for type org.opensaml.common.binding.artifact.SAMLArtifactMap$SAMLArtifactMapEntryFactory according to 'Editor' suffix convention
14:19:03.785 - DEBUG [org.springframework.beans.BeanUtils:386] - No property editor [org.opensaml.xml.parse.ParserPoolEditor] found for type org.opensaml.xml.parse.ParserPool according to 'Editor' suffix convention
14:19:03.787 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.ArtifactMap' to allow for resolving potential circular references
14:19:03.788 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.ArtifactMap'
14:19:03.788 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.788 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.ReplayCache'
14:19:03.788 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.ReplayCache'
14:19:03.788 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:03.789 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.ReplayCache' to allow for resolving potential circular references
14:19:03.789 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.ReplayCache'
14:19:03.790 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.MessageDecoders'
14:19:03.790 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.MessageDecoders'
14:19:03.790 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.MessageDecoders' to allow for resolving potential circular references
14:19:03.790 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpPostSimpleSignDecoder'
14:19:03.793 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:03.795 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpPostSimpleSignDecoder'
14:19:03.796 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpPostDecoder'
14:19:03.796 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:03.796 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpPostDecoder'
14:19:03.797 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpRedirectDecoder'
14:19:03.797 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:03.798 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpRedirectDecoder'
14:19:03.798 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpSoap11Decoder'
14:19:03.799 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:03.800 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpSoap11Decoder'
14:19:03.800 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML1HttpPostDecoder'
14:19:03.801 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ArtifactMap'
14:19:03.801 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:03.802 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML1HttpPostDecoder'
14:19:03.802 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML1HttpSoap11Decoder'
14:19:03.803 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ArtifactMap'
14:19:03.803 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:03.804 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML1HttpSoap11Decoder'
14:19:03.804 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.ShibbolethSSODecoder'
14:19:03.805 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.ShibbolethSSODecoder'
14:19:03.806 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.UnsolicitedSSODecoder'
14:19:03.806 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.IdGenerator'
14:19:03.807 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.UnsolicitedSSODecoder'
14:19:03.811 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'shibboleth.MessageDecoders'
14:19:03.811 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.MessageDecoders'
14:19:03.811 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.MessageEncoders'
14:19:03.811 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.MessageEncoders'
14:19:03.812 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.MessageEncoders' to allow for resolving potential circular references
14:19:03.812 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpPostSimpleSignEncoder'
14:19:03.814 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.VelocityEngine'
14:19:03.816 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpPostSimpleSignEncoder'
14:19:03.816 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpPostEncoder'
14:19:03.816 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.VelocityEngine'
14:19:03.816 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpPostEncoder'
14:19:03.817 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpRedirectEncoder'
14:19:03.818 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpRedirectEncoder'
14:19:03.818 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HTTPArtifactEncoder'
14:19:03.819 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ArtifactMap'
14:19:03.819 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.VelocityEngine'
14:19:03.819 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HTTPArtifactEncoder'
14:19:03.820 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2HttpSoap11Encoder'
14:19:03.820 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2HttpSoap11Encoder'
14:19:03.856 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML1HttpPostEncoder'
14:19:03.857 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.VelocityEngine'
14:19:03.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML1HttpPostEncoder'
14:19:03.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML1HttpArtifactEncoder'
14:19:03.859 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ArtifactMap'
14:19:03.859 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML1HttpArtifactEncoder'
14:19:03.859 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML1HttpSoap11EncoderBuilder'
14:19:03.860 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML1HttpSoap11EncoderBuilder'
14:19:03.860 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'shibboleth.MessageEncoders'
14:19:03.861 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.MessageEncoders'
14:19:03.861 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.861 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.ServletAttributeExporter'
14:19:03.861 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.ServletAttributeExporter'
14:19:03.862 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.ServletAttributeExporter' to allow for resolving potential circular references
14:19:03.863 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'shibboleth.ServletAttributeExporter'
14:19:03.863 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.SessionManager'
14:19:03.863 - DEBUG [edu.internet2.middleware.shibboleth.common.config.service.ServletContextAttributeExporter:85] - Exporting bean shibboleth.SessionManager to servlet context.
14:19:03.863 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.ServletAttributeExporter'
14:19:03.864 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-resolver.xml'
14:19:03.864 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-resolver.xml'
14:19:03.864 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-resolver.xml' to allow for resolving potential circular references
14:19:03.865 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-resolver.xml'
14:19:03.865 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:03.865 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.AttributeResolver'
14:19:03.865 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.AttributeResolver'
14:19:03.867 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.AttributeResolver' to allow for resolving potential circular references
14:19:03.868 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)'
14:19:03.868 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)'
14:19:03.876 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'shibboleth.AttributeResolver'
14:19:03.877 - DEBUG [edu.internet2.middleware.shibboleth.common.config.BaseReloadableService:136] - Initializing shibboleth.AttributeResolver service with resources: [/opt/shibboleth-idp/conf/attribute-resolver.xml]
14:19:03.877 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeResolver
14:19:03.879 - INFO [org.springframework.beans.factory.xml.XmlBeanDefinitionReader:323] - Loading XML bean definitions from /opt/shibboleth-idp/conf/attribute-resolver.xml
14:19:03.910 - DEBUG [org.springframework.beans.factory.xml.DefaultBeanDefinitionDocumentReader:86] - Loading bean definitions
14:19:03.912 - DEBUG [org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver:153] - Loaded mappings [{http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, urn:mace:shibboleth:2.0:resolver:ad=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.AttributeDefinitionNamespaceHandler, urn:mace:shibboleth:2.0:resolver:dc=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.DataConnectorNamespaceHandler, urn:mace:shibboleth:2.0:resolver:pc=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.principalConnector.PrincipalConnectorNamespaceHandler, http://www.springframework.org/schema/lang=org.springframework.scripting.config.LangNamespaceHandler, urn:mace:shibboleth:2.0:idp:profile-handler=edu.internet2.middleware.shibboleth.idp.config.profile.ProfileHandlerNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, urn:mace:shibboleth:2.0:services=edu.internet2.middleware.shibboleth.common.config.service.ServiceNamespaceHandler, urn:mace:shibboleth:2.0:resource=edu.internet2.middleware.shibboleth.common.config.resource.ResourceNamespaceHandler, urn:mace:shibboleth:2.0:attribute:encoder=edu.internet2.middleware.shibboleth.common.config.attribute.encoding.AttributeEncoderNamespaceHandler, urn:mace:shibboleth:2.0:relying-party:saml=edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAMLRelyingPartyNamespaceHandler, urn:mace:shibboleth:2.0:attribute:authority=edu.internet2.middleware.shibboleth.common.config.attribute.authority.AttributeAuthorityNamespaceHandler, urn:mace:shibboleth:2.0:security=edu.internet2.middleware.shibboleth.common.config.security.SecurityNamespaceHandler, urn:mace:shibboleth:2.0:relying-party=edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyNamespaceHandler, urn:mace:shibboleth:2.0:afp=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler, urn:mace:shibboleth:2.0:resolver=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AttributeResolverNamespaceHandler, urn:mace:shibboleth:2.0:metadata=edu.internet2.middleware.shibboleth.common.config.metadata.MetadataNamespaceHandler, urn:mace:shibboleth:2.0:afp:mf:basic=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.match.basic.BasicMatchFunctorNamespaceHandler, urn:mace:shibboleth:2.0:security:saml=edu.internet2.middleware.shibboleth.common.config.security.saml.SAMLSecurityNamespaceHandler, urn:mace:shibboleth:2.0:afp:mf:saml=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.match.saml.SAMLMatchFunctorNamespaceHandler}]
14:19:03.917 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: shibTransient
14:19:03.917 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65] - Dependencies for plugin shibTransient: none
14:19:03.917 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml1Unspec
14:19:03.918 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65] - Dependencies for plugin saml1Unspec: none
14:19:03.918 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Transient
14:19:03.918 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65] - Dependencies for plugin saml2Transient: none
14:19:03.919 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for PrincipalConnector plugin with ID: saml2Persistent
14:19:03.919 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65] - Dependencies for plugin saml2Persistent: none
14:19:03.926 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myLDAP
14:19:03.926 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65] - Dependencies for plugin myLDAP: none
14:19:03.927 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:96] - Data connector myLDAP LDAP URL: ldaps://ldap10.symplicity.com
14:19:03.927 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:103] - Data connector myLDAP connection strategy: ACTIVE_PASSIVE
14:19:03.928 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:108] - Data connector myLDAP base DN: ou=people,dc=symplicity,dc=com
14:19:03.928 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:116] - Data connector myLDAP authentication type: SIMPLE
14:19:03.929 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:120] - Data connector myLDAP principal: cn=Directory Manager,dc=symplicity,dc=com
14:19:03.929 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:133] - Data connector myLDAP LDAP filter template: (uid=$requestContext.principalName)
14:19:03.930 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:140] - Data connector myLDAP search scope: SUBTREE
14:19:03.930 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:184] - Data connector myLDAP use startTLS: false
14:19:03.930 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:206] - Data connector myLDAP search timeout: 3000ms
14:19:03.930 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:213] - Data connector myLDAP max search result size: 1
14:19:03.931 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:220] - Data connector myLDAP merge results: false
14:19:03.931 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:228] - Data connector myLDAP no results is error: false
14:19:03.931 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:237] - Data connector myLDAP will lower case attribute IDs: false
14:19:03.931 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:257] - Data connector myLDAP is pooling connections: false
14:19:03.933 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for DataConnector plugin with ID: myStoredId
14:19:03.934 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62] - Dependencies for plugin myStoredId: [myLDAP]
14:19:04.006 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.StoredIDDataConnectorBeanDefinitionParser:237] - Created application managed data source for data connector myStoredId
14:19:04.006 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.StoredIDDataConnectorBeanDefinitionParser:72] - Data connector 5000 SQL query timeout: {}ms
14:19:04.007 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.StoredIDDataConnectorBeanDefinitionParser:81] - Data connector myStoredId generated attribute ID: presistentID
14:19:04.007 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.StoredIDDataConnectorBeanDefinitionParser:85] - Data connector myStoredId source attribute ID: uid
14:19:04.007 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.StoredIDDataConnectorBeanDefinitionParser:89] - Data connector myStoredId salt: DWtycyXZasL+OcyGGOpsIg/WTrH9vz4fw4HVR0IAS2FepPqB
14:19:04.015 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55] - Parsing configuration for AttributeDefinition plugin with ID: transientId
14:19:04.015 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65] - Dependencies for plugin transientId: none
14:19:04.015 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58] - Setting source attribute ID for attribute definition transientId to:
14:19:04.015 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82] - Attribute definition transientId produces attributes that are only dependencies: false
14:19:04.023 - INFO [org.springframework.context.support.GenericApplicationContext:411] - Refreshing org.springframework.context.support.GenericApplicationContext@247ec34e: display name [ApplicationContext:shibboleth.AttributeResolver]; startup date [Tue Jul 15 14:19:04 EDT 2014]; parent: org.springframework.web.context.support.XmlWebApplicationContext@171183d8
14:19:04.023 - INFO [org.springframework.context.support.GenericApplicationContext:426] - Bean factory for application context [org.springframework.context.support.GenericApplicationContext@247ec34e]: org.springframework.beans.factory.support.DefaultListableBeanFactory@79bdbc73
14:19:04.024 - DEBUG [org.springframework.context.support.GenericApplicationContext:430] - 9 beans defined in org.springframework.context.support.GenericApplicationContext@247ec34e: display name [ApplicationContext:shibboleth.AttributeResolver]; startup date [Tue Jul 15 14:19:04 EDT 2014]; parent: org.springframework.web.context.support.XmlWebApplicationContext@171183d8
14:19:04.026 - DEBUG [org.springframework.context.support.GenericApplicationContext:648] - Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@6075ef17]
14:19:04.026 - DEBUG [org.springframework.context.support.GenericApplicationContext:672] - Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default [org.springframework.context.event.SimpleApplicationEventMulticaster@5ea4007a]
14:19:04.027 - INFO [org.springframework.beans.factory.support.DefaultListableBeanFactory:414] - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@79bdbc73: defining beans [shibTransient,saml1Unspec,saml2Transient,saml2Persistent,myLDAP,myStoredId,edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML1StringNameIdentifierEncoder#0,edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML2StringNameIDEncoder#0,transientId]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@6ece41ee
14:19:04.028 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibTransient'
14:19:04.028 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibTransient'
14:19:04.029 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibTransient' to allow for resolving potential circular references
14:19:04.035 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:04.036 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'shibTransient'
14:19:04.036 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibTransient'
14:19:04.037 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'saml1Unspec'
14:19:04.037 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'saml1Unspec'
14:19:04.037 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'saml1Unspec' to allow for resolving potential circular references
14:19:04.037 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:04.038 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'saml1Unspec'
14:19:04.038 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'saml1Unspec'
14:19:04.038 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'saml2Transient'
14:19:04.038 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'saml2Transient'
14:19:04.039 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'saml2Transient' to allow for resolving potential circular references
14:19:04.039 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:04.039 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'saml2Transient'
14:19:04.039 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'saml2Transient'
14:19:04.040 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'saml2Persistent'
14:19:04.040 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'saml2Persistent'
14:19:04.041 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'saml2Persistent' to allow for resolving potential circular references
14:19:04.047 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'myStoredId'
14:19:04.047 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'myStoredId'
14:19:04.047 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'myStoredId' to allow for resolving potential circular references
14:19:04.053 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'myStoredId'
14:19:04.055 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'myStoredId'
14:19:04.055 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'saml2Persistent'
14:19:04.056 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'saml2Persistent'
14:19:04.056 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'myLDAP'
14:19:04.056 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'myLDAP'
14:19:04.067 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'myLDAP' to allow for resolving potential circular references
14:19:04.075 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.TemplateEngine'
14:19:04.076 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'myLDAP'
14:19:04.082 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:74] - Bind with the following parameters:
14:19:04.082 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:75] - authtype = simple
14:19:04.083 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:76] - dn = cn=Directory Manager,dc=symplicity,dc=com
14:19:04.083 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:83] - credential = <suppressed>
14:19:04.214 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:128] - Set hostname verifier for ldaps
14:19:04.275 - DEBUG [edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking checkServerTrusted for sun.security.ssl.X509TrustManagerImpl@299a3d4
14:19:04.374 - DEBUG [edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking checkServerTrusted for edu.vt.middleware.ldap.ssl.HostnameVerifyingTrustManager@2581f53b
14:19:04.375 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:122] - Verify with the following parameters:
14:19:04.375 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:123] - hostname = ldap10.symplicity.com
14:19:04.375 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:124] - cert = CN=*.symplicity.com, O=Symplicity Corporation, L=Arlington, ST=Virginia, C=US
14:19:04.377 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:197] - verifyDNS using subjectAltNames = [*.symplicity.com, symplicity.com]
14:19:04.377 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:205] - verifyDNS found hostname match: *.symplicity.com
14:19:04.430 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'myLDAP'
14:19:04.430 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'myStoredId'
14:19:04.430 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML1StringNameIdentifierEncoder#0'
14:19:04.431 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML1StringNameIdentifierEncoder#0'
14:19:04.431 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML1StringNameIdentifierEncoder#0' to allow for resolving potential circular references
14:19:04.436 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML1StringNameIdentifierEncoder#0'
14:19:04.436 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML2StringNameIDEncoder#0'
14:19:04.436 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML2StringNameIDEncoder#0'
14:19:04.436 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML2StringNameIDEncoder#0' to allow for resolving potential circular references
14:19:04.440 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'edu.internet2.middleware.shibboleth.common.attribute.encoding.provider.SAML2StringNameIDEncoder#0'
14:19:04.440 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'transientId'
14:19:04.440 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'transientId'
14:19:04.442 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'transientId' to allow for resolving potential circular references
14:19:04.448 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)'
14:19:04.449 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)'
14:19:04.449 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#1'
14:19:04.449 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#1'
14:19:04.450 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.StorageService'
14:19:04.450 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name 'transientId'
14:19:04.523 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'transientId'
14:19:04.524 - DEBUG [edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver:506] - Loading 2 data connectors
14:19:04.524 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'myLDAP'
14:19:04.525 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'myStoredId'
14:19:04.525 - DEBUG [edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver:516] - Loading 1 attribute definitions
14:19:04.525 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'transientId'
14:19:04.525 - DEBUG [edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver:526] - Loading 4 principal connectors
14:19:04.526 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibTransient'
14:19:04.526 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'saml1Unspec'
14:19:04.526 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'saml2Transient'
14:19:04.526 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'saml2Persistent'
14:19:04.527 - DEBUG [edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.dataConnector.StoredIDStore:226] - Selecting persistent ID entry based on prepared sql statement: SELECT * FROM shibpid WHERE persistentId = ? AND deactivationDate IS NULL
14:19:04.749 - DEBUG [edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.dataConnector.StoredIDStore:233] - Setting prepared statement parameter 1: 1
14:19:04.756 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:74] - Bind with the following parameters:
14:19:04.756 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:75] - authtype = simple
14:19:04.757 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:76] - dn = cn=Directory Manager,dc=symplicity,dc=com
14:19:04.757 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:83] - credential = <suppressed>
14:19:04.763 - DEBUG [edu.vt.middleware.ldap.handler.DefaultConnectionHandler:128] - Set hostname verifier for ldaps
14:19:04.765 - DEBUG [edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking checkServerTrusted for sun.security.ssl.X509TrustManagerImpl@e5a209e
14:19:04.768 - DEBUG [edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking checkServerTrusted for edu.vt.middleware.ldap.ssl.HostnameVerifyingTrustManager@36af7db6
14:19:04.768 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:122] - Verify with the following parameters:
14:19:04.768 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:123] - hostname = ldap10.symplicity.com
14:19:04.769 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:124] - cert = CN=*.symplicity.com, O=Symplicity Corporation, L=Arlington, ST=Virginia, C=US
14:19:04.769 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:197] - verifyDNS using subjectAltNames = [*.symplicity.com, symplicity.com]
14:19:04.769 - DEBUG [edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:205] - verifyDNS found hostname match: *.symplicity.com
14:19:04.812 - DEBUG [edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.dataConnector.StoredIDStore:226] - Selecting persistent ID entry based on prepared sql statement: SELECT * FROM shibpid WHERE persistentId = ?
14:19:04.813 - DEBUG [edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.dataConnector.StoredIDStore:233] - Setting prepared statement parameter 1: test
14:19:04.815 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeResolver service loaded new configuration
14:19:04.815 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.AttributeResolver'
14:19:04.815 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-filter.xml'
14:19:04.815 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-filter.xml'
14:19:04.816 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-filter.xml' to allow for resolving potential circular references
14:19:04.816 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-filter.xml'
14:19:04.816 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:04.817 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.AttributeFilterEngine'
14:19:04.817 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.AttributeFilterEngine'
14:19:04.817 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.AttributeFilterEngine' to allow for resolving potential circular references
14:19:04.818 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#1'
14:19:04.818 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#1'
14:19:04.823 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'shibboleth.AttributeFilterEngine'
14:19:04.824 - DEBUG [edu.internet2.middleware.shibboleth.common.config.BaseReloadableService:136] - Initializing shibboleth.AttributeFilterEngine service with resources: [/opt/shibboleth-idp/conf/attribute-filter.xml]
14:19:04.824 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.AttributeFilterEngine
14:19:04.824 - INFO [org.springframework.beans.factory.xml.XmlBeanDefinitionReader:323] - Loading XML bean definitions from /opt/shibboleth-idp/conf/attribute-filter.xml
14:19:04.838 - DEBUG [org.springframework.beans.factory.xml.DefaultBeanDefinitionDocumentReader:86] - Loading bean definitions
14:19:04.840 - DEBUG [org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver:153] - Loaded mappings [{http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, urn:mace:shibboleth:2.0:resolver:ad=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.AttributeDefinitionNamespaceHandler, urn:mace:shibboleth:2.0:resolver:dc=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.DataConnectorNamespaceHandler, urn:mace:shibboleth:2.0:resolver:pc=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.principalConnector.PrincipalConnectorNamespaceHandler, http://www.springframework.org/schema/lang=org.springframework.scripting.config.LangNamespaceHandler, urn:mace:shibboleth:2.0:idp:profile-handler=edu.internet2.middleware.shibboleth.idp.config.profile.ProfileHandlerNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, urn:mace:shibboleth:2.0:services=edu.internet2.middleware.shibboleth.common.config.service.ServiceNamespaceHandler, urn:mace:shibboleth:2.0:resource=edu.internet2.middleware.shibboleth.common.config.resource.ResourceNamespaceHandler, urn:mace:shibboleth:2.0:attribute:encoder=edu.internet2.middleware.shibboleth.common.config.attribute.encoding.AttributeEncoderNamespaceHandler, urn:mace:shibboleth:2.0:relying-party:saml=edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAMLRelyingPartyNamespaceHandler, urn:mace:shibboleth:2.0:attribute:authority=edu.internet2.middleware.shibboleth.common.config.attribute.authority.AttributeAuthorityNamespaceHandler, urn:mace:shibboleth:2.0:security=edu.internet2.middleware.shibboleth.common.config.security.SecurityNamespaceHandler, urn:mace:shibboleth:2.0:relying-party=edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyNamespaceHandler, urn:mace:shibboleth:2.0:afp=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler, urn:mace:shibboleth:2.0:resolver=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AttributeResolverNamespaceHandler, urn:mace:shibboleth:2.0:metadata=edu.internet2.middleware.shibboleth.common.config.metadata.MetadataNamespaceHandler, urn:mace:shibboleth:2.0:afp:mf:basic=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.match.basic.BasicMatchFunctorNamespaceHandler, urn:mace:shibboleth:2.0:security:saml=edu.internet2.middleware.shibboleth.common.config.security.saml.SAMLSecurityNamespaceHandler, urn:mace:shibboleth:2.0:afp:mf:saml=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.match.saml.SAMLMatchFunctorNamespaceHandler}]
14:19:04.841 - DEBUG [edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyGroupBeanDefinitionParser:64] - Parsing attribute filter policy group ShibbolethFilterPolicy
14:19:04.841 - INFO [edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72] - Parsing configuration for attribute filter policy releaseTransientIdToAnyone
14:19:04.853 - INFO [org.springframework.context.support.GenericApplicationContext:411] - Refreshing org.springframework.context.support.GenericApplicationContext@5dc7b741: display name [ApplicationContext:shibboleth.AttributeFilterEngine]; startup date [Tue Jul 15 14:19:04 EDT 2014]; parent: org.springframework.web.context.support.XmlWebApplicationContext@171183d8
14:19:04.853 - INFO [org.springframework.context.support.GenericApplicationContext:426] - Bean factory for application context [org.springframework.context.support.GenericApplicationContext@5dc7b741]: org.springframework.beans.factory.support.DefaultListableBeanFactory@1ac2b5e0
14:19:04.853 - DEBUG [org.springframework.context.support.GenericApplicationContext:430] - 4 beans defined in org.springframework.context.support.GenericApplicationContext@5dc7b741: display name [ApplicationContext:shibboleth.AttributeFilterEngine]; startup date [Tue Jul 15 14:19:04 EDT 2014]; parent: org.springframework.web.context.support.XmlWebApplicationContext@171183d8
14:19:04.854 - DEBUG [org.springframework.context.support.GenericApplicationContext:648] - Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@5b471876]
14:19:04.855 - DEBUG [org.springframework.context.support.GenericApplicationContext:672] - Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default [org.springframework.context.event.SimpleApplicationEventMulticaster@189719cb]
14:19:04.855 - INFO [org.springframework.beans.factory.support.DefaultListableBeanFactory:414] - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1ac2b5e0: defining beans [/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PolicyRequirementRule:_c254a29ed2cd5522b611245317d7d75d,/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PermitValueRule:_979d64117defa8e4fe5498bc920fe4fb,/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeRule:_448f0d4656771c5a389d032a4f8274cf,/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeFilterPolicy:releaseTransientIdToAnyone]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@6ece41ee
14:19:04.856 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PolicyRequirementRule:_c254a29ed2cd5522b611245317d7d75d'
14:19:04.856 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PolicyRequirementRule:_c254a29ed2cd5522b611245317d7d75d'
14:19:04.856 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PolicyRequirementRule:_c254a29ed2cd5522b611245317d7d75d' to allow for resolving potential circular references
14:19:04.856 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PolicyRequirementRule:_c254a29ed2cd5522b611245317d7d75d'
14:19:04.857 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PermitValueRule:_979d64117defa8e4fe5498bc920fe4fb'
14:19:04.857 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PermitValueRule:_979d64117defa8e4fe5498bc920fe4fb'
14:19:04.857 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PermitValueRule:_979d64117defa8e4fe5498bc920fe4fb' to allow for resolving potential circular references
14:19:04.857 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/PermitValueRule:_979d64117defa8e4fe5498bc920fe4fb'
14:19:04.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeRule:_448f0d4656771c5a389d032a4f8274cf'
14:19:04.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeRule:_448f0d4656771c5a389d032a4f8274cf'
14:19:04.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeRule:_448f0d4656771c5a389d032a4f8274cf' to allow for resolving potential circular references
14:19:04.859 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)'
14:19:04.859 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)'
14:19:04.861 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeRule:_448f0d4656771c5a389d032a4f8274cf'
14:19:04.861 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeFilterPolicy:releaseTransientIdToAnyone'
14:19:04.862 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeFilterPolicy:releaseTransientIdToAnyone'
14:19:04.862 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeFilterPolicy:releaseTransientIdToAnyone' to allow for resolving potential circular references
14:19:04.865 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#1'
14:19:04.866 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#1'
14:19:04.866 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#2'
14:19:04.867 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#2'
14:19:04.867 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#2'
14:19:04.867 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#2'
14:19:04.868 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1367] - Invoking afterPropertiesSet() on bean with name '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeFilterPolicy:releaseTransientIdToAnyone'
14:19:04.868 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeFilterPolicy:releaseTransientIdToAnyone'
14:19:04.868 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean '/AttributeFilterPolicyGroup:ShibbolethFilterPolicy/AttributeFilterPolicy:releaseTransientIdToAnyone'
14:19:04.869 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:180] - shibboleth.AttributeFilterEngine service loaded new configuration
14:19:04.869 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.AttributeFilterEngine'
14:19:04.869 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:04.869 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeResolver'
14:19:04.870 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeFilterEngine'
14:19:04.870 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.SAML1AttributeAuthority'
14:19:04.870 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML1AttributeAuthority'
14:19:04.870 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeResolver'
14:19:04.871 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.SAML1AttributeAuthority' to allow for resolving potential circular references
14:19:04.877 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeFilterEngine'
14:19:04.877 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'shibboleth.SAML1AttributeAuthority'
14:19:04.877 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML1AttributeAuthority
14:19:04.878 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML1AttributeAuthority'
14:19:04.878 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:04.878 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeResolver'
14:19:04.878 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeFilterEngine'
14:19:04.879 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.SAML2AttributeAuthority'
14:19:04.879 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.SAML2AttributeAuthority'
14:19:04.879 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeResolver'
14:19:04.880 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.SAML2AttributeAuthority' to allow for resolving potential circular references
14:19:04.885 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.AttributeFilterEngine'
14:19:04.885 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'shibboleth.SAML2AttributeAuthority'
14:19:04.885 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.SAML2AttributeAuthority
14:19:04.885 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'shibboleth.SAML2AttributeAuthority'
14:19:04.886 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/relying-party.xml'
14:19:04.886 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/relying-party.xml'
14:19:04.887 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/relying-party.xml' to allow for resolving potential circular references
14:19:04.887 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/relying-party.xml'
14:19:04.887 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.LogbackLogging'
14:19:04.887 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.SAML1AttributeAuthority'
14:19:04.887 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.SAML2AttributeAuthority'
14:19:04.888 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'shibboleth.RelyingPartyConfigurationManager'
14:19:04.888 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'shibboleth.RelyingPartyConfigurationManager'
14:19:04.888 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'shibboleth.RelyingPartyConfigurationManager' to allow for resolving potential circular references
14:19:04.889 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#2'
14:19:04.889 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#2'
14:19:04.895 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'shibboleth.RelyingPartyConfigurationManager'
14:19:04.895 - DEBUG [edu.internet2.middleware.shibboleth.common.config.BaseReloadableService:136] - Initializing shibboleth.RelyingPartyConfigurationManager service with resources: [/opt/shibboleth-idp/conf/relying-party.xml]
14:19:04.895 - INFO [edu.internet2.middleware.shibboleth.common.config.BaseService:158] - Loading new configuration for service shibboleth.RelyingPartyConfigurationManager
14:19:04.896 - INFO [org.springframework.beans.factory.xml.XmlBeanDefinitionReader:323] - Loading XML bean definitions from /opt/shibboleth-idp/conf/relying-party.xml
14:19:04.934 - DEBUG [org.springframework.beans.factory.xml.DefaultBeanDefinitionDocumentReader:86] - Loading bean definitions
14:19:04.936 - DEBUG [org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver:153] - Loaded mappings [{http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, urn:mace:shibboleth:2.0:resolver:ad=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.AttributeDefinitionNamespaceHandler, urn:mace:shibboleth:2.0:resolver:dc=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.DataConnectorNamespaceHandler, urn:mace:shibboleth:2.0:resolver:pc=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.principalConnector.PrincipalConnectorNamespaceHandler, http://www.springframework.org/schema/lang=org.springframework.scripting.config.LangNamespaceHandler, urn:mace:shibboleth:2.0:idp:profile-handler=edu.internet2.middleware.shibboleth.idp.config.profile.ProfileHandlerNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, urn:mace:shibboleth:2.0:services=edu.internet2.middleware.shibboleth.common.config.service.ServiceNamespaceHandler, urn:mace:shibboleth:2.0:resource=edu.internet2.middleware.shibboleth.common.config.resource.ResourceNamespaceHandler, urn:mace:shibboleth:2.0:attribute:encoder=edu.internet2.middleware.shibboleth.common.config.attribute.encoding.AttributeEncoderNamespaceHandler, urn:mace:shibboleth:2.0:relying-party:saml=edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAMLRelyingPartyNamespaceHandler, urn:mace:shibboleth:2.0:attribute:authority=edu.internet2.middleware.shibboleth.common.config.attribute.authority.AttributeAuthorityNamespaceHandler, urn:mace:shibboleth:2.0:security=edu.internet2.middleware.shibboleth.common.config.security.SecurityNamespaceHandler, urn:mace:shibboleth:2.0:relying-party=edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyNamespaceHandler, urn:mace:shibboleth:2.0:afp=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler, urn:mace:shibboleth:2.0:resolver=edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AttributeResolverNamespaceHandler, urn:mace:shibboleth:2.0:metadata=edu.internet2.middleware.shibboleth.common.config.metadata.MetadataNamespaceHandler, urn:mace:shibboleth:2.0:afp:mf:basic=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.match.basic.BasicMatchFunctorNamespaceHandler, urn:mace:shibboleth:2.0:security:saml=edu.internet2.middleware.shibboleth.common.config.security.saml.SAMLSecurityNamespaceHandler, urn:mace:shibboleth:2.0:afp:mf:saml=edu.internet2.middleware.shibboleth.common.config.attribute.filtering.match.saml.SAMLMatchFunctorNamespaceHandler}]
14:19:04.947 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.BaseMetadataProviderBeanDefinitionParser:42] - Parsing configuration for 'ChainingMetadataProvider' metadata provider with ID: ShibbolethMetadata
14:19:04.947 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.BaseMetadataProviderBeanDefinitionParser:46] - Metadata provider requires valid metadata: true
14:19:04.950 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.BaseMetadataProviderBeanDefinitionParser:42] - Parsing configuration for 'FilesystemMetadataProvider' metadata provider with ID: IdPMD
14:19:04.950 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.BaseMetadataProviderBeanDefinitionParser:46] - Metadata provider requires valid metadata: true
14:19:04.951 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractMetadataProviderBeanDefinitionParser:41] - Metadata provider using parser pool: shibboleth.ParserPool
14:19:04.951 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractMetadataProviderBeanDefinitionParser:45] - Metadata provider fail fast initialization enabled: true
14:19:04.951 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:44] - Metadata provider using task timer: shibboleth.TaskTimer
14:19:04.951 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:48] - Metadata provider refresh delay factor: 0.75
14:19:04.952 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:52] - Metadata provider min refresh delay: 300000ms
14:19:04.955 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:56] - Metadata provider max refresh delay: 86400000ms
14:19:04.956 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.FilesystemMetadataProviderBeanDefinitionParser:52] - Metadata provider 'IdPMD' reading metadata from: /opt/shibboleth-idp/metadata/idp-metadata.xml
14:19:04.957 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.BaseMetadataProviderBeanDefinitionParser:42] - Parsing configuration for 'FileBackedHTTPMetadataProvider' metadata provider with ID: URLMD
14:19:04.957 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.BaseMetadataProviderBeanDefinitionParser:46] - Metadata provider requires valid metadata: true
14:19:04.960 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractMetadataProviderBeanDefinitionParser:41] - Metadata provider using parser pool: shibboleth.ParserPool
14:19:04.960 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractMetadataProviderBeanDefinitionParser:45] - Metadata provider fail fast initialization enabled: false
14:19:04.961 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:44] - Metadata provider using task timer: shibboleth.TaskTimer
14:19:04.961 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:48] - Metadata provider refresh delay factor: 0.75
14:19:04.961 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:52] - Metadata provider min refresh delay: 300000ms
14:19:04.961 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.AbstractReloadingMetadataProviderBeanDefinitionParser:56] - Metadata provider max refresh delay: 14400000ms
14:19:04.963 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.HTTPMetadataProviderBeanDefinitionParser:100] - Metadata provider 'URLMD' HTTP request timeout: 5000ms
14:19:04.980 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.HTTPMetadataProviderBeanDefinitionParser:78] - Metadata provider 'URLMD' metadata URL: http://md.incommon.org/InCommon/InCommon-metadata.xml
14:19:04.980 - DEBUG [edu.internet2.middleware.shibboleth.common.config.metadata.FileBackedHTTPMetadataProviderBeanDefinitionParser:52] - Metadata provider 'URLMD' backing up remote metadata file to:
14:19:04.981 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: anonymous
14:19:04.981 - DEBUG [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:77] - Relying party configuration - provider ID: https://idp.symplicity.com/idp/shibboleth
14:19:04.982 - DEBUG [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:82] - Relying party configuration - default authentication method: null
14:19:04.982 - DEBUG [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:88] - Relying party configuration - default signing credential: IdPCredential
14:19:04.982 - INFO [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:73] - Parsing configuration for relying party with id: default
14:19:04.982 - DEBUG [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:77] - Relying party configuration - provider ID: https://idp.symplicity.com/idp/shibboleth
14:19:04.983 - DEBUG [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:82] - Relying party configuration - default authentication method: null
14:19:04.983 - DEBUG [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:88] - Relying party configuration - default signing credential: IdPCredential
14:19:04.984 - DEBUG [edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyConfigurationBeanDefinitionParser:102] - Relying party configuration - 8 profile configurations
14:19:05.009 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: IdPCredential
14:19:05.010 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.AbstractCredentialBeanDefinitionParser:91] - Parsing credential key names
14:19:05.010 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.AbstractCredentialBeanDefinitionParser:121] - Parsing credential private key
14:19:05.049 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:89] - Parsing x509 credential certificates
14:19:05.092 - INFO [edu.internet2.middleware.shibboleth.common.config.security.StaticExplicitKeySignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for StaticExplicitKeySignature trust engine with id: ICTrust
14:19:05.092 - INFO [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:63] - Parsing configuration for X509Filesystem credential with id: MyFederation1Credentials
14:19:05.093 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.AbstractCredentialBeanDefinitionParser:91] - Parsing credential key names
14:19:05.093 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.AbstractX509CredentialBeanDefinitionParser:89] - Parsing x509 credential certificates
14:19:05.095 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingSignatureTrustEngineBeanDefinitionParser:59] - Parsing configuration for SignatureChaining trust engine with id: shibboleth.SignatureTrustEngine
14:19:05.095 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ChainingSignatureTrustEngineBeanDefinitionParser:68] - Parsing chain trust engine member shibboleth.SignatureTrustEngine
14:19:05.096 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeySignatureTrustEngineBeanDefinitionParser:50] - Parsing configuration for MetadataExplicitKeySignature trust engine with id: shibboleth.SignatureMetadataExplicitKeyTrustEngine
14:19:05.096 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ChainingSignatureTrustEngineBeanDefinitionParser:68] - Parsing chain trust engine member shibboleth.SignatureTrustEngine
14:19:05.097 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXSignatureTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXSignature trust engine with id: shibboleth.SignatureMetadataPKIXTrustEngine
14:19:05.098 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ChainingTrustEngineBeanDefinitionParser:59] - Parsing configuration for Chaining trust engine with id: shibboleth.CredentialTrustEngine
14:19:05.098 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ChainingTrustEngineBeanDefinitionParser:68] - Parsing chain trust engine member shibboleth.CredentialTrustEngine
14:19:05.099 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataExplicitKeyTrustEngineBeanDefinitionParser:48] - Parsing configuration for MetadataExplicitKey trust engine with id: shibboleth.CredentialMetadataExplictKeyTrustEngine
14:19:05.099 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ChainingTrustEngineBeanDefinitionParser:68] - Parsing chain trust engine member shibboleth.CredentialTrustEngine
14:19:05.100 - INFO [edu.internet2.middleware.shibboleth.common.config.security.MetadataPKIXX509CredentialTrustEngineBeanDefinitionParser:52] - Parsing configuration for MetadataPKIXX509Credential trust engine with id: shibboleth.CredentialMetadataPKIXTrustEngine
14:19:05.101 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.ShibbolethSSOSecurityPolicy
14:19:05.101 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:63] - Configuring security policy: shibboleth.ShibbolethSSOSecurityPolicy
14:19:05.107 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1AttributeQuerySecurityPolicy
14:19:05.107 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:63] - Configuring security policy: shibboleth.SAML1AttributeQuerySecurityPolicy
14:19:05.112 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML1ArtifactResolutionSecurityPolicy
14:19:05.112 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:63] - Configuring security policy: shibboleth.SAML1ArtifactResolutionSecurityPolicy
14:19:05.113 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SSOSecurityPolicy
14:19:05.113 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:63] - Configuring security policy: shibboleth.SAML2SSOSecurityPolicy
14:19:05.117 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2AttributeQuerySecurityPolicy
14:19:05.117 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:63] - Configuring security policy: shibboleth.SAML2AttributeQuerySecurityPolicy
14:19:05.119 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2ArtifactResolutionSecurityPolicy
14:19:05.119 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:63] - Configuring security policy: shibboleth.SAML2ArtifactResolutionSecurityPolicy
14:19:05.121 - INFO [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:59] - Parsing configuration for SecurityPolicyType security policy with id: shibboleth.SAML2SLOSecurityPolicy
14:19:05.121 - DEBUG [edu.internet2.middleware.shibboleth.common.config.security.ShibbolethSecurityPolicyBeanDefinitionParser:63] - Configuring security policy: shibboleth.SAML2SLOSecurityPolicy
14:19:05.123 - INFO [org.springframework.context.support.GenericApplicationContext:411] - Refreshing org.springframework.context.support.GenericApplicationContext@49c60e18: display name [ApplicationContext:shibboleth.RelyingPartyConfigurationManager]; startup date [Tue Jul 15 14:19:05 EDT 2014]; parent: org.springframework.web.context.support.XmlWebApplicationContext@171183d8
14:19:05.123 - INFO [org.springframework.context.support.GenericApplicationContext:426] - Bean factory for application context [org.springframework.context.support.GenericApplicationContext@49c60e18]: org.springframework.beans.factory.support.DefaultListableBeanFactory@6e1d5b3a
14:19:05.123 - DEBUG [org.springframework.context.support.GenericApplicationContext:430] - 79 beans defined in org.springframework.context.support.GenericApplicationContext@49c60e18: display name [ApplicationContext:shibboleth.RelyingPartyConfigurationManager]; startup date [Tue Jul 15 14:19:05 EDT 2014]; parent: org.springframework.web.context.support.XmlWebApplicationContext@171183d8
14:19:05.135 - DEBUG [org.springframework.context.support.GenericApplicationContext:648] - Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@4145fcca]
14:19:05.135 - DEBUG [org.springframework.context.support.GenericApplicationContext:672] - Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default [org.springframework.context.event.SimpleApplicationEventMulticaster@59d75340]
14:19:05.140 - INFO [org.springframework.beans.factory.support.DefaultListableBeanFactory:414] - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@6e1d5b3a: defining beans [IdPMD,org.opensaml.saml2.metadata.provider.RequiredValidUntilFilter#0,org.opensaml.saml2.metadata.provider.EntityRoleFilter#0,org.opensaml.saml2.metadata.provider.MetadataFilterChain#0,URLMD,ShibbolethMetadata,anonymous,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.ShibbolethSSOProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML1AttributeQueryProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML1ArtifactResolutionProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2SSOProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2ECPProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2AttributeQueryProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2ArtifactResolutionProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2LogoutRequestProfileConfigurationFactoryBean#0,default,IdPCredential,MyFederation1Credentials,ICTrust,shibboleth.SignatureMetadataExplicitKeyTrustEngine,shibboleth.SignatureMetadataPKIXTrustEngine,shibboleth.SignatureTrustEngine,shibboleth.CredentialMetadataExplictKeyTrustEngine,shibboleth.CredentialMetadataPKIXTrustEngine,shibboleth.CredentialTrustEngine,org.opensaml.common.binding.security.MessageReplayRule#0,org.opensaml.common.binding.security.IssueInstantRule#0,org.opensaml.ws.security.provider.MandatoryIssuerRule#0,shibboleth.ShibbolethSSOSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#1,org.opensaml.common.binding.security.IssueInstantRule#1,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#0,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#0,org.opensaml.ws.security.provider.MandatoryIssuerRule#1,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#0,shibboleth.SAML1AttributeQuerySecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#2,org.opensaml.common.binding.security.IssueInstantRule#2,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#1,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#1,org.opensaml.ws.security.provider.MandatoryIssuerRule#2,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#1,shibboleth.SAML1ArtifactResolutionSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#3,org.opensaml.common.binding.security.IssueInstantRule#3,org.opensaml.saml2.binding.security.SAML2AuthnRequestsSignedRule#0,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#2,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#0,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#0,org.opensaml.ws.security.provider.MandatoryIssuerRule#3,shibboleth.SAML2SSOSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#4,org.opensaml.common.binding.security.IssueInstantRule#4,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#3,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#1,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#1,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#2,org.opensaml.ws.security.provider.MandatoryIssuerRule#4,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#2,shibboleth.SAML2AttributeQuerySecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#5,org.opensaml.common.binding.security.IssueInstantRule#5,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#4,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#2,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#2,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#3,org.opensaml.ws.security.provider.MandatoryIssuerRule#5,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#3,shibboleth.SAML2ArtifactResolutionSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#6,org.opensaml.common.binding.security.IssueInstantRule#6,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#5,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#3,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#3,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#4,org.opensaml.ws.security.provider.MandatoryIssuerRule#6,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#4,shibboleth.SAML2SLOSecurityPolicy,edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyGroup#0]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@6ece41ee
14:19:05.140 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'IdPMD'
14:19:05.140 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'IdPMD'
14:19:05.141 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.TaskTimer'
14:19:05.143 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'IdPMD' to allow for resolving potential circular references
14:19:05.150 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:05.151 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'IdPMD'
14:19:05.184 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'IdPMD'
14:19:05.184 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'org.opensaml.saml2.metadata.provider.RequiredValidUntilFilter#0'
14:19:05.184 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'org.opensaml.saml2.metadata.provider.RequiredValidUntilFilter#0'
14:19:05.185 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'org.opensaml.saml2.metadata.provider.RequiredValidUntilFilter#0' to allow for resolving potential circular references
14:19:05.185 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'org.opensaml.saml2.metadata.provider.RequiredValidUntilFilter#0'
14:19:05.185 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'org.opensaml.saml2.metadata.provider.EntityRoleFilter#0'
14:19:05.185 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'org.opensaml.saml2.metadata.provider.EntityRoleFilter#0'
14:19:05.186 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'org.opensaml.saml2.metadata.provider.EntityRoleFilter#0' to allow for resolving potential circular references
14:19:05.188 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'org.opensaml.saml2.metadata.provider.EntityRoleFilter#0'
14:19:05.188 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'org.opensaml.saml2.metadata.provider.MetadataFilterChain#0'
14:19:05.188 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'org.opensaml.saml2.metadata.provider.MetadataFilterChain#0'
14:19:05.189 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'org.opensaml.saml2.metadata.provider.MetadataFilterChain#0' to allow for resolving potential circular references
14:19:05.189 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)'
14:19:05.189 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)'
14:19:05.190 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#1'
14:19:05.190 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#1'
14:19:05.192 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean 'org.opensaml.saml2.metadata.provider.MetadataFilterChain#0'
14:19:05.192 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Creating shared instance of singleton bean 'URLMD'
14:19:05.193 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean 'URLMD'
14:19:05.193 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.TaskTimer'
14:19:05.195 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:459] - Eagerly caching bean 'URLMD' to allow for resolving potential circular references
14:19:05.203 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#2'
14:19:05.203 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#2'
14:19:05.203 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#2'
14:19:05.204 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:383] - Creating instance of bean '(inner bean)#3'
14:19:05.204 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#3'
14:19:05.205 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:411] - Finished creating instance of bean '(inner bean)#2'
14:19:05.205 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:214] - Returning cached instance of singleton bean 'shibboleth.ParserPool'
14:19:05.205 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:1410] - Invoking init method 'initialize' on bean with name 'URLMD'
14:19:38.801 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean '(inner bean)#2': [(inner bean)#2, URLMD]
14:19:38.851 - INFO [org.springframework.beans.factory.support.DefaultListableBeanFactory:421] - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@6e1d5b3a: defining beans [IdPMD,org.opensaml.saml2.metadata.provider.RequiredValidUntilFilter#0,org.opensaml.saml2.metadata.provider.EntityRoleFilter#0,org.opensaml.saml2.metadata.provider.MetadataFilterChain#0,URLMD,ShibbolethMetadata,anonymous,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.ShibbolethSSOProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML1AttributeQueryProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML1ArtifactResolutionProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2SSOProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2ECPProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2AttributeQueryProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2ArtifactResolutionProfileConfigurationFactoryBean#0,edu.internet2.middleware.shibboleth.common.config.relyingparty.saml.SAML2LogoutRequestProfileConfigurationFactoryBean#0,default,IdPCredential,MyFederation1Credentials,ICTrust,shibboleth.SignatureMetadataExplicitKeyTrustEngine,shibboleth.SignatureMetadataPKIXTrustEngine,shibboleth.SignatureTrustEngine,shibboleth.CredentialMetadataExplictKeyTrustEngine,shibboleth.CredentialMetadataPKIXTrustEngine,shibboleth.CredentialTrustEngine,org.opensaml.common.binding.security.MessageReplayRule#0,org.opensaml.common.binding.security.IssueInstantRule#0,org.opensaml.ws.security.provider.MandatoryIssuerRule#0,shibboleth.ShibbolethSSOSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#1,org.opensaml.common.binding.security.IssueInstantRule#1,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#0,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#0,org.opensaml.ws.security.provider.MandatoryIssuerRule#1,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#0,shibboleth.SAML1AttributeQuerySecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#2,org.opensaml.common.binding.security.IssueInstantRule#2,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#1,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#1,org.opensaml.ws.security.provider.MandatoryIssuerRule#2,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#1,shibboleth.SAML1ArtifactResolutionSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#3,org.opensaml.common.binding.security.IssueInstantRule#3,org.opensaml.saml2.binding.security.SAML2AuthnRequestsSignedRule#0,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#2,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#0,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#0,org.opensaml.ws.security.provider.MandatoryIssuerRule#3,shibboleth.SAML2SSOSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#4,org.opensaml.common.binding.security.IssueInstantRule#4,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#3,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#1,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#1,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#2,org.opensaml.ws.security.provider.MandatoryIssuerRule#4,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#2,shibboleth.SAML2AttributeQuerySecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#5,org.opensaml.common.binding.security.IssueInstantRule#5,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#4,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#2,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#2,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#3,org.opensaml.ws.security.provider.MandatoryIssuerRule#5,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#3,shibboleth.SAML2ArtifactResolutionSecurityPolicy,org.opensaml.common.binding.security.MessageReplayRule#6,org.opensaml.common.binding.security.IssueInstantRule#6,org.opensaml.common.binding.security.SAMLProtocolMessageXMLSignatureSecurityPolicyRule#5,org.opensaml.saml2.binding.security.SAML2HTTPRedirectDeflateSignatureRule#3,org.opensaml.saml2.binding.security.SAML2HTTPPostSimpleSignRule#3,edu.internet2.middleware.shibboleth.common.binding.security.ShibbolethClientCertAuthRule#4,org.opensaml.ws.security.provider.MandatoryIssuerRule#6,org.opensaml.ws.security.provider.MandatoryAuthenticatedMessageRule#4,shibboleth.SAML2SLOSecurityPolicy,edu.internet2.middleware.shibboleth.common.config.relyingparty.RelyingPartyGroup#0]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@6ece41ee
14:19:38.853 - ERROR [edu.internet2.middleware.shibboleth.common.config.BaseService:188] - Configuration was not loaded for shibboleth.RelyingPartyConfigurationManager service, error creating components. The root cause of this error was: java.lang.NullPointerException: null
14:19:38.854 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean '(inner bean)#2': [shibboleth.RelyingPartyConfigurationManager]
14:19:38.855 - INFO [org.springframework.beans.factory.support.DefaultListableBeanFactory:421] - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@6ece41ee: defining beans [shibboleth.CacheManager,shibboleth.TaskTimer,shibboleth.LogbackLogging,shibboleth.OpensamlConfig,shibboleth.IdGenerator,shibboleth.VelocityEngine,shibboleth.TemplateEngine,shibboleth.ParserPool,shibboleth.StorageService,shibboleth.StorageServiceSweeper,shibboleth.SessionManager,shibboleth.ArtifactMap,shibboleth.ReplayCache,shibboleth.MessageDecoders,shibboleth.MessageEncoders,shibboleth.ServletAttributeExporter,org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-resolver.xml,shibboleth.AttributeResolver,org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/attribute-filter.xml,shibboleth.AttributeFilterEngine,shibboleth.SAML1AttributeAuthority,shibboleth.SAML2AttributeAuthority,org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/relying-party.xml,shibboleth.RelyingPartyConfigurationManager,org.opensaml.util.resource.FilesystemResource:/opt/shibboleth-idp/conf/handler.xml,shibboleth.HandlerManager,edu.internet2.middleware.shibboleth.common.config.service.ServletContextAttributeExporter#0]; root of factory hierarchy
14:19:38.856 - DEBUG [org.springframework.beans.factory.support.DisposableBeanAdapter:148] - Invoking destroy() on bean with name 'shibboleth.MessageEncoders'
14:19:38.856 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.SAML2HttpPostSimpleSignEncoder': [shibboleth.MessageEncoders]
14:19:38.857 - DEBUG [org.springframework.beans.factory.support.DisposableBeanAdapter:148] - Invoking destroy() on bean with name 'shibboleth.MessageDecoders'
14:19:38.857 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.SAML2HttpPostSimpleSignDecoder': [shibboleth.MessageDecoders]
14:19:38.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.TaskTimer': [shibboleth.LogbackLogging, shibboleth.StorageServiceSweeper]
14:19:38.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.LogbackLogging': [shibboleth.OpensamlConfig, shibboleth.ParserPool, shibboleth.IdGenerator, shibboleth.VelocityEngine, shibboleth.TemplateEngine, shibboleth.StorageService, shibboleth.StorageServiceSweeper, shibboleth.SessionManager, shibboleth.ArtifactMap, shibboleth.ReplayCache, shibboleth.ServletAttributeExporter, shibboleth.AttributeResolver, shibboleth.AttributeFilterEngine, shibboleth.SAML1AttributeAuthority, shibboleth.SAML2AttributeAuthority]
14:19:38.858 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibMetadataExtensions': [shibboleth.OpensamlConfig]
14:19:38.859 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.XercesSecurityManager': [shibboleth.ParserPool]
14:19:38.859 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.VelocityEngine': [shibboleth.TemplateEngine]
14:19:38.860 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.StorageService': [shibboleth.StorageServiceSweeper, shibboleth.SessionManager, shibboleth.ArtifactMap, shibboleth.ReplayCache]
14:19:38.860 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean 'shibboleth.AttributeResolver': [shibboleth.SAML1AttributeAuthority, shibboleth.SAML2AttributeAuthority]
14:19:38.860 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean '(inner bean)': [shibboleth.AttributeResolver]
14:19:38.861 - DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory:476] - Retrieved dependent beans for bean '(inner bean)#1': [shibboleth.AttributeFilterEngine]
14:19:38.861 - DEBUG [org.springframework.beans.factory.support.DisposableBeanAdapter:203] - Invoking destroy method 'cancel' on bean with name 'shibboleth.TaskTimer'
14:19:38.861 - DEBUG [org.springframework.beans.factory.support.DisposableBeanAdapter:148] - Invoking destroy() on bean with name 'shibboleth.CacheManager'
14:19:38.862 - INFO [org.springframework.cache.ehcache.EhCacheManagerFactoryBean:139] - Shutting down EHCache CacheManager
14:19:38.869 - ERROR [org.springframework.web.context.ContextLoader:215] - Context initialization failed
org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'shibboleth.RelyingPartyConfigurationManager': Invocation of init method failed; nested exception is edu.internet2.middleware.shibboleth.common.service.ServiceException: Configuration was not loaded for shibboleth.RelyingPartyConfigurationManager service, error creating components.
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1338) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:473) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at java.security.AccessController.doPrivileged(Native Method) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:728) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:380) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:255) ~[spring-web-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:199) ~[spring-web-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:45) ~[spring-web-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4206) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardContext.start(StandardContext.java:4705) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:799) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:779) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:601) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:675) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:601) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:502) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1317) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:324) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:142) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1065) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardHost.start(StandardHost.java:840) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1057) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:463) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardService.start(StandardService.java:525) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.core.StandardServer.start(StandardServer.java:754) ~[catalina-6.0.35.jar:6.0.35]
at org.apache.catalina.startup.Catalina.start(Catalina.java:595) ~[catalina-6.0.35.jar:6.0.35]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_31]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.6.0_31]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.6.0_31]
at java.lang.reflect.Method.invoke(Method.java:622) ~[na:1.6.0_31]
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289) ~[bootstrap.jar:6.0.35]
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414) ~[bootstrap.jar:6.0.35]
Caused by: edu.internet2.middleware.shibboleth.common.service.ServiceException: Configuration was not loaded for shibboleth.RelyingPartyConfigurationManager service, error creating components.
at edu.internet2.middleware.shibboleth.common.config.BaseService.loadContext(BaseService.java:192) ~[shibboleth-common-1.4.0.jar:na]
at edu.internet2.middleware.shibboleth.common.config.BaseReloadableService.initialize(BaseReloadableService.java:148) ~[shibboleth-common-1.4.0.jar:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_31]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.6.0_31]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.6.0_31]
at java.lang.reflect.Method.invoke(Method.java:622) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1414) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1375) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1335) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
... 39 common frames omitted
Caused by: java.lang.NullPointerException: null
at org.opensaml.saml2.metadata.provider.AbstractReloadingMetadataProvider.refresh(AbstractReloadingMetadataProvider.java:269) ~[opensaml-2.6.0.jar:na]
at org.opensaml.saml2.metadata.provider.AbstractReloadingMetadataProvider.doInitialization(AbstractReloadingMetadataProvider.java:236) ~[opensaml-2.6.0.jar:na]
at org.opensaml.saml2.metadata.provider.FileBackedHTTPMetadataProvider.doInitialization(FileBackedHTTPMetadataProvider.java:102) ~[opensaml-2.6.0.jar:na]
at org.opensaml.saml2.metadata.provider.AbstractMetadataProvider.initialize(AbstractMetadataProvider.java:407) ~[opensaml-2.6.0.jar:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_31]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.6.0_31]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.6.0_31]
at java.lang.reflect.Method.invoke(Method.java:622) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1414) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1375) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1335) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:473) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at java.security.AccessController.doPrivileged(Native Method) ~[na:1.6.0_31]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429) ~[spring-beans-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:728) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:380) ~[spring-context-2.5.6.SEC03.jar:2.5.6.SEC03]
at edu.internet2.middleware.shibboleth.common.config.BaseService.loadContext(BaseService.java:171) ~[shibboleth-common-1.4.0.jar:na]
... 47 common frames omitted
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment