IdP user authendication using mysql user table

Raja Scientist B (CS) raja at inflibnet.ac.in
Tue Nov 3 06:26:09 EST 2015 

Please find the log from idp-process.log


2015-11-03 16:51:32,559 - INFO
[net.shibboleth.idp.log.LogbackLoggingService:240] - Shibboleth IdP Version
3.1.2
2015-11-03 16:51:32,570 - INFO
[net.shibboleth.idp.log.LogbackLoggingService:241] - Java
version='1.8.0_60' vendor='Oracle Corporation'
2015-11-03 16:51:32,573 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:199]
- Service 'shibboleth.LoggingService': Reload time set to: 300000, starting
refresh thread
2015-11-03 16:51:32,654 - INFO
[org.opensaml.core.config.InitializationService:48] - Initializing OpenSAML
using the Java Services API
2015-11-03 16:51:34,201 - INFO
[org.opensaml.xmlsec.algorithm.AlgorithmRegistry:206] - Algorithm failed
runtime support check, will not be usable:
http://www.w3.org/2001/04/xmlenc#ripemd160
2015-11-03 16:51:34,218 - 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
2015-11-03 16:51:34,253 - 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
2015-11-03 16:51:34,993 - INFO
[net.shibboleth.utilities.java.support.security.BasicKeystoreKeyStrategy:323]
- Loading initial default key: secret1
2015-11-03 16:51:35,165 - INFO
[net.shibboleth.utilities.java.support.security.BasicKeystoreKeyStrategy:335]
- Default key updated to secret1
2015-11-03 16:51:35,373 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:172]
- Service 'shibboleth.AttributeFilterService': Performing initial load
2015-11-03 16:51:35,373 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:258]
- Service 'shibboleth.AttributeFilterService': Reloading service
configuration
2015-11-03 16:51:35,380 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/conf/attribute-filter.xml]
2015-11-03 16:53:12,731 - INFO
[net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:510]
- Refreshing ApplicationContext:shibboleth.AttributeFilterService: startup
date [Tue Nov 03 16:53:12 IST 2015]; parent: Root WebApplicationContext
2015-11-03 16:53:12,793 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service
'shibboleth.AttributeFilterService': Completed reload and swapped in latest
configuration for service 'shibboleth.AttributeFilterService'
2015-11-03 16:53:12,794 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service
'shibboleth.AttributeFilterService': Reload complete
2015-11-03 16:53:12,794 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:199]
- Service 'shibboleth.AttributeFilterService': Reload time set to: 900000,
starting refresh thread
2015-11-03 16:53:12,799 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:172]
- Service 'shibboleth.AttributeResolverService': Performing initial load
2015-11-03 16:53:12,800 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:258]
- Service 'shibboleth.AttributeResolverService': Reloading service
configuration
2015-11-03 16:53:12,803 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/conf/attribute-resolver.xml]
2015-11-03 16:53:12,892 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for AttributeDefinition plugin with id : uid
2015-11-03 16:53:12,893 - INFO
[net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:52]
- Parsing configuration for Dependency with pluginId : mySIS
2015-11-03 16:53:12,906 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for AttributeDefinition plugin with id :
eduPersonPrincipalName
2015-11-03 16:53:12,907 - INFO
[net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:52]
- Parsing configuration for Dependency with pluginId : mySIS
2015-11-03 16:53:12,909 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for AttributeDefinition plugin with id : mail
2015-11-03 16:53:12,910 - INFO
[net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:52]
- Parsing configuration for Dependency with pluginId : mySIS
2015-11-03 16:53:12,911 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for AttributeDefinition plugin with id :
eduPersonAffiliation
2015-11-03 16:53:12,911 - INFO
[net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:52]
- Parsing configuration for Dependency with pluginId : mySIS
2015-11-03 16:53:12,912 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for AttributeDefinition plugin with id :
eduPersonScopedAffiliation
2015-11-03 16:53:12,912 - INFO
[net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:52]
- Parsing configuration for Dependency with pluginId : mySIS
2015-11-03 16:53:12,913 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for AttributeDefinition plugin with id :
eduPersonOrgUnitDN
2015-11-03 16:53:12,913 - INFO
[net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:52]
- Parsing configuration for Dependency with pluginId : mySIS
2015-11-03 16:53:12,914 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for AttributeDefinition plugin with id :
eduPersonOrgDN
2015-11-03 16:53:12,914 - INFO
[net.shibboleth.idp.attribute.resolver.spring.ResolverPluginDependencyParser:52]
- Parsing configuration for Dependency with pluginId : mySIS
2015-11-03 16:53:12,932 - INFO
[net.shibboleth.idp.attribute.resolver.spring.BaseResolverPluginParser:58]
- Parsing configuration for DataConnector plugin with id : mySIS
2015-11-03 16:53:12,952 - INFO
[net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:510]
- Refreshing ApplicationContext:shibboleth.AttributeResolverService:
startup date [Tue Nov 03 16:53:12 IST 2015]; parent: Root
WebApplicationContext
2015-11-03 16:53:13,959 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service
'shibboleth.AttributeResolverService': Completed reload and swapped in
latest configuration for service 'shibboleth.AttributeResolverService'
2015-11-03 16:53:13,959 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service
'shibboleth.AttributeResolverService': Reload complete
2015-11-03 16:53:13,961 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:199]
- Service 'shibboleth.AttributeResolverService': Reload time set to:
900000, starting refresh thread
2015-11-03 16:53:13,974 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:172]
- Service 'shibboleth.NameIdentifierGenerationService': Performing initial
load
2015-11-03 16:53:13,975 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:258]
- Service 'shibboleth.NameIdentifierGenerationService': Reloading service
configuration
2015-11-03 16:53:13,978 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/conf/saml-nameid.xml]
2015-11-03 16:53:14,015 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/system/conf/saml-nameid-system.xml]
2015-11-03 16:53:14,039 - INFO
[net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:510]
- Refreshing ApplicationContext:shibboleth.NameIdentifierGenerationService:
startup date [Tue Nov 03 16:53:14 IST 2015]; parent: Root
WebApplicationContext
2015-11-03 16:53:14,183 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service
'shibboleth.NameIdentifierGenerationService': Completed reload and swapped
in latest configuration for service
'shibboleth.NameIdentifierGenerationService'
2015-11-03 16:53:14,184 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service
'shibboleth.NameIdentifierGenerationService': Reload complete
2015-11-03 16:53:14,185 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:199]
- Service 'shibboleth.NameIdentifierGenerationService': Reload time set to:
900000, starting refresh thread
2015-11-03 16:53:14,191 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:172]
- Service 'shibboleth.RelyingPartyResolverService': Performing initial load
2015-11-03 16:53:14,191 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:258]
- Service 'shibboleth.RelyingPartyResolverService': Reloading service
configuration
2015-11-03 16:53:14,193 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/conf/relying-party.xml]
2015-11-03 16:53:14,232 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/conf/credentials.xml]
2015-11-03 16:53:14,270 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/system/conf/relying-party-system.xml]
2015-11-03 16:53:14,310 - INFO
[net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:510]
- Refreshing ApplicationContext:shibboleth.RelyingPartyResolverService:
startup date [Tue Nov 03 16:53:14 IST 2015]; parent: Root
WebApplicationContext
2015-11-03 16:53:14,651 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:172]
- Service 'shibboleth.MetadataResolverService': Performing initial load
2015-11-03 16:53:14,651 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:258]
- Service 'shibboleth.MetadataResolverService': Reloading service
configuration
2015-11-03 16:53:14,654 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/conf/metadata-providers.xml]
2015-11-03 16:53:14,732 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/system/conf/metadata-providers-system.xml]
2015-11-03 16:53:14,745 - INFO
[net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:510]
- Refreshing ApplicationContext:shibboleth.MetadataResolverService: startup
date [Tue Nov 03 16:53:14 IST 2015]; parent: Root WebApplicationContext
2015-11-03 16:53:16,477 - INFO
[org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:465]
- New metadata successfully loaded for '
http://www.testshib.org/metadata/testshib-providers.xml'
2015-11-03 16:53:16,498 - INFO
[org.opensaml.saml.metadata.resolver.impl.AbstractReloadingMetadataResolver:306]
- Next refresh cycle for metadata provider '
http://www.testshib.org/metadata/testshib-providers.xml' will occur on
'2015-11-03T14:23:15.304Z' ('2015-11-03T19:53:15.304+05:30' local time)
2015-11-03 16:53:16,503 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service
'shibboleth.MetadataResolverService': Completed reload and swapped in
latest configuration for service 'shibboleth.MetadataResolverService'
2015-11-03 16:53:16,503 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service
'shibboleth.MetadataResolverService': Reload complete
2015-11-03 16:53:16,561 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service
'shibboleth.RelyingPartyResolverService': Completed reload and swapped in
latest configuration for service 'shibboleth.RelyingPartyResolverService'
2015-11-03 16:53:16,561 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service
'shibboleth.RelyingPartyResolverService': Reload complete
2015-11-03 16:53:16,562 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:199]
- Service 'shibboleth.RelyingPartyResolverService': Reload time set to:
900000, starting refresh thread
2015-11-03 16:53:16,563 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:172]
- Service 'shibboleth.ReloadableAccessControlService': Performing initial
load
2015-11-03 16:53:16,564 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:258]
- Service 'shibboleth.ReloadableAccessControlService': Reloading service
configuration
2015-11-03 16:53:16,565 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/conf/access-control.xml]
2015-11-03 16:53:16,580 - INFO
[net.shibboleth.ext.spring.util.SchemaTypeAwareXMLBeanDefinitionReader:317]
- Loading XML bean definitions from file
[/opt/shibboleth-idp/system/conf/access-control-system.xml]
2015-11-03 16:53:16,586 - INFO
[net.shibboleth.ext.spring.context.FilesystemGenericApplicationContext:510]
- Refreshing ApplicationContext:shibboleth.ReloadableAccessControlService:
startup date [Tue Nov 03 16:53:16 IST 2015]; parent: Root
WebApplicationContext
2015-11-03 16:53:16,601 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:378] - Service
'shibboleth.ReloadableAccessControlService': Completed reload and swapped
in latest configuration for service
'shibboleth.ReloadableAccessControlService'
2015-11-03 16:53:16,601 - INFO
[net.shibboleth.ext.spring.service.ReloadableSpringService:385] - Service
'shibboleth.ReloadableAccessControlService': Reload complete
2015-11-03 16:53:16,601 - INFO
[net.shibboleth.utilities.java.support.service.AbstractReloadableService:199]
- Service 'shibboleth.ReloadableAccessControlService': Reload time set to:
300000, starting refresh thread
2015-11-03 16:53:16,737 - INFO
[net.shibboleth.ext.spring.context.DeferPlaceholderFileSystemXmlWebApplicationContext:510]
- Refreshing WebApplicationContext for namespace 'idp-servlet': startup
date [Tue Nov 03 16:53:16 IST 2015]; parent: Root WebApplicationContext
2015-11-03 16:53:17,215 - INFO
[net.shibboleth.idp.authn.impl.RemoteUserAuthServlet:135] -
RemoteUserAuthServlet will process REMOTE_USER, along with attributes []
and headers []
2015-11-03 16:53:45,274 - WARN
[net.shibboleth.idp.authn.impl.ValidateUsernamePasswordAgainstJAAS:202] -
Profile Action ValidateUsernamePasswordAgainstJAAS: Login by
principal at jsslawcollege.com produced exception
java.security.NoSuchAlgorithmException: Error constructing implementation
(algorithm: JavaLoginConfig, provider: SUN, class:
sun.security.provider.ConfigFile$Spi)
at java.security.Provider$Service.newInstance(Provider.java:1617)
Caused by: java.io.IOException: Configuration Error:
Line 1: expected [{], found [null]
at sun.security.provider.ConfigFile$Spi.ioException(ConfigFile.java:666)



Thanks,
Raja V


On Tue, Nov 3, 2015 at 4:35 PM, Rod Widdowson <rdw at steadingsoftware.com>
wrote:

> > Login Failure: Error constructing implementation (algorithm:
> JavaLoginConfig, provider: SUN, class: sun.security.provider.ConfigFile$Spi)
>
> And your logs say what?
>
> Off the top of my head it could be a JAAS misconfig...
>
> --
> To unsubscribe from this list send an email to
> users-unsubscribe at shibboleth.net
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://shibboleth.net/pipermail/users/attachments/20151103/2a5955ad/attachment-0001.html>

More information about the users mailing list