-----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