-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

On 18/01/2014 02:20, Jeremy Boynes wrote:
> I have started seeing a test failure on one of the tribes tests on
> trunk (see log below). OSX 10.9.1 with 1.7.0_51 or 1.7.0_45 
> r1559303
> 
> Is anyone else seeing similar, or could you provide me with clues
> on where to start digging?

I've seen the occasional failure like this but have never been able to
repeat it. Neither can I see anything in the code that would suggest
why one of the listeners doesn't see any of the other members.

As a starting point, I think I'd adjust the test so it reports what
all of the listeners have heard before failing if any of them have not
seen all the other members. That would give a better idea of what was
going wrong and might help steer the next step.

Mark

> 
> Thanks Jeremy
> 
> ---------
> 
> Testsuite:
> org.apache.catalina.tribes.group.TestGroupChannelMemberArrival 
> Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed:
> 8.41 sec ------------- Standard Output --------------- 
> 1390010749589 All channels started. ------------- ----------------
> --------------- ------------- Standard Error ----------------- Jan
> 17, 2014 6:05:42 PM org.apache.catalina.tribes.util.UUIDGenerator
> <clinit> INFO: Creation of SecureRandom instance for UUID
> generation using [SHA1PRNG] took [298] milliseconds. Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4000 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4001 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4002 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4003 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4004 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4005 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4006 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4007 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4008 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.transport.ReceiverBase bind 
> INFO: Receiver Server Socket bound to:/10.0.1.22:4009 Jan 17, 2014
> 6:05:42 PM org.apache.catalina.tribes.membership.McastServiceImpl
> setupSocket INFO: Setting cluster mcast soTimeout to 500 Jan 17,
> 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl setupSocket 
> INFO: Setting cluster mcast soTimeout to 500 Jan 17, 2014 6:05:42
> PM org.apache.catalina.tribes.membership.McastServiceImpl
> setupSocket INFO: Setting cluster mcast soTimeout to 500 Jan 17,
> 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl setupSocket 
> INFO: Setting cluster mcast soTimeout to 500 Jan 17, 2014 6:05:42
> PM org.apache.catalina.tribes.membership.McastServiceImpl
> setupSocket INFO: Setting cluster mcast soTimeout to 500 Jan 17,
> 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl setupSocket 
> INFO: Setting cluster mcast soTimeout to 500 Jan 17, 2014 6:05:42
> PM org.apache.catalina.tribes.membership.McastServiceImpl
> setupSocket INFO: Setting cluster mcast soTimeout to 500 Jan 17,
> 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl setupSocket 
> INFO: Setting cluster mcast soTimeout to 500 Jan 17, 2014 6:05:42
> PM org.apache.catalina.tribes.membership.McastServiceImpl
> setupSocket INFO: Setting cluster mcast soTimeout to 500 Jan 17,
> 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl setupSocket 
> INFO: Setting cluster mcast soTimeout to 500 Jan 17, 2014 6:05:42
> PM org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:42 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:4 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:43 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Sleeping for 1000 milliseconds to establish
> cluster membership, start level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 Jan 17, 2014 6:05:44 PM
> org.apache.catalina.tribes.membership.McastServiceImpl
> waitForMembers INFO: Done sleeping, membership established, start
> level:8 ------------- ---------------- ---------------
> 
> Testcase: testMemberArrival took 8.393 sec FAILED Checking member
> arrival length (Listener-10) expected:<9> but was:<0> 
> junit.framework.AssertionFailedError: Checking member arrival
> length (Listener-10) expected:<9> but was:<0> at
> org.apache.catalina.tribes.group.TestGroupChannelMemberArrival.testMemberArrival(TestGroupChannelMemberArrival.java:80)
>
> 
> 

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.9 (MingW32)
Comment: Using GnuPG with Thunderbird - http://www.enigmail.net/

iQIcBAEBAgAGBQJS2kgPAAoJEBDAHFovYFnnJkAP/3KAR4hIgZCIvYEkDQ7CsHFc
qA0Q7cU1ICBZaNGmrGtyn4wtz9ApyEc3W3+dniD/pCBwTtnozweOZa1Xa/QxQEZX
ltsw+iqtmREBWwXmu12EoSPMIhkZyg6HBHmSaH+6jU6HhCQylw0wSxM77HT7BZaq
LqJmG5otkoKRrJyZXWyip8EdLt/COW7pdMDMXcVsK0SFnNOqCs82Ti/oJDuntOUa
Xdcji/iU5kqMGDQzvX00BcYfuhgD2JbwtpzTOXAJv+Rop6dLeek5wGgIq9mBFzt8
m/MmMcstyccHkw9oN3vnR6qcdniyoZKzEadauDtTX2oQ5LuhnveV9tos55O/YAvn
3sJ68Qwj2qRm9h3v1TlKrof7c9khUzoLgEVUw9EsbGBQmJRgaA7G8qmQzKBL03ep
XSeX7ywYLEBrcmdcsowvov9vJMW1Phbrl7He+97J2B/7/GlgQlWPCcbzqUI1XdkK
dGFhLqjiUnjMdt/ImJeYrQW7eq9/Z9xKgVXryOBec1BghI62Xcd3/0TFKI84SgLP
4il+Gm6Dd6APJmiPr/tBCAp6lZ8mW89Mk6w/2N4SbwA6PfiujjEiCsxoYbkwHboX
+Sln1cIGFWCYXSeKNY0K3Tq7/WB3MUEuVfH2ySG8dAUjcsMBldSj1IG10k8io/yS
AW3APm+/FOi8U+rtVPps
=tuSs
-----END PGP SIGNATURE-----

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@tomcat.apache.org
For additional commands, e-mail: dev-h...@tomcat.apache.org

Reply via email to