Bug 1085741 - [QA] (6.3.0) all AdvancedLdapLoginModuleTestCase fails on OracleJDK1.7 and OpenJDK due to SocketTimeoutException
Summary: [QA] (6.3.0) all AdvancedLdapLoginModuleTestCase fails on OracleJDK1.7 and Op...
Keywords:
Status: CLOSED DUPLICATE of bug 1085709
Alias: None
Product: JBoss Enterprise Application Platform 6
Classification: JBoss
Component: Testsuite
Version: 6.3.0
Hardware: Unspecified
OS: Unspecified
unspecified
urgent
Target Milestone: ---
: ---
Assignee: Dominik Pospisil
QA Contact: Pavel Jelinek
URL:
Whiteboard:
Depends On:
Blocks: 1085709
TreeView+ depends on / blocked
 
Reported: 2014-04-09 08:50 UTC by Pavel Jelinek
Modified: 2014-10-25 12:17 UTC (History)
2 users (show)

Fixed In Version:
Clone Of:
Environment:
Last Closed: 2014-04-10 09:21:19 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description Pavel Jelinek 2014-04-09 08:50:12 UTC
It started to happen after ApacheDS upgrade to 2.0.0-M15. 
We run testsuite with -Dnode0 specified.

See e.g.:
https://jenkins.mw.lab.eng.bos.redhat.com/hudson/view/EAP6/view/EAP6-AS-Testsuite/job/eap-60-as-testsuite-RHEL-matrix-openJDK7/jdk=openjdk-1.7.0-local,label_exp=RHEL5%26%26x86/137/testReport/org.jboss.as.test.integration.security.loginmodules.negotiation/AdvancedLdapLoginModuleTestCase/

Stacktrace

javax.security.auth.login.LoginException: Receive timed out
	at com.sun.security.auth.module.Krb5LoginModule.attemptAuthentication(Krb5LoginModule.java:767)
	at com.sun.security.auth.module.Krb5LoginModule.login(Krb5LoginModule.java:584)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at javax.security.auth.login.LoginContext.invoke(LoginContext.java:775)
	at javax.security.auth.login.LoginContext.access$000(LoginContext.java:205)
	at javax.security.auth.login.LoginContext$5.run(LoginContext.java:697)
	at javax.security.auth.login.LoginContext$5.run(LoginContext.java:695)
	at java.security.AccessController.doPrivileged(Native Method)
	at javax.security.auth.login.LoginContext.invokePriv(LoginContext.java:694)
	at javax.security.auth.login.LoginContext.login(LoginContext.java:602)
	at org.jboss.as.test.integration.security.common.Utils.makeCallWithKerberosAuthn(Utils.java:511)
	at org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase.testDeployment(AdvancedLdapLoginModuleTestCase.java:269)
	at org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase.test1(AdvancedLdapLoginModuleTestCase.java:198)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
	at org.jboss.arquillian.junit.Arquillian$6$1.invoke(Arquillian.java:270)
	at org.jboss.arquillian.container.test.impl.execution.LocalTestExecuter.execute(LocalTestExecuter.java:60)
	at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
	at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
	at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
	at org.jboss.arquillian.container.test.impl.execution.ClientTestExecuter.execute(ClientTestExecuter.java:53)
	at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
	at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
	at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:142)
	at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createTestContext(ContainerEventController.java:129)
	at sun.reflect.GeneratedMethodAccessor17.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
	at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60)
	at sun.reflect.GeneratedMethodAccessor1.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
	at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89)
	at sun.reflect.GeneratedMethodAccessor3.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
	at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75)
	at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
	at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.test(EventTestRunnerAdaptor.java:111)
	at org.jboss.arquillian.junit.Arquillian$6.evaluate(Arquillian.java:263)
	at org.jboss.arquillian.junit.Arquillian$4.evaluate(Arquillian.java:226)
	at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
	at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
	at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
	at org.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185)
	at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
	at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
	at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
	at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147)
	at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:234)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:133)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:114)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:188)
	at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:166)
	at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:86)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:101)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:74)
Caused by: java.net.SocketTimeoutException: Receive timed out
	at java.net.PlainDatagramSocketImpl.receive0(Native Method)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:145)
	at java.net.DatagramSocket.receive(DatagramSocket.java:786)
	at sun.security.krb5.internal.UDPClient.receive(NetClient.java:207)
	at sun.security.krb5.KdcComm$KdcCommunication.run(KdcComm.java:390)
	at sun.security.krb5.KdcComm$KdcCommunication.run(KdcComm.java:343)
	at java.security.AccessController.doPrivileged(Native Method)
	at sun.security.krb5.KdcComm.send(KdcComm.java:327)
	at sun.security.krb5.KdcComm.send(KdcComm.java:219)
	at sun.security.krb5.KdcComm.send(KdcComm.java:191)
	at sun.security.krb5.KrbAsReqBuilder.send(KrbAsReqBuilder.java:319)
	at sun.security.krb5.KrbAsReqBuilder.action(KrbAsReqBuilder.java:364)
	at com.sun.security.auth.module.Krb5LoginModule.attemptAuthentication(Krb5LoginModule.java:725)
	... 98 more
Standard Output

13:20:35,280 INFO  [org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase] (main) Starting deployment ldap-test-DEP1
13:20:35,298 INFO  [org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase] (main) Starting deployment ldap-test-DEP2
13:20:35,315 INFO  [org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase] (main) Starting deployment ldap-test-DEP3
13:20:35,335 INFO  [org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase] (main) Starting deployment ldap-test-DEP4
13:20:35,653 INFO  [org.jboss.as.test.integration.security.common.AbstractSystemPropertiesServerSetupTask] (main) Adding system properties.
13:20:35,858 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "add",
    "address" => [("system-property" => "sun.security.krb5.debug")],
    "value" => "true"
}
13:20:35,861 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "response-headers" => {"process-state" => "reload-required"}
}
13:20:35,983 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "add",
    "address" => [("system-property" => "jboss.security.disable.secdomain.option")],
    "value" => "true"
}
13:20:35,984 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "response-headers" => {"process-state" => "reload-required"}
}
13:20:36,164 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "add",
    "address" => [("system-property" => "java.security.krb5.conf")],
    "value" => "/mnt/hudson_workspace/workspace/eap-60-as-testsuite-RHEL-matrix-openJDK7/b70703c6/jboss-eap-6.3-src/testsuite/integration/basic/target/workdir/SPNEGO-workdir/krb5.conf"
}
13:20:36,168 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "response-headers" => {"process-state" => "reload-required"}
}
13:20:39,426 WARN  [org.apache.directory.server.core.DefaultDirectoryService] (main) ApacheDS shutdown hook has NOT been registered with the runtime.  This default setting for standalone operation has been overriden.
13:20:47,582 WARN  [org.apache.directory.server.core.DefaultDirectoryService] (main) You didn't change the admin password of directory service instance 'JBossDS'.  Please update the admin password as soon as possible to prevent a possible security breach.
13:20:49,669 INFO  [org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase] (main) dn: ou=Users,dc=jboss,dc=org
objectClass: organizationalUnit
objectClass: top
ou: Users

dn: uid=krbtgt,ou=Users,dc=jboss,dc=org
objectClass: top
objectClass: person
objectClass: inetOrgPerson
objectClass: krb5principal
objectClass: krb5kdcentry
cn: KDC Service
sn: Service
uid: krbtgt
userPassword: secret
krb5PrincipalName: krbtgt/JBOSS.ORG
krb5KeyVersionNumber: 0

dn: uid=ldap,ou=Users,dc=jboss,dc=org
objectClass: top
objectClass: person
objectClass: inetOrgPerson
objectClass: krb5principal
objectClass: krb5kdcentry
cn: LDAP
sn: Service
uid: ldap
userPassword: randall
krb5PrincipalName: ldap/10.16.95.23
krb5KeyVersionNumber: 0

dn: uid=HTTP,ou=Users,dc=jboss,dc=org
objectClass: top
objectClass: person
objectClass: inetOrgPerson
objectClass: krb5principal
objectClass: krb5kdcentry
cn: HTTP
sn: Service
uid: HTTP
userPassword: httppwd
krb5PrincipalName: HTTP/10.16.95.23
krb5KeyVersionNumber: 0

dn: uid=hnelson,ou=Users,dc=jboss,dc=org
objectClass: top
objectClass: person
objectClass: inetOrgPerson
objectClass: krb5principal
objectClass: krb5kdcentry
cn: Horatio Nelson
sn: Nelson
uid: hnelson
userPassword: secret
krb5PrincipalName: hnelson
krb5KeyVersionNumber: 0

dn: uid=jduke,ou=Users,dc=jboss,dc=org
objectClass: top
objectClass: person
objectClass: inetOrgPerson
objectClass: krb5principal
objectClass: krb5kdcentry
cn: Java Duke
mail: jduke
sn: duke
uid: jduke
userPassword: theduke
krb5PrincipalName: jduke
krb5KeyVersionNumber: 0

dn: uid=jduke2,ou=Users,dc=jboss,dc=org
objectClass: top
objectClass: person
objectClass: inetOrgPerson
objectClass: krb5principal
objectClass: krb5kdcentry
cn: Java Duke2
employeeNumber: judke2-123
sn: Duke2
uid: jduke2
userPassword:: dGhlZHVrZTI=
krb5PrincipalName: jduke2
krb5KeyVersionNumber: 0

# Example1

dn: ou=Roles,dc=jboss,dc=org
objectclass: top
objectclass: organizationalUnit
ou: Roles

dn: cn=Echo,ou=Roles,dc=jboss,dc=org
objectClass: top
objectClass: groupOfNames
cn: Echo
description: the JBossAdmin group
member: uid=jduke,ou=Users,dc=jboss,dc=org

dn: cn=TheDuke,ou=Roles,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: TheDuke
description: TheDuke role context
member: uid=jduke,ou=Users,dc=jboss,dc=org

# Example2

dn: o=example2,dc=jboss,dc=org
objectClass: top
objectClass: dcObject
objectClass: organization
dc: jboss
o: JBoss

dn: ou=Roles,o=example2,dc=jboss,dc=org
objectClass: top
objectClass: organizationalUnit
ou: Roles

dn: cn=jduke,ou=Roles,o=example2,dc=jboss,dc=org
objectClass: top
objectClass: organizationalRole
description: cn=Echo,ou=Roles,o=example2,dc=jboss,dc=org
description: cn=TheDuke,ou=Roles,o=example2,dc=jboss,dc=org
postalAddress: jduke
cn: jduke

dn: cn=jduke2,ou=Roles,o=example2,dc=jboss,dc=org
objectClass: top
objectClass: organizationalRole
description: cn=Echo2,ou=Roles,o=example2,dc=jboss,dc=org
description: cn=TheDuke2,ou=Roles,o=example2,dc=jboss,dc=org
postalAddress: jduke2
cn: jduke2

dn: cn=Echo,ou=Roles,o=example2,dc=jboss,dc=org
objectClass: top
objectClass: groupOfNames
cn: Echo
description: the echo role
member: uid=jduke,ou=Users,dc=jboss,dc=org

dn: cn=TheDuke,ou=Roles,o=example2,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: TheDuke
description: the duke role
member: uid=jduke,ou=Users,dc=jboss,dc=org

dn: cn=Echo2,ou=Roles,o=example2,dc=jboss,dc=org
objectClass: top
objectClass: groupOfNames
cn: Echo2
description: the Echo2 role
member: uid=jduke2,ou=Users,dc=jboss,dc=org

dn: cn=TheDuke2,ou=Roles,o=example2,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: TheDuke2
description: the duke2 role
member: uid=jduke2,ou=Users,dc=jboss,dc=org

dn: cn=JBossAdmin,ou=Roles,o=example2,dc=jboss,dc=org
objectClass: top
objectClass: groupOfNames
cn: JBossAdmin
description: the JBossAdmin group
member: uid=jduke,ou=Users,dc=jboss,dc=org

# Example3

dn: o=example3,dc=jboss,dc=org
objectclass: top
objectclass: dcObject
objectclass: organization
dc: jboss
o: JBoss

dn: ou=Roles,o=example3,dc=jboss,dc=org
objectClass: top
objectClass: organizationalUnit
ou: Roles

dn: cn=jduke,ou=Roles,o=example3,dc=jboss,dc=org
objectClass: top
objectClass: organizationalRole
description: cn=Echo,ou=Roles,o=example3,dc=jboss,dc=org
description: cn=TheDuke,ou=Roles,o=example3,dc=jboss,dc=org
cn: jduke

dn: cn=Echo,ou=Roles,o=example3,dc=jboss,dc=org
objectClass: top
objectClass: groupOfNames
cn: Echo
description: the JBossAdmin group
member: uid=jduke,ou=Users,dc=jboss,dc=org

dn: cn=TheDuke,ou=Roles,o=example3,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: TheDuke
member: uid=jduke,ou=Users,dc=jboss,dc=org

# Example4

dn: o=example4,dc=jboss,dc=org
objectclass: top
objectclass: dcObject
objectclass: organization
dc: jboss
o: JBoss

dn: ou=Roles,o=example4,dc=jboss,dc=org
objectClass: top
objectClass: organizationalUnit
ou: Roles

dn: cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: RG1
member: cn=empty

dn: cn=RG2,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: RG2
member: cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org
member: uid=jduke,ou=Users,dc=jboss,dc=org

dn: cn=RG3,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: RG3
member: cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org

dn: cn=R1,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: R1
member: cn=RG2,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org

dn: cn=R2,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: R2
member: cn=RG2,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org

dn: cn=R3,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: R3
member: cn=RG2,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org
member: cn=RG3,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org

dn: cn=R4,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: R4
member: cn=RG3,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org

dn: cn=R5,ou=Roles,o=example4,dc=jboss,dc=org
objectClass: groupOfNames
objectClass: top
cn: R5
member: cn=RG3,cn=RG1,ou=Roles,o=example4,dc=jboss,dc=org
member: uid=jduke,ou=Users,dc=jboss,dc=org

13:21:00,103 WARN  [org.apache.directory.server.core.normalization.NormalizationInterceptor] (main) The Rdn 'o=example2' is not present in the entry
13:21:09,942 WARN  [org.apache.directory.server.core.normalization.NormalizationInterceptor] (main) The Rdn 'o=example3' is not present in the entry
13:21:13,899 WARN  [org.apache.directory.server.core.normalization.NormalizationInterceptor] (main) The Rdn 'o=example4' is not present in the entry
[31m13:21:19,991 ERROR [org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl] (Periodic Recovery) IJ000906: Error during crash recovery: java:/H2XADS (Could not create connection): javax.resource.ResourceException: Could not create connection
	at org.jboss.jca.adapters.jdbc.xa.XAManagedConnectionFactory.getXAManagedConnection(XAManagedConnectionFactory.java:514)
	at org.jboss.jca.adapters.jdbc.xa.XAManagedConnectionFactory$1.run(XAManagedConnectionFactory.java:410)
	at org.jboss.jca.adapters.jdbc.xa.XAManagedConnectionFactory$1.run(XAManagedConnectionFactory.java:407)
	at java.security.AccessController.doPrivileged(Native Method) [rt.jar:1.7.0_51]
	at javax.security.auth.Subject.doAs(Subject.java:415) [rt.jar:1.7.0_51]
	at org.jboss.jca.adapters.jdbc.xa.XAManagedConnectionFactory.createManagedConnection(XAManagedConnectionFactory.java:406)
	at org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl.open(XAResourceRecoveryImpl.java:343)
	at org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl.getXAResources(XAResourceRecoveryImpl.java:170)
	at com.arjuna.ats.internal.jbossatx.jta.XAResourceRecoveryHelperWrapper.getXAResources(XAResourceRecoveryHelperWrapper.java:51) [jbossjts-integration-4.17.18.Final-redhat-1.jar:4.17.18.Final-redhat-1]
	at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.resourceInitiatedRecoveryForRecoveryHelpers(XARecoveryModule.java:516) [jbossjts-jacorb-4.17.18.Final-redhat-1.jar:4.17.18.Final-redhat-1]
	at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.periodicWorkFirstPass(XARecoveryModule.java:182) [jbossjts-jacorb-4.17.18.Final-redhat-1.jar:4.17.18.Final-redhat-1]
	at com.arjuna.ats.internal.arjuna.recovery.PeriodicRecovery.doWorkInternal(PeriodicRecovery.java:743) [jbossjts-jacorb-4.17.18.Final-redhat-1.jar:4.17.18.Final-redhat-1]
	at com.arjuna.ats.internal.arjuna.recovery.PeriodicRecovery.run(PeriodicRecovery.java:371) [jbossjts-jacorb-4.17.18.Final-redhat-1.jar:4.17.18.Final-redhat-1]
Caused by: java.lang.IllegalStateException
	at org.jboss.msc.value.InjectedValue.getValue(InjectedValue.java:47)
	at org.jboss.as.connector.subsystems.datasources.AbstractDataSourceService.driverClassLoader(AbstractDataSourceService.java:237)
	at org.jboss.as.connector.subsystems.datasources.AbstractDataSourceService.access$200(AbstractDataSourceService.java:89)
	at org.jboss.as.connector.subsystems.datasources.AbstractDataSourceService$AS7DataSourceDeployer$1.getClassLoader(AbstractDataSourceService.java:402)
	at org.jboss.jca.adapters.jdbc.xa.XAManagedConnectionFactory.getXADataSource(XAManagedConnectionFactory.java:634)
	at org.jboss.jca.adapters.jdbc.xa.XAManagedConnectionFactory.getXAManagedConnection(XAManagedConnectionFactory.java:497)
	... 12 more

[0m13:21:23,483 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding security domain host
13:21:23,487 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=Kerberos, flag=required, options = {storeKey=true, useKeyTab=true, principal=HTTP/10.16.95.23, keyTab=/mnt/hudson_workspace/workspace/eap-60-as-testsuite-RHEL-matrix-openJDK7/b70703c6/jboss-eap-6.3-src/testsuite/integration/basic/target/workdir/SPNEGO-workdir/http.keytab, debug=true, refreshKrb5Config=true, doNotPrompt=true}]
13:21:23,500 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for authorization module.
13:21:23,505 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for mapping module.
13:21:23,509 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for JSSE module.
13:21:23,513 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding security domain ldap-test-DEP1
13:21:23,518 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=SPNEGO, flag=required, options = {serverSecurityDomain=host, password-stacking=useFirstPass}]
13:21:23,525 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=org.jboss.security.negotiation.AdvancedLdapLoginModule, flag=required, options = {bindAuthentication=GSSAPI, baseFilter=(krb5PrincipalName={0}), jaasSecurityDomain=host, roleFilter=(member={1}), java.naming.provider.url=ldap://10.16.95.23:10389, rolesCtxDN=ou=Roles,dc=jboss,dc=org, baseCtxDN=ou=Users,dc=jboss,dc=org, roleAttributeID=cn, password-stacking=useFirstPass}]
13:21:23,540 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for authorization module.
13:21:23,546 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for mapping module.
13:21:23,551 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for JSSE module.
13:21:23,556 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding security domain ldap-test-DEP2
13:21:23,560 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=SPNEGO, flag=required, options = {serverSecurityDomain=host, password-stacking=useFirstPass}]
13:21:23,567 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=AdvancedLdap, flag=required, options = {bindAuthentication=GSSAPI, baseFilter=(krb5PrincipalName={0}), jaasSecurityDomain=host, roleFilter=(postalAddress={0}), java.naming.provider.url=ldap://10.16.95.23:10389, rolesCtxDN=ou=Roles,o=example2,dc=jboss,dc=org, roleNameAttributeID=cn, baseCtxDN=ou=Users,dc=jboss,dc=org, roleAttributeIsDN=true, roleAttributeID=description, password-stacking=useFirstPass}]
13:21:23,584 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for authorization module.
13:21:23,589 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for mapping module.
13:21:23,594 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for JSSE module.
13:21:23,600 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding security domain ldap-test-DEP3
13:21:23,605 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=SPNEGO, flag=required, options = {serverSecurityDomain=host, password-stacking=useFirstPass}]
13:21:23,615 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=org.jboss.security.negotiation.AdvancedLdapLoginModule, flag=required, options = {bindAuthentication=GSSAPI, baseFilter=(mail={0}), jaasSecurityDomain=host, roleFilter=(member={1}), java.naming.provider.url=ldap://10.16.95.23:10389, rolesCtxDN=ou=Roles,o=example3,dc=jboss,dc=org, baseCtxDN=ou=Users,dc=jboss,dc=org, roleAttributeID=cn, password-stacking=useFirstPass}]
13:21:23,631 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for authorization module.
13:21:23,635 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for mapping module.
13:21:23,640 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for JSSE module.
13:21:23,645 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding security domain ldap-test-DEP4
13:21:23,650 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=SPNEGO, flag=required, options = {serverSecurityDomain=host, password-stacking=useFirstPass}]
13:21:23,657 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) Adding authentication module [code=org.jboss.security.negotiation.AdvancedLdapLoginModule, flag=required, options = {bindAuthentication=GSSAPI, baseFilter=(mail={0}), jaasSecurityDomain=host, roleFilter=(member={1}), java.naming.provider.url=ldap://10.16.95.23:10389, rolesCtxDN=ou=Roles,o=example4,dc=jboss,dc=org, recurseRoles=true, baseCtxDN=ou=Users,dc=jboss,dc=org, roleAttributeID=cn, password-stacking=useFirstPass}]
13:21:23,673 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for authorization module.
13:21:23,678 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for mapping module.
13:21:23,681 INFO  [org.jboss.as.test.integration.security.common.AbstractSecurityDomainsServerSetupTask] (main) No security configuration for JSSE module.
[0m13:21:23,953 INFO  [org.jboss.as.controller] (management-handler-thread - 1) JBAS014774: Service status report
JBAS014776:    Newly corrected services:
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."com.sun.faces.config.ConfigureListener".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."javax.servlet.jsp.jstl.tlv.PermittedTaglibsTLV".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."javax.servlet.jsp.jstl.tlv.ScriptFreeTLV".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."org.apache.catalina.servlets.DefaultServlet".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."org.apache.jasper.servlet.JspServlet".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."org.jboss.as.test.integration.security.common.servlets.SimpleSecuredServlet".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."org.jboss.as.test.integration.security.common.servlets.SimpleServlet".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".component."org.jboss.as.test.integration.security.loginmodules.negotiation.PropagateIdentityServlet".START (no longer required)
      service jboss.deployment.unit."kerberos-test-form-fallback.war".jndiDependencyService (no longer required)
      service jboss.security.security-domain.SPNEGO-with-fallback (no longer required)
      service jboss.web.deployment.default-host./kerberos-test-form-fallback (no longer required)
      service jboss.web.deployment.default-host./kerberos-test-form-fallback.realm (no longer required)

[0m13:21:23,956 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "composite",
    "address" => [],
    "steps" => [
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "host")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "host"),
                ("authentication" => "classic")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "host"),
                ("authentication" => "classic"),
                ("login-module" => "Kerberos")
            ],
            "code" => "Kerberos",
            "flag" => "required",
            "module-options" => [
                ("storeKey" => "true"),
                ("useKeyTab" => "true"),
                ("principal" => "HTTP/10.16.95.23"),
                ("keyTab" => "/mnt/hudson_workspace/workspace/eap-60-as-testsuite-RHEL-matrix-openJDK7/b70703c6/jboss-eap-6.3-src/testsuite/integration/basic/target/workdir/SPNEGO-workdir/http.keytab"),
                ("debug" => "true"),
                ("refreshKrb5Config" => "true"),
                ("doNotPrompt" => "true")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        }
    ]
}
13:21:24,004 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "result" => {
        "step-1" => {"outcome" => "success"},
        "step-2" => {"outcome" => "success"},
        "step-3" => {"outcome" => "success"}
    },
    "response-headers" => {"process-state" => "reload-required"}
}
13:21:24,250 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "composite",
    "address" => [],
    "steps" => [
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP1")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP1"),
                ("authentication" => "classic")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP1"),
                ("authentication" => "classic"),
                ("login-module" => "SPNEGO")
            ],
            "code" => "SPNEGO",
            "flag" => "required",
            "module-options" => [
                ("serverSecurityDomain" => "host"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP1"),
                ("authentication" => "classic"),
                ("login-module" => "org.jboss.security.negotiation.AdvancedLdapLoginModule")
            ],
            "code" => "org.jboss.security.negotiation.AdvancedLdapLoginModule",
            "flag" => "required",
            "module-options" => [
                ("bindAuthentication" => "GSSAPI"),
                ("baseFilter" => "(krb5PrincipalName={0})"),
                ("jaasSecurityDomain" => "host"),
                ("roleFilter" => "(member={1})"),
                ("java.naming.provider.url" => "ldap://10.16.95.23:10389"),
                ("rolesCtxDN" => "ou=Roles,dc=jboss,dc=org"),
                ("baseCtxDN" => "ou=Users,dc=jboss,dc=org"),
                ("roleAttributeID" => "cn"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        }
    ]
}
13:21:24,316 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "result" => {
        "step-1" => {"outcome" => "success"},
        "step-2" => {"outcome" => "success"},
        "step-3" => {"outcome" => "success"},
        "step-4" => {"outcome" => "success"}
    },
    "response-headers" => {"process-state" => "reload-required"}
}
13:21:24,487 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "composite",
    "address" => [],
    "steps" => [
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP2")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP2"),
                ("authentication" => "classic")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP2"),
                ("authentication" => "classic"),
                ("login-module" => "SPNEGO")
            ],
            "code" => "SPNEGO",
            "flag" => "required",
            "module-options" => [
                ("serverSecurityDomain" => "host"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP2"),
                ("authentication" => "classic"),
                ("login-module" => "AdvancedLdap")
            ],
            "code" => "AdvancedLdap",
            "flag" => "required",
            "module-options" => [
                ("bindAuthentication" => "GSSAPI"),
                ("baseFilter" => "(krb5PrincipalName={0})"),
                ("jaasSecurityDomain" => "host"),
                ("roleFilter" => "(postalAddress={0})"),
                ("java.naming.provider.url" => "ldap://10.16.95.23:10389"),
                ("rolesCtxDN" => "ou=Roles,o=example2,dc=jboss,dc=org"),
                ("roleNameAttributeID" => "cn"),
                ("baseCtxDN" => "ou=Users,dc=jboss,dc=org"),
                ("roleAttributeIsDN" => "true"),
                ("roleAttributeID" => "description"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        }
    ]
}
13:21:24,549 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "result" => {
        "step-1" => {"outcome" => "success"},
        "step-2" => {"outcome" => "success"},
        "step-3" => {"outcome" => "success"},
        "step-4" => {"outcome" => "success"}
    },
    "response-headers" => {"process-state" => "reload-required"}
}
13:21:24,753 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "composite",
    "address" => [],
    "steps" => [
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP3")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP3"),
                ("authentication" => "classic")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP3"),
                ("authentication" => "classic"),
                ("login-module" => "SPNEGO")
            ],
            "code" => "SPNEGO",
            "flag" => "required",
            "module-options" => [
                ("serverSecurityDomain" => "host"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP3"),
                ("authentication" => "classic"),
                ("login-module" => "org.jboss.security.negotiation.AdvancedLdapLoginModule")
            ],
            "code" => "org.jboss.security.negotiation.AdvancedLdapLoginModule",
            "flag" => "required",
            "module-options" => [
                ("bindAuthentication" => "GSSAPI"),
                ("baseFilter" => "(mail={0})"),
                ("jaasSecurityDomain" => "host"),
                ("roleFilter" => "(member={1})"),
                ("java.naming.provider.url" => "ldap://10.16.95.23:10389"),
                ("rolesCtxDN" => "ou=Roles,o=example3,dc=jboss,dc=org"),
                ("baseCtxDN" => "ou=Users,dc=jboss,dc=org"),
                ("roleAttributeID" => "cn"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        }
    ]
}
13:21:24,813 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "result" => {
        "step-1" => {"outcome" => "success"},
        "step-2" => {"outcome" => "success"},
        "step-3" => {"outcome" => "success"},
        "step-4" => {"outcome" => "success"}
    },
    "response-headers" => {"process-state" => "reload-required"}
}
13:21:25,032 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update: {
    "operation" => "composite",
    "address" => [],
    "steps" => [
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP4")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP4"),
                ("authentication" => "classic")
            ]
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP4"),
                ("authentication" => "classic"),
                ("login-module" => "SPNEGO")
            ],
            "code" => "SPNEGO",
            "flag" => "required",
            "module-options" => [
                ("serverSecurityDomain" => "host"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        },
        {
            "operation" => "add",
            "address" => [
                ("subsystem" => "security"),
                ("security-domain" => "ldap-test-DEP4"),
                ("authentication" => "classic"),
                ("login-module" => "org.jboss.security.negotiation.AdvancedLdapLoginModule")
            ],
            "code" => "org.jboss.security.negotiation.AdvancedLdapLoginModule",
            "flag" => "required",
            "module-options" => [
                ("bindAuthentication" => "GSSAPI"),
                ("baseFilter" => "(mail={0})"),
                ("jaasSecurityDomain" => "host"),
                ("roleFilter" => "(member={1})"),
                ("java.naming.provider.url" => "ldap://10.16.95.23:10389"),
                ("rolesCtxDN" => "ou=Roles,o=example4,dc=jboss,dc=org"),
                ("recurseRoles" => "true"),
                ("baseCtxDN" => "ou=Users,dc=jboss,dc=org"),
                ("roleAttributeID" => "cn"),
                ("password-stacking" => "useFirstPass")
            ],
            "operation-headers" => {"allow-resource-service-restart" => true}
        }
    ]
}
13:21:25,087 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Client update result: {
    "outcome" => "success",
    "result" => {
        "step-1" => {"outcome" => "success"},
        "step-2" => {"outcome" => "success"},
        "step-3" => {"outcome" => "success"},
        "step-4" => {"outcome" => "success"}
    },
    "response-headers" => {"process-state" => "reload-required"}
}
[0m13:21:25,198 INFO  [org.jboss.as.repository] (management-handler-thread - 4) JBAS014900: Content added at location /mnt/hudson_workspace/workspace/eap-60-as-testsuite-RHEL-matrix-openJDK7/b70703c6/jboss-eap-6.3-src/testsuite/integration/basic/target/jbossas/standalone/data/content/b3/419f36283901edd76ebb1ef41820554dbf380a/content
[0m[0m13:21:25,201 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) JBAS015876: Starting deployment of "ldap-test-DEP1.war" (runtime-name: "ldap-test-DEP1.war")
[0m[0m13:21:25,266 INFO  [org.jboss.as.arquillian] (MSC service thread 1-4) Arquillian deployment detected: ArquillianConfig[service=jboss.arquillian.config."ldap-test-DEP1.war",unit=ldap-test-DEP1.war,tests=[org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase]]
[0m[0m13:21:25,270 INFO  [org.jboss.web] (ServerService Thread Pool -- 149) JBAS018210: Register web context: /ldap-test-DEP1
[0m[0m13:21:25,512 INFO  [org.jboss.as.server] (management-handler-thread - 4) JBAS018559: Deployed "ldap-test-DEP1.war" (runtime-name : "ldap-test-DEP1.war")
[0m[0m13:21:25,663 INFO  [org.jboss.as.repository] (management-handler-thread - 3) JBAS014900: Content added at location /mnt/hudson_workspace/workspace/eap-60-as-testsuite-RHEL-matrix-openJDK7/b70703c6/jboss-eap-6.3-src/testsuite/integration/basic/target/jbossas/standalone/data/content/59/54b039c6de0ae9af0b5576896d3e9aee608b87/content
[0m[0m13:21:25,668 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) JBAS015876: Starting deployment of "ldap-test-DEP2.war" (runtime-name: "ldap-test-DEP2.war")
[0m[0m13:21:25,732 INFO  [org.jboss.as.arquillian] (MSC service thread 1-4) Arquillian deployment detected: ArquillianConfig[service=jboss.arquillian.config."ldap-test-DEP2.war",unit=ldap-test-DEP2.war,tests=[org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase]]
[0m[0m13:21:25,735 INFO  [org.jboss.web] (ServerService Thread Pool -- 149) JBAS018210: Register web context: /ldap-test-DEP2
[0m[0m13:21:25,901 INFO  [org.jboss.as.server] (management-handler-thread - 3) JBAS018559: Deployed "ldap-test-DEP2.war" (runtime-name : "ldap-test-DEP2.war")
[0m[0m13:21:26,113 INFO  [org.jboss.as.repository] (management-handler-thread - 4) JBAS014900: Content added at location /mnt/hudson_workspace/workspace/eap-60-as-testsuite-RHEL-matrix-openJDK7/b70703c6/jboss-eap-6.3-src/testsuite/integration/basic/target/jbossas/standalone/data/content/94/51683931a37e511758c93825c9c14eaa4ba4f2/content
[0m[0m13:21:26,116 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015876: Starting deployment of "ldap-test-DEP3.war" (runtime-name: "ldap-test-DEP3.war")
[0m[0m13:21:26,181 INFO  [org.jboss.as.arquillian] (MSC service thread 1-3) Arquillian deployment detected: ArquillianConfig[service=jboss.arquillian.config."ldap-test-DEP3.war",unit=ldap-test-DEP3.war,tests=[org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase]]
[0m[0m13:21:26,187 INFO  [org.jboss.web] (ServerService Thread Pool -- 151) JBAS018210: Register web context: /ldap-test-DEP3
[0m[0m13:21:26,429 INFO  [org.jboss.as.server] (management-handler-thread - 4) JBAS018559: Deployed "ldap-test-DEP3.war" (runtime-name : "ldap-test-DEP3.war")
[0m[0m13:21:26,543 INFO  [org.jboss.as.repository] (management-handler-thread - 3) JBAS014900: Content added at location /mnt/hudson_workspace/workspace/eap-60-as-testsuite-RHEL-matrix-openJDK7/b70703c6/jboss-eap-6.3-src/testsuite/integration/basic/target/jbossas/standalone/data/content/11/b2842b9a01ae9b459e06c238e810f4ca9f13a7/content
[0m[0m13:21:26,547 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015876: Starting deployment of "ldap-test-DEP4.war" (runtime-name: "ldap-test-DEP4.war")
[0m[0m13:21:26,613 INFO  [org.jboss.as.arquillian] (MSC service thread 1-6) Arquillian deployment detected: ArquillianConfig[service=jboss.arquillian.config."ldap-test-DEP4.war",unit=ldap-test-DEP4.war,tests=[org.jboss.as.test.integration.security.loginmodules.negotiation.AdvancedLdapLoginModuleTestCase]]
[0m[0m13:21:26,618 INFO  [org.jboss.web] (ServerService Thread Pool -- 152) JBAS018210: Register web context: /ldap-test-DEP4
[0m[0m13:21:26,838 INFO  [org.jboss.as.server] (management-handler-thread - 3) JBAS018559: Deployed "ldap-test-DEP4.war" (runtime-name : "ldap-test-DEP4.war")
[0m13:21:26,860 INFO  [org.jboss.as.test.integration.security.common.Utils] (main) Requesting URI: http://10.16.95.23:8080/ldap-test-DEP1/printRoles?role=TheDuke&role=Echo&role=TheDuke2&role=Echo2&role=JBossAdmin&role=jduke&role=jduke2&role=RG1&role=RG2&role=RG3&role=R1&role=R2&role=R3&role=R4&role=R5&role=Roles
13:21:27,334 WARN  [org.apache.http.client.protocol.RequestTargetAuthentication] (main) NEGOTIATE authentication error: No valid credentials provided (Mechanism level: No valid credentials provided (Mechanism level: Failed to find any Kerberos tgt))
Debug is  true storeKey false useTicketCache false useKeyTab false doNotPrompt false ticketCache is null isInitiator true KeyTab is null refreshKrb5Config is true principal is null tryFirstPass is false useFirstPass is false storePass is false clearPass is false
Refreshing Kerberos configuration
		[Krb5LoginModule] user entered username: jduke

&#27;[33m13:21:30,017 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:34f8, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:34f9, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@16b00c4 >
&#27;[0m&#27;[33m13:21:30,018 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:34f8, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:34f9, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,174 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:358d, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:358e, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@1ff7e08 >
&#27;[0m&#27;[33m13:21:30,175 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:358d, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:358e, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,226 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:35d5, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:35d6, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@ab53bb >
&#27;[0m&#27;[33m13:21:30,227 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:35d5, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:35d6, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,274 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:351d, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:351f, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@656da >
&#27;[0m&#27;[33m13:21:30,275 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:351d, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:351f, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,322 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:3505, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:3506, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@303897 >
&#27;[0m&#27;[33m13:21:30,324 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:3505, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:3506, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,354 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:35ad, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:35ae, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@1d36581 >
&#27;[0m&#27;[33m13:21:30,356 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:35ad, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:35ae, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,447 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:36fa, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:36fb, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@1acfc2a >
&#27;[0m&#27;[33m13:21:30,448 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:36fa, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:36fb, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,493 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:3576, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:3577, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@1df4e29 >
&#27;[0m&#27;[33m13:21:30,495 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:3576, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:3577, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,563 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:34eb, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:34ec, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@1847d46 >
&#27;[0m&#27;[33m13:21:30,564 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:34eb, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:34ec, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,745 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:34e2, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:34e3, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@13c8ed4 >
&#27;[0m&#27;[33m13:21:30,746 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:34e2, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:34e3, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m&#27;[33m13:21:30,816 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord < resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:36c0, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:36c1, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@961d52 >
&#27;[0m&#27;[33m13:21:30,817 WARN  [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a1059e0:1fe44eab:53442acb:36c0, node_name=1, branch_uid=0:ffff0a1059e0:1fe44eab:53442acb:36c1, subordinatenodename=null, eis_name=unknown eis name >
&#27;[0m		[Krb5LoginModule] authentication failed 
Receive timed out

Comment 1 Dominik Pospisil 2014-04-10 09:21:19 UTC

*** This bug has been marked as a duplicate of bug 1085709 ***


Note You need to log in before you can comment on or make changes to this bug.