Restarting catalina doesn't bring up shib/tomcat
Sunny
ssn at ebi.ac.uk
Thu Jul 23 12:21:02 EDT 2015
Hi
After stopping and then starting shib/tomcat services using catalina.sh
it doesn't start any of the services. No errors are reporting after
running the command ./catalina.sh start, however the logs suggest
something is not loading correctly. I've attached logs to the end of the
emails. There are two sections of the logs that seem to indicate where
this issue is:-
1)
SEVERE: Servlet.service() for servlet jsp threw exception
java.lang.NullPointerException
at
org.apache.jsp.uApprove.terms_002dof_002duse_jsp._jspService(terms_002dof_002duse_jsp.java:98)
and
2)
13:42:26.686 - 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.xml.sax.SAXParseException: Attribute "id" was already specified for
element "metadata:MetadataProvider".
Any advice?
Kind Regards
----
Logs
[xxx at xxx logs]# cat catalina.2015-07-20.log
20-Jul-2015 13:42:20 org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8443
20-Jul-2015 13:42:20 org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-9443
20-Jul-2015 13:42:21 org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
20-Jul-2015 13:42:21 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.
20-Jul-2015 13:42:21 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.
20-Jul-2015 13:42:21 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.
20-Jul-2015 13:42:21 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.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 5b4b8403]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 3ac80fc8]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 2484319e]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 62d58ecc]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 329225c5]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 18edc273]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 217918ce]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 154b8d8d]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 133a2ee7]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 50dd498d]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 7007f174]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 197d3022]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 69e31540]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 3b88530f]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 73051c51]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 2193823d])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 548644d5]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:21 org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8443
20-Jul-2015 13:42:21 org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-9443
20-Jul-2015 13:42:22 org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal
performance in production environments was not found on the
java.library.path:
/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64/server:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
20-Jul-2015 13:42:23 org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8443
20-Jul-2015 13:42:23 org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-9443
20-Jul-2015 13:42:23 org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 704 ms
20-Jul-2015 13:42:23 org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
20-Jul-2015 13:42:23 org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.41
20-Jul-2015 13:42:23 org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor idp.xml
20-Jul-2015 13:42:26 org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
20-Jul-2015 13:42:26 org.apache.catalina.core.StandardContext start
SEVERE: Context [/idp] startup failed due to previous errors
20-Jul-2015 13:42:26 org.apache.catalina.loader.WebappClassLoader
checkThreadLocalMapForLeaks
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 at 69b08471])
and a value of type
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer] (value
[edu.vt.middleware.ldap.ssl.DefaultSSLContextInitializer at 43c74cec]) but
failed to remove it when the web application was stopped. This is very
likely to create a memory leak.
20-Jul-2015 13:42:26 org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor manager.xml
20-Jul-2015 13:42:26 org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
20-Jul-2015 13:42:26 org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
20-Jul-2015 13:42:27 org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory examples
20-Jul-2015 13:42:27 org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
20-Jul-2015 13:42:27 org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8443
20-Jul-2015 13:42:27 org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-9443
20-Jul-2015 13:42:27 org.apache.catalina.startup.Catalina start
INFO: Server startup in 4074 ms
---
[xxx at xxx logs]# cat localhost.2015-06-26.log
26-Jun-2015 12:26:42 org.apache.catalina.core.ApplicationDispatcher invoke
SEVERE: Servlet.service() for servlet jsp threw exception
java.lang.NullPointerException
at
org.apache.jsp.uApprove.terms_002dof_002duse_jsp._jspService(terms_002dof_002duse_jsp.java:98)
at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:723)
at
org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:388)
at
org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:313)
at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:260)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:723)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:646)
at
org.apache.catalina.core.ApplicationDispatcher.processRequest(ApplicationDispatcher.java:436)
at
org.apache.catalina.core.ApplicationDispatcher.doForward(ApplicationDispatcher.java:374)
at
org.apache.catalina.core.ApplicationDispatcher.forward(ApplicationDispatcher.java:302)
at ch.SWITCH.aai.uApprove.ViewHelper.showView(ViewHelper.java:140)
at ch.SWITCH.aai.uApprove.tou.ToUServlet.doGet(ToUServlet.java:90)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:723)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
edu.internet2.middleware.shibboleth.idp.util.NoCacheFilter.doFilter(NoCacheFilter.java:50)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
edu.internet2.middleware.shibboleth.idp.session.IdPSessionFilter.doFilter(IdPSessionFilter.java:87)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
edu.internet2.middleware.shibboleth.common.log.SLF4JMDCCleanupFilter.doFilter(SLF4JMDCCleanupFilter.java:52)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
at
org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:861)
at
org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:606)
at
org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489)
at java.lang.Thread.run(Thread.java:701)
[xxx at xxx logs]#
cat idp-process.log
13:42:25.820 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.OpensamlConfigBean:80] -
Loading OpenSAML configuration file:
jar:file:/opt/apache-tomcat-6.0.41/work/Catalina/localhost/idp/WEB-INF/lib/shibboleth-common-1.4.2.jar!/shibboleth-saml-ext-config.xml
13:42:25.956 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.service.ServletContextAttributeExporter:85]
- Exporting bean shibboleth.SessionManager to servlet context.
13:42:25.965 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.BaseReloadableService:136]
- Initializing shibboleth.AttributeResolver service with resources:
[/opt/shibboleth-idp/conf/attribute-resolver.xml]
13:42:25.965 - INFO
[edu.internet2.middleware.shibboleth.common.config.BaseService:158] -
Loading new configuration for service shibboleth.AttributeResolver
13:42:26.003 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for PrincipalConnector plugin with ID: shibTransient
13:42:26.004 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65]
- Dependencies for plugin shibTransient: none
13:42:26.004 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for PrincipalConnector plugin with ID: saml1Unspec
13:42:26.004 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65]
- Dependencies for plugin saml1Unspec: none
13:42:26.005 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for PrincipalConnector plugin with ID:
saml2Transient
13:42:26.005 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65]
- Dependencies for plugin saml2Transient: none
13:42:26.010 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for DataConnector plugin with ID: myLDAP
13:42:26.011 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65]
- Dependencies for plugin myLDAP: none
13:42:26.011 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:96]
- Data connector myLDAP LDAP URL: ldaps://xxxx.xxx.xxx
13:42:26.011 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:103]
- Data connector myLDAP connection strategy: ACTIVE_PASSIVE
13:42:26.012 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:108]
- Data connector myLDAP base DN:
ou=people,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx
13:42:26.012 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:116]
- Data connector myLDAP authentication type: SIMPLE
13:42:26.012 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:120]
- Data connector myLDAP principal:
uid=ldap,ou=people,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx
13:42:26.013 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:133]
- Data connector myLDAP LDAP filter template:
(uid=$requestContext.principalName)
13:42:26.013 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:140]
- Data connector myLDAP search scope: SUBTREE
13:42:26.013 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:147]
- Data connector myLDAP return attributes: [uid, cn, givenName, sn,
mail, eduPersonAffiliation]
13:42:26.014 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:184]
- Data connector myLDAP use startTLS: false
13:42:26.014 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:206]
- Data connector myLDAP search timeout: 3000ms
13:42:26.014 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:213]
- Data connector myLDAP max search result size: 1
13:42:26.014 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:220]
- Data connector myLDAP merge results: false
13:42:26.014 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:228]
- Data connector myLDAP no results is error: false
13:42:26.015 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:237]
- Data connector myLDAP will lower case attribute IDs: false
13:42:26.015 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.dataConnector.LdapDataConnectorBeanDefinitionParser:257]
- Data connector myLDAP is pooling connections: false
13:42:26.016 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for DataConnector plugin with ID: computedID
13:42:26.017 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin computedID: [myLDAP]
13:42:26.023 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID: uid
13:42:26.023 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin uid: [myLDAP]
13:42:26.023 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition uid to: uid
13:42:26.024 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition uid produces attributes that are only
dependencies: false
13:42:26.031 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID: email
13:42:26.031 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin email: [myLDAP]
13:42:26.031 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition email to: mail
13:42:26.031 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition email produces attributes that are only
dependencies: false
13:42:26.032 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID: commonName
13:42:26.032 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin commonName: [myLDAP]
13:42:26.032 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition commonName to: cn
13:42:26.032 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition commonName produces attributes that are only
dependencies: false
13:42:26.033 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID: surname
13:42:26.033 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin surname: [myLDAP]
13:42:26.033 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition surname to: sn
13:42:26.033 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition surname produces attributes that are only
dependencies: false
13:42:26.034 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID: givenName
13:42:26.034 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin givenName: [myLDAP]
13:42:26.034 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition givenName to:
givenName
13:42:26.034 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition givenName produces attributes that are only
dependencies: false
13:42:26.035 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID:
eduPersonAffiliation
13:42:26.035 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin eduPersonAffiliation: [myLDAP]
13:42:26.035 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition
eduPersonAffiliation to: eduPersonAffiliation
13:42:26.035 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition eduPersonAffiliation produces attributes that are
only dependencies: false
13:42:26.036 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID:
eduPersonPrincipalName
13:42:26.037 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin eduPersonPrincipalName: [myLDAP]
13:42:26.037 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition
eduPersonPrincipalName to: uid
13:42:26.037 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition eduPersonPrincipalName produces attributes that
are only dependencies: false
13:42:26.039 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.ScopedAttributeDefinitionBeanDefinitionParser:53]
- Setting scope of attribute definition eduPersonPrincipalName to:
xxxx.xxx.xxx
13:42:26.039 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID:
eduPersonScopedAffiliation
13:42:26.039 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin eduPersonScopedAffiliation: [eduPersonAffiliation]
13:42:26.039 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition
eduPersonScopedAffiliation to: eduPersonAffiliation
13:42:26.039 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition eduPersonScopedAffiliation produces attributes
that are only dependencies: false
13:42:26.040 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.ScopedAttributeDefinitionBeanDefinitionParser:53]
- Setting scope of attribute definition eduPersonScopedAffiliation to:
xxxx.xxx.xxx
13:42:26.041 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID:
eduPersonTargetedID
13:42:26.041 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:62]
- Dependencies for plugin eduPersonTargetedID: [computedID]
13:42:26.041 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition
eduPersonTargetedID to: computedID
13:42:26.041 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition eduPersonTargetedID produces attributes that are
only dependencies: false
13:42:26.043 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:55]
- Parsing configuration for AttributeDefinition plugin with ID: transientId
13:42:26.043 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.AbstractResolutionPlugInBeanDefinitionParser:65]
- Dependencies for plugin transientId: none
13:42:26.043 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:58]
- Setting source attribute ID for attribute definition transientId to:
13:42:26.043 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.resolver.attributeDefinition.BaseAttributeDefinitionBeanDefinitionParser:82]
- Attribute definition transientId produces attributes that are only
dependencies: false
13:42:26.095 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:74] - Bind with
the following parameters:
13:42:26.095 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:75] - authtype
= simple
13:42:26.095 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:76] - dn =
uid=ldap,ou=people,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx
13:42:26.096 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:83] -
credential = <suppressed>
13:42:26.104 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:128] - Set
hostname verifier for ldaps
13:42:26.141 - DEBUG
[edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking
checkServerTrusted for sun.security.ssl.X509TrustManagerImpl at 21bbc5f2
13:42:26.280 - DEBUG
[edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking
checkServerTrusted for
edu.vt.middleware.ldap.ssl.HostnameVerifyingTrustManager at 1d0dbe25
13:42:26.280 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:127] - Verify with
the following parameters:
13:42:26.280 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:128] - hostname =
xxxx.xxx.xxx
13:42:26.280 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:129] - cert =
CN=xxxx.xxx.xxx, O=xxxx.xxx.xxx, L=xxxx.xxx.xxx, ST=xxxx.xxx.xxx,
C=xxxx.xxx.xxx
13:42:26.282 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:202] - verifyDNS
using subjectAltNames = [xxxx.xxx.xxx]
13:42:26.282 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:210] - verifyDNS
found hostname match: xxxx.xxx.xxx
13:42:26.339 - WARN
[edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.dataConnector.ComputedIDDataConnector:64]
- This data connector is deprecated. The StoredID data connector should
be used in its place.
13:42:26.498 - DEBUG
[edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver:511]
- Loading 2 data connectors
13:42:26.498 - DEBUG
[edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver:521]
- Loading 10 attribute definitions
13:42:26.499 - DEBUG
[edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver:531]
- Loading 3 principal connectors
13:42:26.499 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:74] - Bind with
the following parameters:
13:42:26.500 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:75] - authtype
= simple
13:42:26.500 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:76] - dn =
uid=ldap,ou=people,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx,dc=xxxx.xxx.xxx
13:42:26.500 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:83] -
credential = <suppressed>
13:42:26.503 - DEBUG
[edu.vt.middleware.ldap.handler.DefaultConnectionHandler:128] - Set
hostname verifier for ldaps
13:42:26.505 - DEBUG
[edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking
checkServerTrusted for sun.security.ssl.X509TrustManagerImpl at 3342ac03
13:42:26.507 - DEBUG
[edu.vt.middleware.ldap.ssl.AggregateTrustManager:75] - invoking
checkServerTrusted for
edu.vt.middleware.ldap.ssl.HostnameVerifyingTrustManager at 7ffc50a0
13:42:26.507 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:127] - Verify with
the following parameters:
13:42:26.507 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:128] - hostname =
xxxx.xxx.xxx
13:42:26.507 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:129] - cert =
CN=xxxx.xxx.xxx, O=xxxx.xxx.xxx, L=xxxx.xxx.xxx, ST=xxxx.xxx.xxx,
C=xxxx.xxx.xxx
13:42:26.507 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:202] - verifyDNS
using subjectAltNames = [xxxx.xxx.xxx]
13:42:26.508 - DEBUG
[edu.vt.middleware.ldap.ssl.DefaultHostnameVerifier:210] - verifyDNS
found hostname match: xxxx.xxx.xxx
13:42:26.551 - INFO
[edu.internet2.middleware.shibboleth.common.config.BaseService:180] -
shibboleth.AttributeResolver service loaded new configuration
13:42:26.560 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.BaseReloadableService:136]
- Initializing shibboleth.AttributeFilterEngine service with resources:
[/opt/shibboleth-idp/conf/attribute-filter.xml]
13:42:26.560 - INFO
[edu.internet2.middleware.shibboleth.common.config.BaseService:158] -
Loading new configuration for service shibboleth.AttributeFilterEngine
13:42:26.576 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyGroupBeanDefinitionParser:64]
- Parsing attribute filter policy group ShibbolethFilterPolicy
13:42:26.577 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72]
- Parsing configuration for attribute filter policy
releaseTransientIdToAnyone
13:42:26.585 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72]
- Parsing configuration for attribute filter policy releaseToAnyoneinUKfed
13:42:26.591 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72]
- Parsing configuration for attribute filter policy releaseToXXX
13:42:26.592 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72]
- Parsing configuration for attribute filter policy releaseToCampusSites
13:42:26.594 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72]
- Parsing configuration for attribute filter policy
releasePersonalAttributes
13:42:26.597 - INFO
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:72]
- Parsing configuration for attribute filter policy null
13:42:26.598 - WARN
[edu.internet2.middleware.shibboleth.common.config.attribute.filtering.AttributeFilterPolicyBeanDefinitionParser:62]
- AttributeFilterPolicy elements should include an 'id' attribute. This
is not currently required but will be in future versions.
13:42:26.641 - INFO
[edu.internet2.middleware.shibboleth.common.config.BaseService:180] -
shibboleth.AttributeFilterEngine service loaded new configuration
13:42:26.646 - INFO
[edu.internet2.middleware.shibboleth.common.config.BaseService:158] -
Loading new configuration for service shibboleth.SAML1AttributeAuthority
13:42:26.649 - INFO
[edu.internet2.middleware.shibboleth.common.config.BaseService:158] -
Loading new configuration for service shibboleth.SAML2AttributeAuthority
13:42:26.655 - DEBUG
[edu.internet2.middleware.shibboleth.common.config.BaseReloadableService:136]
- Initializing shibboleth.RelyingPartyConfigurationManager service with
resources: [/opt/shibboleth-idp/conf/relying-party.xml]
13:42:26.655 - INFO
[edu.internet2.middleware.shibboleth.common.config.BaseService:158] -
Loading new configuration for service
shibboleth.RelyingPartyConfigurationManager
13:42:26.686 - 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.xml.sax.SAXParseException: Attribute "id" was already specified for
element "metadata:MetadataProvider".
[xxx at xxx logs]#
More information about the users
mailing list