By: Luke Lu user 02 Feb 2018 at 2:36 a.m. CST

14 Responses
Luke Lu gravatar
# problem introduce in trust relationship the metadata xml file cannot be validated. Metadata Validate log ``` s4s-elt-character: Non-whitespace characters are not allowed in schema elements other than 'xs:appinfo' and 'xs:documentation'. Saw 'ar=decodeURIComponent('http://docs.oasis-open.org%2fsecurity%2fsaml%2fv2.0%2fsaml-schema-assertion-2.0.xsd');tout=setTimeout('location.replace(ar)',2000);'. ``` ``` Error of schema creating Warning: cannot validate metadata. Check internet connetion ans www.w3.org availability. Warning: Error of schema creating Warning: s4s-elt-character: Non-whitespace characters are not allowed in schema elements other than 'xs:appinfo' and 'xs:documentation'. Saw 'ar=decodeURIComponent('http://docs.oasis-open.org%2fsecurity%2fsaml%2fv2.0%2fsaml-schema-assertion-2.0.xsd');tout=setTimeout('location.replace(ar)',2000);'. ``` # logs ``` 2018-02-01 01:08:23,101 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.MetadataResolverService': Reloading service configuration 2018-02-01 01:08:23,454 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/metadata-providers.xml] 2018-02-01 01:08:23,696 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/metadata-providers-system.xml] 2018-02-01 01:08:23,722 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 01:08:23 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:08:23,735 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.MetadataResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.MetadataResolverService' 2018-02-01 01:08:23,735 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.MetadataResolverService: startup date [Tue Jan 30 08:48:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:08:23,765 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.MetadataResolverService': Reload complete 2018-02-01 01:09:23,058 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.MetadataResolverService': Reloading service configuration 2018-02-01 01:09:23,060 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/metadata-providers.xml] 2018-02-01 01:09:23,086 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/metadata-providers-system.xml] 2018-02-01 01:09:23,101 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 01:09:23 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:09:23,119 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.MetadataResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.MetadataResolverService' 2018-02-01 01:09:23,119 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 01:08:23 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:09:23,119 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.MetadataResolverService': Reload complete 2018-02-01 01:18:22,108 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeFilterService': Reloading service configuration 2018-02-01 01:18:22,110 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-filter.xml] 2018-02-01 01:18:22,163 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.AttributeFilterService: startup date [Thu Feb 01 01:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:22,244 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.AttributeFilterService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeFilterService' 2018-02-01 01:18:22,244 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.AttributeFilterService: startup date [Tue Jan 30 08:48:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:22,244 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.AttributeFilterService': Reload complete 2018-02-01 01:18:22,282 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeResolverService': Reloading service configuration 2018-02-01 01:18:22,283 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-resolver.xml] 2018-02-01 01:18:22,328 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for AttributeDefinition plugin with id: mail 2018-02-01 01:18:22,329 - INFO [net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:54] - Parsing configuration for Dependency with pluginId: siteLDAP 2018-02-01 01:18:22,329 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for AttributeDefinition plugin with id: givenName 2018-02-01 01:18:22,329 - INFO [net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:54] - Parsing configuration for Dependency with pluginId: siteLDAP 2018-02-01 01:18:22,330 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for DataConnector plugin with id: siteLDAP 2018-02-01 01:18:22,330 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.AttributeResolverService: startup date [Thu Feb 01 01:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:22,638 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.AttributeResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeResolverService' 2018-02-01 01:18:22,638 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.AttributeResolverService: startup date [Tue Jan 30 08:48:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:22,638 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.AttributeResolverService': Reload complete 2018-02-01 01:18:22,874 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.RelyingPartyResolverService': Reloading service configuration 2018-02-01 01:18:22,875 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/relying-party.xml] 2018-02-01 01:18:22,949 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/credentials.xml] 2018-02-01 01:18:22,959 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/relying-party-system.xml] 2018-02-01 01:18:22,966 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableCASServiceRegistry': Reloading service configuration 2018-02-01 01:18:22,967 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/cas-protocol.xml] 2018-02-01 01:18:22,975 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Thu Feb 01 01:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:23,046 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.ReloadableCASServiceRegistry': Completed reload and swapped in latest configuration for service 'shibboleth.ReloadableCASServiceRegistry' 2018-02-01 01:18:23,046 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Tue Jan 30 08:48:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:23,046 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload complete 2018-02-01 01:18:23,047 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Thu Feb 01 01:18:23 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:23,271 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.RelyingPartyResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.RelyingPartyResolverService' 2018-02-01 01:18:23,272 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Tue Jan 30 08:48:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 01:18:23,272 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.RelyingPartyResolverService': Reload complete 2018-02-01 03:26:29,326 - INFO [net.shibboleth.ext.spring.context.DelimiterAwareApplicationContext:982] - Closing WebApplicationContext for namespace 'idp-servlet': startup date [Tue Jan 30 07:18:23 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,429 - INFO [net.shibboleth.ext.spring.context.DelimiterAwareApplicationContext:982] - Closing Root WebApplicationContext: startup date [Tue Jan 30 07:18:19 UTC 2018]; root of context hierarchy 2018-02-01 03:26:29,462 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.AttributeFilterService: startup date [Thu Feb 01 01:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,462 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.AttributeFilterService': Starting shutdown 2018-02-01 03:26:29,462 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.AttributeFilterService': Completing shutdown 2018-02-01 03:26:29,463 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 01:09:23 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,468 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.MetadataResolverService': Starting shutdown 2018-02-01 03:26:29,468 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.MetadataResolverService': Completing shutdown 2018-02-01 03:26:29,468 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.AttributeResolverService: startup date [Thu Feb 01 01:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,470 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.AttributeResolverService': Starting shutdown 2018-02-01 03:26:29,470 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.AttributeResolverService': Completing shutdown 2018-02-01 03:26:29,470 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.NameIdentifierGenerationService: startup date [Tue Jan 30 07:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,471 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.NameIdentifierGenerationService': Starting shutdown 2018-02-01 03:26:29,471 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.NameIdentifierGenerationService': Completing shutdown 2018-02-01 03:26:29,472 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Thu Feb 01 01:18:23 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,475 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.RelyingPartyResolverService': Starting shutdown 2018-02-01 03:26:29,475 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.RelyingPartyResolverService': Completing shutdown 2018-02-01 03:26:29,476 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.ReloadableAccessControlService: startup date [Tue Jan 30 07:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,476 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.ReloadableAccessControlService': Starting shutdown 2018-02-01 03:26:29,476 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.ReloadableAccessControlService': Completing shutdown 2018-02-01 03:26:29,477 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Thu Feb 01 01:18:22 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:29,477 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.ReloadableCASServiceRegistry': Starting shutdown 2018-02-01 03:26:29,477 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.ReloadableCASServiceRegistry': Completing shutdown 2018-02-01 03:26:29,488 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:207] - Service 'shibboleth.LoggingService': Starting shutdown 2018-02-01 03:26:29,488 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:216] - Service 'shibboleth.LoggingService': Completing shutdown 2018-02-01 03:26:40,158 - INFO [net.shibboleth.idp.log.LogbackLoggingService:240] - Shibboleth IdP Version 3.3.1 2018-02-01 03:26:40,162 - INFO [net.shibboleth.idp.log.LogbackLoggingService:241] - Java version='1.8.0_112' vendor='Oracle Corporation' 2018-02-01 03:26:40,174 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.LoggingService': Reload time set to: 300000, starting refresh thread 2018-02-01 03:26:40,230 - INFO [org.opensaml.core.config.InitializationService:48] - Initializing OpenSAML using the Java Services API 2018-02-01 03:26:40,389 - INFO [org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed runtime support check, will not be usable: http://www.w3.org/2001/04/xmlenc#ripemd160 2018-02-01 03:26:40,395 - INFO [org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed runtime support check, will not be usable: http://www.w3.org/2001/04/xmldsig-more#hmac-ripemd160 2018-02-01 03:26:40,404 - INFO [org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed runtime support check, will not be usable: http://www.w3.org/2001/04/xmldsig-more#rsa-ripemd160 2018-02-01 03:26:41,100 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.AttributeFilterService': Performing initial load 2018-02-01 03:26:41,100 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeFilterService': Reloading service configuration 2018-02-01 03:26:41,105 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-filter.xml] 2018-02-01 03:26:41,139 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.AttributeFilterService: startup date [Thu Feb 01 03:26:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:41,196 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.AttributeFilterService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeFilterService' 2018-02-01 03:26:41,196 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.AttributeFilterService': Reload complete 2018-02-01 03:26:41,197 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.AttributeFilterService': Reload time set to: 900000, starting refresh thread 2018-02-01 03:26:41,206 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.AttributeResolverService': Performing initial load 2018-02-01 03:26:41,206 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeResolverService': Reloading service configuration 2018-02-01 03:26:41,216 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-resolver.xml] 2018-02-01 03:26:41,268 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for AttributeDefinition plugin with id: mail 2018-02-01 03:26:41,268 - INFO [net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:54] - Parsing configuration for Dependency with pluginId: siteLDAP 2018-02-01 03:26:41,269 - WARN [net.shibboleth.idp.attribute.resolver.spring.ad.BaseAttributeDefinitionParser:78] - Attribute Definition 'mail': Configuration contains at least one element in the deprecated 'urn:mace:shibboleth:2.0:resolver' namespace. 2018-02-01 03:26:41,275 - WARN [net.shibboleth.idp.attribute.resolver.spring.enc.BaseAttributeEncoderParser:80] - Configuration contains at least one element in the deprecated 'urn:mace:shibboleth:2.0:attribute:encoder' namespace. 2018-02-01 03:26:41,276 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for AttributeDefinition plugin with id: givenName 2018-02-01 03:26:41,276 - INFO [net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:54] - Parsing configuration for Dependency with pluginId: siteLDAP 2018-02-01 03:26:41,281 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for DataConnector plugin with id: siteLDAP 2018-02-01 03:26:41,282 - WARN [net.shibboleth.idp.attribute.resolver.spring.dc.AbstractDataConnectorParser:117] - Data Connector 'siteLDAP': Configuration contains at least one element in the deprecated 'urn:mace:shibboleth:2.0:resolver:dc' namespace. 2018-02-01 03:26:41,311 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.AttributeResolverService: startup date [Thu Feb 01 03:26:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:41,732 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.AttributeResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeResolverService' 2018-02-01 03:26:41,733 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.AttributeResolverService': Reload complete 2018-02-01 03:26:41,733 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.AttributeResolverService': Reload time set to: 900000, starting refresh thread 2018-02-01 03:26:41,749 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.NameIdentifierGenerationService': Performing initial load 2018-02-01 03:26:41,749 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.NameIdentifierGenerationService': Reloading service configuration 2018-02-01 03:26:41,754 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/saml-nameid.xml] 2018-02-01 03:26:41,779 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/saml-nameid-system.xml] 2018-02-01 03:26:41,793 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.NameIdentifierGenerationService: startup date [Thu Feb 01 03:26:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:41,842 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.NameIdentifierGenerationService': Completed reload and swapped in latest configuration for service 'shibboleth.NameIdentifierGenerationService' 2018-02-01 03:26:41,843 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.NameIdentifierGenerationService': Reload complete 2018-02-01 03:26:41,843 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.NameIdentifierGenerationService': Reload time set to: 900000, starting refresh thread 2018-02-01 03:26:41,852 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.RelyingPartyResolverService': Performing initial load 2018-02-01 03:26:41,852 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.RelyingPartyResolverService': Reloading service configuration 2018-02-01 03:26:41,853 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/relying-party.xml] 2018-02-01 03:26:41,863 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/credentials.xml] 2018-02-01 03:26:41,869 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/relying-party-system.xml] 2018-02-01 03:26:41,878 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Thu Feb 01 03:26:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:42,184 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.MetadataResolverService': Performing initial load 2018-02-01 03:26:42,184 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.MetadataResolverService': Reloading service configuration 2018-02-01 03:26:42,187 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/metadata-providers.xml] 2018-02-01 03:26:42,230 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/metadata-providers-system.xml] 2018-02-01 03:26:42,238 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 03:26:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:42,261 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.MetadataResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.MetadataResolverService' 2018-02-01 03:26:42,261 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.MetadataResolverService': Reload complete 2018-02-01 03:26:42,262 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.MetadataResolverService': Reload time set to: 60000, starting refresh thread 2018-02-01 03:26:42,387 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.RelyingPartyResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.RelyingPartyResolverService' 2018-02-01 03:26:42,387 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.RelyingPartyResolverService': Reload complete 2018-02-01 03:26:42,388 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.RelyingPartyResolverService': Reload time set to: 900000, starting refresh thread 2018-02-01 03:26:42,394 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.ReloadableAccessControlService': Performing initial load 2018-02-01 03:26:42,394 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableAccessControlService': Reloading service configuration 2018-02-01 03:26:42,398 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/access-control.xml] 2018-02-01 03:26:42,411 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/access-control-system.xml] 2018-02-01 03:26:42,442 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.ReloadableAccessControlService: startup date [Thu Feb 01 03:26:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:42,463 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.ReloadableAccessControlService': Completed reload and swapped in latest configuration for service 'shibboleth.ReloadableAccessControlService' 2018-02-01 03:26:42,463 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.ReloadableAccessControlService': Reload complete 2018-02-01 03:26:42,464 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.ReloadableAccessControlService': Reload time set to: 300000, starting refresh thread 2018-02-01 03:26:42,475 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:172] - Service 'shibboleth.ReloadableCASServiceRegistry': Performing initial load 2018-02-01 03:26:42,476 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableCASServiceRegistry': Reloading service configuration 2018-02-01 03:26:42,478 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/cas-protocol.xml] 2018-02-01 03:26:42,491 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Thu Feb 01 03:26:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:42,502 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.ReloadableCASServiceRegistry': Completed reload and swapped in latest configuration for service 'shibboleth.ReloadableCASServiceRegistry' 2018-02-01 03:26:42,502 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload complete 2018-02-01 03:26:42,502 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:199] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload time set to: 900000, starting refresh thread 2018-02-01 03:26:43,209 - INFO [net.shibboleth.ext.spring.context.DelimiterAwareApplicationContext:581] - Refreshing WebApplicationContext for namespace 'idp-servlet': startup date [Thu Feb 01 03:26:43 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:26:43,691 - INFO [net.shibboleth.idp.authn.impl.RemoteUserAuthServlet:193] - RemoteUserAuthServlet will process REMOTE_USER, along with attributes [] and headers [] 2018-02-01 03:37:42,265 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.MetadataResolverService': Reloading service configuration 2018-02-01 03:37:42,268 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/metadata-providers.xml] 2018-02-01 03:37:42,292 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/metadata-providers-system.xml] 2018-02-01 03:37:42,298 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 03:37:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:37:42,317 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.MetadataResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.MetadataResolverService' 2018-02-01 03:37:42,317 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 03:26:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:37:42,318 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.MetadataResolverService': Reload complete 2018-02-01 03:38:42,266 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.MetadataResolverService': Reloading service configuration 2018-02-01 03:38:42,267 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/metadata-providers.xml] 2018-02-01 03:38:42,283 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/metadata-providers-system.xml] 2018-02-01 03:38:42,292 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 03:38:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:38:42,311 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.MetadataResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.MetadataResolverService' 2018-02-01 03:38:42,312 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.MetadataResolverService: startup date [Thu Feb 01 03:37:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:38:42,313 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.MetadataResolverService': Reload complete 2018-02-01 03:41:41,197 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeFilterService': Reloading service configuration 2018-02-01 03:41:41,199 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-filter.xml] 2018-02-01 03:41:41,219 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.AttributeFilterService: startup date [Thu Feb 01 03:41:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:41,243 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.AttributeFilterService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeFilterService' 2018-02-01 03:41:41,244 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.AttributeFilterService: startup date [Thu Feb 01 03:26:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:41,244 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.AttributeFilterService': Reload complete 2018-02-01 03:41:41,734 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.AttributeResolverService': Reloading service configuration 2018-02-01 03:41:41,735 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/attribute-resolver.xml] 2018-02-01 03:41:41,751 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for AttributeDefinition plugin with id: mail 2018-02-01 03:41:41,751 - INFO [net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:54] - Parsing configuration for Dependency with pluginId: siteLDAP 2018-02-01 03:41:41,752 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for AttributeDefinition plugin with id: givenName 2018-02-01 03:41:41,752 - INFO [net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:54] - Parsing configuration for Dependency with pluginId: siteLDAP 2018-02-01 03:41:41,752 - INFO [net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:60] - Parsing configuration for DataConnector plugin with id: siteLDAP 2018-02-01 03:41:41,753 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.AttributeResolverService: startup date [Thu Feb 01 03:41:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:41,853 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.AttributeResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.AttributeResolverService' 2018-02-01 03:41:41,853 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.AttributeResolverService: startup date [Thu Feb 01 03:26:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:41,855 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.AttributeResolverService': Reload complete 2018-02-01 03:41:42,388 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.RelyingPartyResolverService': Reloading service configuration 2018-02-01 03:41:42,405 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/relying-party.xml] 2018-02-01 03:41:42,414 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/credentials.xml] 2018-02-01 03:41:42,419 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/system/conf/relying-party-system.xml] 2018-02-01 03:41:42,426 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Thu Feb 01 03:41:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:42,502 - INFO [net.shibboleth.utilities.java.support.service.AbstractReloadableService:258] - Service 'shibboleth.ReloadableCASServiceRegistry': Reloading service configuration 2018-02-01 03:41:42,503 - INFO [net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317] - Loading XML bean definitions from file [/opt/shibboleth-idp/conf/cas-protocol.xml] 2018-02-01 03:41:42,510 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:581] - Refreshing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Thu Feb 01 03:41:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:42,537 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.ReloadableCASServiceRegistry': Completed reload and swapped in latest configuration for service 'shibboleth.ReloadableCASServiceRegistry' 2018-02-01 03:41:42,538 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.ReloadableCASServiceRegistry: startup date [Thu Feb 01 03:26:42 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:42,538 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.ReloadableCASServiceRegistry': Reload complete 2018-02-01 03:41:42,619 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:380] - Service 'shibboleth.RelyingPartyResolverService': Completed reload and swapped in latest configuration for service 'shibboleth.RelyingPartyResolverService' 2018-02-01 03:41:42,620 - INFO [net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:982] - Closing ApplicationContext:shibboleth.RelyingPartyResolverService: startup date [Thu Feb 01 03:26:41 UTC 2018]; parent: Root WebApplicationContext 2018-02-01 03:41:42,622 - INFO [net.shibboleth.ext.spring.service.ReloadableSpringService:387] - Service 'shibboleth.RelyingPartyResolverService': Reload complete ```

By Thomas Gasmyr Mougang staff 04 Feb 2018 at 3:11 p.m. CST

Thomas Gasmyr Mougang gravatar
Hi Luke, Can you profile logs file as attachments. We also need the trust relationship metadata file

By Luke Lu user 04 Feb 2018 at 6:58 p.m. CST

Luke Lu gravatar
Hi Thomas, All the relevant files in my google drive, please see the url link. [Relevant Files](https://drive.google.com/drive/folders/19_l-BdS1VuDrRVi4Dt_Rc_oP_TFalkcp?usp=sharing)

By Thomas Gasmyr Mougang staff 05 Feb 2018 at 12:51 a.m. CST

Thomas Gasmyr Mougang gravatar
Hi **Luke**, As per my knowledge the metadata seems correct. I can see from this [image](https://pasteboard.co/H6c0uQG.png) that you are getting the metadata file from remote host. Please do this: * Download a copy of your metadata file and upload that to your **https://gluu.royole.com**. * Then configure your SP to point to that _local copy_ and give a shot. The pro of this approach are: * The validation take less time since the metadata is local * No worry about timeout that may occur(since you don't have to fetch the metadata from another). Let me know if that fix your issue. Thanks!

By Luke Lu user 05 Feb 2018 at 1:58 a.m. CST

Luke Lu gravatar
Hi Thomas, I have tried this approach. but the problem still there with same errors. the communication between gluu idp and sp site is fine. Thanks Thomas!

By Thomas Gasmyr Mougang staff 05 Feb 2018 at 3:16 a.m. CST

Thomas Gasmyr Mougang gravatar
Hi Luke, Here is another solution that i think may help. Note that this is a result of a google search. Please try the solution from this [link](https://stackoverflow.com/questions/24695103/schema-validation-s4s-elt-character-non-whitespace-characters-are-not-allowed). Thanks!

By Luke Lu user 05 Feb 2018 at 5:33 a.m. CST

Luke Lu gravatar
Hi Thomas, it is so weird! this solution i have tried and was not effective. Thanks!

By Luke Lu user 05 Feb 2018 at 8:53 p.m. CST

Luke Lu gravatar
Hi Thomas, I upgraded to version 3.1.2. but it still don't work.

By Luke Lu user 05 Feb 2018 at 8:56 p.m. CST

Luke Lu gravatar
Hi Thomas, How can i change the timeout threshold. the default value is 2000ms? Thanks!

By Thomas Gasmyr Mougang staff 05 Feb 2018 at 11:56 p.m. CST

Thomas Gasmyr Mougang gravatar
Hi Luke, Which timeout do you want to change?

By Thomas Gasmyr Mougang staff 07 Feb 2018 at 1:43 a.m. CST

Thomas Gasmyr Mougang gravatar
Hey Luke, How is it going on? Have you found a solution for you issue? if so please share with us.

By Thomas Gasmyr Mougang staff 08 Feb 2018 at 2:37 a.m. CST

Thomas Gasmyr Mougang gravatar
Hi Luke, This ticket will be closed tomorrow morning for inactivity.

By Luke Lu user 08 Feb 2018 at 11:38 p.m. CST

Luke Lu gravatar
Hi Thomas, It still not work. I don't what should i do now.

By Mohib Zico staff 14 Feb 2018 at 10:55 a.m. CST

Mohib Zico gravatar
Thomas, Reassigning this ticket to me; got some free time...

By Mohib Zico staff 20 Feb 2018 at 1:39 p.m. CST

Mohib Zico gravatar
Hi, This is definitely connectivity issue... >> entityID="https://172.16.100.76/websso/SAML2/Metadata/vsphere.local"> Make sure you can talk to 172.16.100.76 from your Gluu Server VM ( do telnet etc.) As these are just internal IP addresses; we have less thing to do from this side; however.. I know that one of our customer is using vsphere service SSO with their Gluu Server.