Naming test timeout was Re: [jboss-dev] PUSH

[Adrian Brock]

On Thu, 2008-06-26 at 14:30 +0200, Sacha Labourey wrote:
> In case you usually don't look at the result of the AS testsuite, here 
> is the list of the 39 remaining failing tests:
> 
> 
>     * org.jboss.test.naming.test.SimpleUnitTestCase.unknown

This test passes for me locally, so there's obviously something
happening different on the test machine.

When I look at the log around the time of this test
http://hudson.jboss.org/hudson/job/JBoss-AS-5.0.x-TestSuite-sun15/lastBuild/artifact/trunk/build/output/jboss-5.0.0.CR1/server/all/log/server.log

I see some warnings from the TM and some errors from jgroups:

1) end of previous test

2008-06-26 04:05:00,541 DEBUG
[org.jboss.deployers.plugins.deployers.DeployersImpl] (RMI TCP
Connection(487)-10.18.94.175) Fully Undeployed
vfszip:/qa/services/hudson/
hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/testsuite/output/lib/naming-pooled.sar

2) TM Warnings (these are repeated)

2008-06-26 04:05:18,631 WARN  [com.arjuna.ats.jta.logging.loggerI18N]
(Thread-19)
[com.arjuna.ats.internal.jta.resources.arjunacore.norecoveryxa]
[com.arjuna.ats.inter
nal.jta.resources.arjunacore.norecoveryxa] Could not find new XAResource
to use for recovering non-serializable XAResource < 131075, 28, 26,
49459749504848565458989998
48585256545152521029758511015748974950484856545898999848585256545152521029758511015753 >
2008-06-26 04:05:18,631 WARN
[com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-19)
[com.arjuna.ats.internal.arjuna.gandiva.inventory.StaticInventory_1] -
cannot 
find null implementation.
2008-06-26 04:05:18,631 WARN
[com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-19)
[com.arjuna.ats.arjuna.recovery.RecoverAtomicAction_4] -
RecoverAtomicAction: t
ransaction a120086:bcb0:486344fa:3e90 not activated, unable to replay
phase 2 commit
2008-06-26 04:05:18,632 DEBUG
[com.arjuna.ats.arjuna.logging.arjLoggerI18N]
(Server.Connection:10.18.0.134:34373)
[com.arjuna.ats.arjuna.recovery.ActionStatusService_1
] -
transactionType: /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction uid: a120086:bcb0:486344fa:3e99 
 Status is 7
2008-06-26 04:05:18,637 WARN  [com.arjuna.ats.jta.logging.loggerI18N]
(Thread-19)
[com.arjuna.ats.internal.jta.resources.arjunacore.norecoveryxa]
[com.arjuna.ats.inter
nal.jta.resources.arjunacore.norecoveryxa] Could not find new XAResource
to use for recovering non-serializable XAResource < 131075, 28, 26,
49459749504848565458989998
485852565451525210297585110157579749504848565458989998485852565451525210297585110157101 >
2008-06-26 04:05:18,637 WARN
[com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-19)
[com.arjuna.ats.internal.arjuna.gandiva.inventory.StaticInventory_1] -
cannot 
find null implementation.
2008-06-26 04:05:18,637 WARN
[com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-19)
[com.arjuna.ats.arjuna.recovery.RecoverAtomicAction_4] -
RecoverAtomicAction: t
ransaction a120086:bcb0:486344fa:3e99 not activated, unable to replay
phase 2 commit
2008-06-26 04:05:18,637 DEBUG
[com.arjuna.ats.arjuna.logging.arjLoggerI18N]
(Server.Connection:10.18.0.134:34373)
[com.arjuna.ats.arjuna.recovery.ActionStatusService_1
] -
transactionType: /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction uid: a120086:bcb0:486344fa:3ea2 
 Status is 7
2008-06-26 04:05:18,639 WARN  [com.arjuna.ats.jta.logging.loggerI18N]
(Thread-19)
[com.arjuna.ats.internal.jta.resources.arjunacore.norecoveryxa]
[com.arjuna.ats.inter
nal.jta.resources.arjunacore.norecoveryxa] Could not find new XAResource
to use for recovering non-serializable XAResource < 131075, 28, 26,
49459749504848565458989998
48585256545152521029758511019750974950484856545898999848585256545152521029758511019755 >
2008-06-26 04:05:18,639 WARN
[com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-19)
[com.arjuna.ats.internal.arjuna.gandiva.inventory.StaticInventory_1] -
cannot 
find null implementation.
2008-06-26 04:05:18,639 WARN
[com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-19)
[com.arjuna.ats.arjuna.recovery.RecoverAtomicAction_4] -
RecoverAtomicAction: t
ransaction a120086:bcb0:486344fa:3ea2 not activated, unable to replay
phase 2 commit

3) JGroups error (there are repeated)

2008-06-26 04:06:02,959 ERROR [org.jgroups.protocols.MPING]
(ReceiverThread) failed receiving packet (from /10.18.0.232:45567)
java.io.IOException: length has to be 4 or 16 bytes (was 10 bytes)
        at org.jgroups.stack.IpAddress.readFrom(IpAddress.java:306)
        at org.jgroups.Message.readFrom(Message.java:737)
        at org.jgroups.protocols.MPING.run(MPING.java:355)
        at java.lang.Thread.run(Thread.java:595)

4) Start of next test (5 minutes after step 1)

2008-06-26 04:10:06,294 WARN  [org.jboss.deployment.MainDeployer] (RMI
TCP Connection(491)-10.18.94.175) undeploy
'file:/qa/services/hudson/hudson_workspace/workspace/
JBoss-AS-5.0.x-TestSuite-sun15/trunk/testsuite/output/lib/probe.jar' :
package not deployed
2008-06-26 04:10:06,294 INFO  [org.jboss.deployment.MainDeployer] (RMI
TCP Connection(491)-10.18.94.175) deploy,
url=file:/qa/services/hudson/hudson_workspace/workspac
e/JBoss-AS-5.0.x-TestSuite-sun15/trunk/testsuite/output/lib/probe.jar

-- 
xxxxxxxxxxxxxxxxxxxxxxxxxxxx
Adrian Brock
Chief Scientist
JBoss, a division of Red Hat
xxxxxxxxxxxxxxxxxxxxxxxxxxxx